builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0294 starttime: 1484016645.99 results: success (0) buildid: 20170109162508 builduid: 5975fea350084e1fb54a3e180add5941 revision: 08a4d4135acdf49e0db6670e1cca550767fbe2f4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 18:50:45.989488) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 18:50:45.989956) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 18:50:45.990248) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 18:50:46.022507) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 18:50:46.022903) ========= 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.L230gMCPLq/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.51S0o3kzjN/Listeners TMPDIR=/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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-09 18:50:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 151M=0s 2017-01-09 18:50:46 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091225 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 18:50:46.141188) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 18:50:46.141540) ========= 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.L230gMCPLq/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.51S0o3kzjN/Listeners TMPDIR=/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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.024356 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 18:50:46.189649) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-09 18:50:46.190703) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 08a4d4135acdf49e0db6670e1cca550767fbe2f4 --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 08a4d4135acdf49e0db6670e1cca550767fbe2f4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.L230gMCPLq/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.51S0o3kzjN/Listeners TMPDIR=/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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-09 18:50:46,287 truncating revision to first 12 chars 2017-01-09 18:50:46,287 Setting DEBUG logging. 2017-01-09 18:50:46,287 attempt 1/10 2017-01-09 18:50:46,287 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/08a4d4135acd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 18:50:48,011 unpacking tar archive at: mozilla-aurora-08a4d4135acd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.995606 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-09 18:50:48.213126) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 18:50:48.213500) ========= script_repo_revision: 08a4d4135acdf49e0db6670e1cca550767fbe2f4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 18:50:48.213929) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 18:50:48.214200) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 18:50:48.234183) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-09 18:50:48.234573) ========= /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.L230gMCPLq/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.51S0o3kzjN/Listeners TMPDIR=/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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 18:50:48 INFO - MultiFileLogger online at 20170109 18:50:48 in /builds/slave/test 18:50:48 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 18:50:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:50:48 INFO - {'append_to_log': False, 18:50:48 INFO - 'base_work_dir': '/builds/slave/test', 18:50:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:50:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:50:48 INFO - 'branch': 'Mozilla-Aurora', 18:50:48 INFO - 'buildbot_json_path': 'buildprops.json', 18:50:48 INFO - 'config_files': ('talos/mac_config.py',), 18:50:48 INFO - 'default_actions': ('clobber', 18:50:48 INFO - 'read-buildbot-config', 18:50:48 INFO - 'download-and-extract', 18:50:48 INFO - 'populate-webroot', 18:50:48 INFO - 'create-virtualenv', 18:50:48 INFO - 'install', 18:50:48 INFO - 'run-tests'), 18:50:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:50:48 INFO - 'download_minidump_stackwalk': True, 18:50:48 INFO - 'download_symbols': 'ondemand', 18:50:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:50:48 INFO - 'tooltool.py': '/tools/tooltool.py', 18:50:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:50:48 INFO - '/tools/misc-python/virtualenv.py')}, 18:50:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:50:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:50:48 INFO - 'installer_path': 'installer.exe', 18:50:48 INFO - 'log_level': 'info', 18:50:48 INFO - 'log_name': 'talos', 18:50:48 INFO - 'log_to_console': True, 18:50:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:50:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:50:48 INFO - 'opt_config_files': (), 18:50:48 INFO - 'pip_index': False, 18:50:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:50:48 INFO - 'cmd': ('bash', 18:50:48 INFO - '-c', 18:50:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:50:48 INFO - 'enabled': True, 18:50:48 INFO - 'halt_on_failure': False, 18:50:48 INFO - 'name': 'check_screen_resolution'},), 18:50:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:50:48 INFO - 'cmd': ('bash', 18:50:48 INFO - '-c', 18:50:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:50:48 INFO - 'enabled': True, 18:50:48 INFO - 'halt_on_failure': False, 18:50:48 INFO - 'name': 'check_screen_resolution'},), 18:50:48 INFO - 'run_cmd_checks_enabled': True, 18:50:48 INFO - 'sps_profile': False, 18:50:48 INFO - 'sps_profile_interval': 0, 18:50:48 INFO - 'suite': 'dromaeojs-e10s', 18:50:48 INFO - 'system_bits': '32', 18:50:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:50:48 INFO - 'title': 't-yosemite-r7-0294', 18:50:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:50:48 INFO - 'use_talos_json': True, 18:50:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:50:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:50:48 INFO - 'work_dir': 'build'} 18:50:48 INFO - [mozharness: 2017-01-10 02:50:48.436714Z] Running clobber step. 18:50:48 INFO - Running pre-action listener: _resource_record_pre_action 18:50:48 INFO - Running main action method: clobber 18:50:48 INFO - rmtree: /builds/slave/test/build 18:50:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:50:50 INFO - Running post-action listener: _resource_record_post_action 18:50:50 INFO - [mozharness: 2017-01-10 02:50:50.801099Z] Finished clobber step (success) 18:50:50 INFO - [mozharness: 2017-01-10 02:50:50.801287Z] Running read-buildbot-config step. 18:50:50 INFO - Running pre-action listener: _resource_record_pre_action 18:50:50 INFO - Running main action method: read_buildbot_config 18:50:50 INFO - Using buildbot properties: 18:50:50 INFO - { 18:50:50 INFO - "project": "", 18:50:50 INFO - "product": "firefox", 18:50:50 INFO - "script_repo_revision": "production", 18:50:50 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 18:50:50 INFO - "repository": "", 18:50:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 18:50:50 INFO - "buildid": "20170109162508", 18:50:50 INFO - "pgo_build": "False", 18:50:50 INFO - "basedir": "/builds/slave/test", 18:50:50 INFO - "buildnumber": 30, 18:50:50 INFO - "slavename": "t-yosemite-r7-0294", 18:50:50 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 18:50:50 INFO - "platform": "yosemite_r7", 18:50:50 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:50:50 INFO - "branch": "mozilla-aurora", 18:50:50 INFO - "repo_path": "releases/mozilla-aurora", 18:50:50 INFO - "revision": "08a4d4135acdf49e0db6670e1cca550767fbe2f4", 18:50:50 INFO - "stage_platform": "macosx64", 18:50:50 INFO - "builduid": "5975fea350084e1fb54a3e180add5941", 18:50:50 INFO - "slavebuilddir": "test" 18:50:50 INFO - } 18:50:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 18:50:50 INFO - Running post-action listener: _resource_record_post_action 18:50:50 INFO - [mozharness: 2017-01-10 02:50:50.804744Z] Finished read-buildbot-config step (success) 18:50:50 INFO - [mozharness: 2017-01-10 02:50:50.804859Z] Running download-and-extract step. 18:50:50 INFO - Running pre-action listener: _resource_record_pre_action 18:50:50 INFO - Running main action method: download_and_extract 18:50:50 INFO - mkdir: /builds/slave/test/build/tests 18:50:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:50 INFO - trying https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 18:50:50 INFO - Downloading https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/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 18:50:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/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 18:50:52 INFO - Downloaded 1199 bytes. 18:50:52 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 18:50:52 INFO - Using the following test package requirements: 18:50:52 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 18:50:52 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 18:50:52 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 18:50:52 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:52 INFO - u'jsshell-mac.zip'], 18:50:52 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 18:50:52 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 18:50:52 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 18:50:52 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 18:50:52 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 18:50:52 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 18:50:52 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:50:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 18:50:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:50:52 INFO - Fetch https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 18:50:52 INFO - Http code: 200 18:50:52 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 18:50:52 INFO - x-amz-cf-id: OvNNOUVaxZ-YcJDgJVtsV8dP75-HPiVrlPMxtUVgOE0SIY4sFhDRfg== 18:50:52 INFO - x-amz-version-id: O4DEF9_bXc2mdQIdyKCw3Qa_2kFIIBA_ 18:50:53 INFO - Expected file size: 16271347 18:50:53 INFO - Obtained file size: 16271347 18:50:59 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 18:50:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 18:50:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:50:59 INFO - Fetch https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 18:51:00 INFO - Http code: 200 18:51:00 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 18:51:00 INFO - x-amz-cf-id: bdz8SgcWJpUHfH5_QuAD2Wif7ofuvfDBwYAzvPq6Z5YJ6MWzGFb8AA== 18:51:00 INFO - x-amz-version-id: O4DEF9_bXc2mdQIdyKCw3Qa_2kFIIBA_ 18:51:00 INFO - Expected file size: 16271347 18:51:00 INFO - Obtained file size: 16271347 18:51:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 18:51:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:51:06 INFO - Fetch https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 18:51:07 INFO - Http code: 200 18:51:07 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 18:51:07 INFO - x-amz-cf-id: 5m3NSsHsdqD1b_KsE2VAzBxHAtK-ZD3_ahBNtjW6H1SFMlPTrKA6og== 18:51:07 INFO - x-amz-version-id: G5yfCCoCfaRoy60C5c9c4.9YEUNT5v2n 18:51:07 INFO - Expected file size: 14409038 18:51:07 INFO - Obtained file size: 14409038 18:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:08 INFO - trying https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:51:08 INFO - Downloading https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 18:51:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/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 18:51:11 INFO - Downloaded 109880091 bytes. 18:51:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:51:11 INFO - mkdir: /builds/slave/test/properties 18:51:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:51:11 INFO - Writing to file /builds/slave/test/properties/build_url 18:51:11 INFO - Contents: 18:51:11 INFO - build_url:https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:51:11 INFO - Running post-action listener: _resource_record_post_action 18:51:11 INFO - Running post-action listener: set_extra_try_arguments 18:51:11 INFO - [mozharness: 2017-01-10 02:51:11.727738Z] Finished download-and-extract step (success) 18:51:11 INFO - [mozharness: 2017-01-10 02:51:11.727876Z] Running populate-webroot step. 18:51:11 INFO - Running pre-action listener: _resource_record_pre_action 18:51:11 INFO - Running main action method: populate_webroot 18:51:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:51:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:51:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:51:11 INFO - '--tppagecycles', 18:51:11 INFO - '7'], 18:51:11 INFO - 'tests': ['tsvgm']}}, 18:51:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:51:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:51:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:51:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:51:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:51:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:51:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:11 INFO - 'talos_options': ['--disable-e10s'], 18:51:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:51:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:51:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:11 INFO - 'talos_options': ['--disable-e10s'], 18:51:11 INFO - 'tests': ['damp', 'tps']}, 18:51:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:11 INFO - 'tests': ['damp', 'tps']}, 18:51:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:51:11 INFO - 'tests': ['dromaeo_dom']}, 18:51:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:51:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:51:11 INFO - 'tests': ['basic_compositor_video']}, 18:51:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:51:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:51:11 INFO - 'tests': ['a11yr', 18:51:11 INFO - 'ts_paint', 18:51:11 INFO - 'tpaint', 18:51:11 INFO - 'sessionrestore', 18:51:11 INFO - 'sessionrestore_no_auto_restore', 18:51:11 INFO - 'tabpaint']}, 18:51:11 INFO - 'other-e10s': {'tests': ['a11yr', 18:51:11 INFO - 'ts_paint', 18:51:11 INFO - 'tpaint', 18:51:11 INFO - 'sessionrestore', 18:51:11 INFO - 'sessionrestore_no_auto_restore', 18:51:11 INFO - 'tabpaint']}, 18:51:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:51:11 INFO - 'ts_paint', 18:51:11 INFO - 'tpaint', 18:51:11 INFO - 'sessionrestore', 18:51:11 INFO - 'sessionrestore_no_auto_restore', 18:51:11 INFO - 'tabpaint']}, 18:51:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:51:11 INFO - 'ts_paint', 18:51:11 INFO - 'tpaint', 18:51:11 INFO - 'sessionrestore', 18:51:11 INFO - 'sessionrestore_no_auto_restore', 18:51:11 INFO - 'tabpaint']}, 18:51:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:51:11 INFO - 'tests': ['a11yr', 18:51:11 INFO - 'ts_paint', 18:51:11 INFO - 'tpaint', 18:51:11 INFO - 'sessionrestore', 18:51:11 INFO - 'sessionrestore_no_auto_restore', 18:51:11 INFO - 'tabpaint']}, 18:51:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:51:11 INFO - 'tests': ['a11yr', 18:51:11 INFO - 'ts_paint', 18:51:11 INFO - 'tpaint', 18:51:11 INFO - 'sessionrestore', 18:51:11 INFO - 'sessionrestore_no_auto_restore', 18:51:11 INFO - 'tabpaint']}, 18:51:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:51:11 INFO - 'tests': ['tsvgx', 18:51:11 INFO - 'tsvgr_opacity', 18:51:11 INFO - 'tart', 18:51:11 INFO - 'tscrollx', 18:51:11 INFO - 'cart']}, 18:51:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:51:11 INFO - 'tsvgr_opacity', 18:51:11 INFO - 'tart', 18:51:11 INFO - 'tscrollx', 18:51:11 INFO - 'cart']}, 18:51:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:11 INFO - 'talos_options': ['--disable-e10s'], 18:51:11 INFO - 'tests': ['tp5o']}, 18:51:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:11 INFO - 'tests': ['tp5o']}, 18:51:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:11 INFO - 'talos_options': ['--disable-e10s', 18:51:11 INFO - '--xperf_path', 18:51:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:51:11 INFO - 'tests': ['tp5n']}, 18:51:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:51:11 INFO - 'talos_options': ['--xperf_path', 18:51:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:51:11 INFO - 'tests': ['tp5n']}}, 18:51:11 INFO - 'talos.zip': {'path': '', 18:51:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:51:11 INFO - Running post-action listener: _resource_record_post_action 18:51:11 INFO - [mozharness: 2017-01-10 02:51:11.746861Z] Finished populate-webroot step (success) 18:51:11 INFO - [mozharness: 2017-01-10 02:51:11.746950Z] Running create-virtualenv step. 18:51:11 INFO - Running pre-action listener: _resource_record_pre_action 18:51:11 INFO - Running main action method: create_virtualenv 18:51:11 INFO - Creating virtualenv /builds/slave/test/build/venv 18:51:11 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 18:51:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:51:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:51:11 INFO - Using real prefix '/tools/python27' 18:51:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:51:12 INFO - Installing distribute.............................................................................................................................................................................................done. 18:51:15 INFO - Installing pip.................done. 18:51:15 INFO - Return code: 0 18:51:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:51:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:51:15 INFO - Reading from file tmpfile_stdout 18:51:15 INFO - Output received: 18:51:15 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) 18:51:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107db6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d2c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa1d5b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bcad30>, '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 0x107a30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc2b20>, '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.L230gMCPLq/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.51S0o3kzjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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 18:51:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:51:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:51:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L230gMCPLq/Render', 18:51:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:15 INFO - 'HOME': '/Users/cltbld', 18:51:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:15 INFO - 'LOGNAME': 'cltbld', 18:51:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:15 INFO - 'MOZ_NO_REMOTE': '1', 18:51:15 INFO - 'NO_EM_RESTART': '1', 18:51:15 INFO - 'PAGER': '/bin/cat', 18:51:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:15 INFO - 'PWD': '/builds/slave/test', 18:51:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:15 INFO - 'SHELL': '/bin/bash', 18:51:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.51S0o3kzjN/Listeners', 18:51:15 INFO - 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/', 18:51:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:15 INFO - 'USER': 'cltbld', 18:51:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:15 INFO - 'XPC_FLAGS': '0x0', 18:51:15 INFO - 'XPC_SERVICE_NAME': '0', 18:51:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:15 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 18:51:15 INFO - Cleaning up... 18:51:16 INFO - Return code: 0 18:51:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:51:16 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')]} 18:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:16 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')]} 18:51:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:16 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 0x107db6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d2c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa1d5b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bcad30>, '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 0x107a30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc2b20>, '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.L230gMCPLq/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.51S0o3kzjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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 18:51:16 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 18:51:16 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 18:51:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L230gMCPLq/Render', 18:51:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:16 INFO - 'HOME': '/Users/cltbld', 18:51:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:16 INFO - 'LOGNAME': 'cltbld', 18:51:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:16 INFO - 'MOZ_NO_REMOTE': '1', 18:51:16 INFO - 'NO_EM_RESTART': '1', 18:51:16 INFO - 'PAGER': '/bin/cat', 18:51:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:16 INFO - 'PWD': '/builds/slave/test', 18:51:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:16 INFO - 'SHELL': '/bin/bash', 18:51:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.51S0o3kzjN/Listeners', 18:51:16 INFO - 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/', 18:51:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:16 INFO - 'USER': 'cltbld', 18:51:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:16 INFO - 'XPC_FLAGS': '0x0', 18:51:16 INFO - 'XPC_SERVICE_NAME': '0', 18:51:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:16 INFO - Downloading/unpacking psutil>=3.1.1 18:51:16 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 18:51:16 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 18:51:16 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 18:51:16 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 18:51:16 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 18:51:16 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 18:51:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:51:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:51:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:51:19 INFO - Installing collected packages: psutil 18:51:19 INFO - Running setup.py install for psutil 18:51:19 INFO - building 'psutil._psutil_osx' extension 18:51:19 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 18:51:20 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 18:51:20 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 18:51:20 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 18:51:20 INFO - building 'psutil._psutil_posix' extension 18:51:20 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 18:51:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:51:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:51:20 INFO - ^ 18:51:20 INFO - 1 warning generated. 18:51:20 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 18:51:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:51:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:51:20 INFO - Successfully installed psutil 18:51:20 INFO - Cleaning up... 18:51:20 INFO - Return code: 0 18:51:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:51:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:20 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 0x107db6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d2c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa1d5b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bcad30>, '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 0x107a30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc2b20>, '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.L230gMCPLq/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.51S0o3kzjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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 18:51:20 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 18:51:20 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 18:51:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L230gMCPLq/Render', 18:51:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:20 INFO - 'HOME': '/Users/cltbld', 18:51:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:20 INFO - 'LOGNAME': 'cltbld', 18:51:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:20 INFO - 'MOZ_NO_REMOTE': '1', 18:51:20 INFO - 'NO_EM_RESTART': '1', 18:51:20 INFO - 'PAGER': '/bin/cat', 18:51:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:20 INFO - 'PWD': '/builds/slave/test', 18:51:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:20 INFO - 'SHELL': '/bin/bash', 18:51:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.51S0o3kzjN/Listeners', 18:51:20 INFO - 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/', 18:51:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:20 INFO - 'USER': 'cltbld', 18:51:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:20 INFO - 'XPC_FLAGS': '0x0', 18:51:20 INFO - 'XPC_SERVICE_NAME': '0', 18:51:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:51:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:51:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:51:23 INFO - Installing collected packages: mozsystemmonitor 18:51:23 INFO - Running setup.py install for mozsystemmonitor 18:51:24 INFO - Successfully installed mozsystemmonitor 18:51:24 INFO - Cleaning up... 18:51:24 INFO - Return code: 0 18:51:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:24 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 0x107db6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d2c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa1d5b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bcad30>, '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 0x107a30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc2b20>, '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.L230gMCPLq/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.51S0o3kzjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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 18:51:24 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 18:51:24 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 18:51:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L230gMCPLq/Render', 18:51:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:24 INFO - 'HOME': '/Users/cltbld', 18:51:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:24 INFO - 'LOGNAME': 'cltbld', 18:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:24 INFO - 'MOZ_NO_REMOTE': '1', 18:51:24 INFO - 'NO_EM_RESTART': '1', 18:51:24 INFO - 'PAGER': '/bin/cat', 18:51:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:24 INFO - 'PWD': '/builds/slave/test', 18:51:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:24 INFO - 'SHELL': '/bin/bash', 18:51:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.51S0o3kzjN/Listeners', 18:51:24 INFO - 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/', 18:51:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:24 INFO - 'USER': 'cltbld', 18:51:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:24 INFO - 'XPC_FLAGS': '0x0', 18:51:24 INFO - 'XPC_SERVICE_NAME': '0', 18:51:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:24 INFO - Downloading/unpacking jsonschema==2.5.1 18:51:24 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 18:51:24 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 18:51:24 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 18:51:24 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 18:51:24 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 18:51:24 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 18:51:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:51:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:27 INFO - Downloading functools32-3.2.3-2.tar.gz 18:51:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:51:27 INFO - warning: no files found matching '*.txt' 18:51:27 INFO - no previously-included directories found matching 'build' 18:51:27 INFO - no previously-included directories found matching 'dist' 18:51:27 INFO - no previously-included directories found matching '.git*' 18:51:27 INFO - Installing collected packages: jsonschema, functools32 18:51:27 INFO - Running setup.py install for functools32 18:51:27 INFO - warning: no files found matching '*.txt' 18:51:27 INFO - no previously-included directories found matching 'build' 18:51:27 INFO - no previously-included directories found matching 'dist' 18:51:27 INFO - no previously-included directories found matching '.git*' 18:51:27 INFO - Successfully installed jsonschema functools32 18:51:27 INFO - Cleaning up... 18:51:27 INFO - Return code: 0 18:51:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:51:27 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')]} 18:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:27 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')]} 18:51:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:27 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 0x107db6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d2c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa1d5b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bcad30>, '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 0x107a30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc2b20>, '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.L230gMCPLq/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.51S0o3kzjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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 18:51:27 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 18:51:27 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 18:51:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L230gMCPLq/Render', 18:51:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:27 INFO - 'HOME': '/Users/cltbld', 18:51:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:27 INFO - 'LOGNAME': 'cltbld', 18:51:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:27 INFO - 'MOZ_NO_REMOTE': '1', 18:51:27 INFO - 'NO_EM_RESTART': '1', 18:51:27 INFO - 'PAGER': '/bin/cat', 18:51:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:27 INFO - 'PWD': '/builds/slave/test', 18:51:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:27 INFO - 'SHELL': '/bin/bash', 18:51:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.51S0o3kzjN/Listeners', 18:51:27 INFO - 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/', 18:51:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:27 INFO - 'USER': 'cltbld', 18:51:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:27 INFO - 'XPC_FLAGS': '0x0', 18:51:27 INFO - 'XPC_SERVICE_NAME': '0', 18:51:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:51:27 INFO - Cleaning up... 18:51:27 INFO - Return code: 0 18:51:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:51:27 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')]} 18:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:27 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')]} 18:51:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:27 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 0x107db6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d2c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa1d5b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bcad30>, '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 0x107a30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc2b20>, '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.L230gMCPLq/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.51S0o3kzjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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 18:51:27 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 18:51:27 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 18:51:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L230gMCPLq/Render', 18:51:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:27 INFO - 'HOME': '/Users/cltbld', 18:51:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:27 INFO - 'LOGNAME': 'cltbld', 18:51:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:27 INFO - 'MOZ_NO_REMOTE': '1', 18:51:27 INFO - 'NO_EM_RESTART': '1', 18:51:27 INFO - 'PAGER': '/bin/cat', 18:51:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:27 INFO - 'PWD': '/builds/slave/test', 18:51:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:27 INFO - 'SHELL': '/bin/bash', 18:51:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.51S0o3kzjN/Listeners', 18:51:27 INFO - 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/', 18:51:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:27 INFO - 'USER': 'cltbld', 18:51:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:27 INFO - 'XPC_FLAGS': '0x0', 18:51:27 INFO - 'XPC_SERVICE_NAME': '0', 18:51:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:28 INFO - Downloading/unpacking blobuploader==1.2.4 18:51:28 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 18:51:28 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 18:51:28 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 18:51:28 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 18:51:28 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 18:51:28 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 18:51:30 INFO - Downloading blobuploader-1.2.4.tar.gz 18:51:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:51:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:51:30 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 18:51:30 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 18:51:30 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 18:51:30 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 18:51:30 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 18:51:30 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 18:51:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:51:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:51:31 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 18:51:31 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 18:51:31 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 18:51:31 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 18:51:31 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 18:51:31 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 18:51:32 INFO - Downloading docopt-0.6.1.tar.gz 18:51:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:51:32 INFO - Installing collected packages: blobuploader, requests, docopt 18:51:32 INFO - Running setup.py install for blobuploader 18:51:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:51:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:51:32 INFO - Running setup.py install for requests 18:51:32 INFO - Running setup.py install for docopt 18:51:32 INFO - Successfully installed blobuploader requests docopt 18:51:32 INFO - Cleaning up... 18:51:33 INFO - Return code: 0 18:51:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:51:33 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')]} 18:51:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:33 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')]} 18:51:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:33 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 0x107db6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d2c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa1d5b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bcad30>, '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 0x107a30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc2b20>, '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.L230gMCPLq/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.51S0o3kzjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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 18:51:33 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 18:51:33 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 18:51:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L230gMCPLq/Render', 18:51:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:33 INFO - 'HOME': '/Users/cltbld', 18:51:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:33 INFO - 'LOGNAME': 'cltbld', 18:51:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:33 INFO - 'MOZ_NO_REMOTE': '1', 18:51:33 INFO - 'NO_EM_RESTART': '1', 18:51:33 INFO - 'PAGER': '/bin/cat', 18:51:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:33 INFO - 'PWD': '/builds/slave/test', 18:51:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:33 INFO - 'SHELL': '/bin/bash', 18:51:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.51S0o3kzjN/Listeners', 18:51:33 INFO - 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/', 18:51:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:33 INFO - 'USER': 'cltbld', 18:51:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:33 INFO - 'XPC_FLAGS': '0x0', 18:51:33 INFO - 'XPC_SERVICE_NAME': '0', 18:51:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:51:33 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-dSSglv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:51:33 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-QRak0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:51:33 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-G4Nyhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:51:33 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-AXOfgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:51:33 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-SumHEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:51:33 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-jEunya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:51:33 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-pZAin9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:51:34 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-y0ognL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:51:34 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-7PH94J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:51:34 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-ea58Kv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:51:34 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-BbCWtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:51:34 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-wgDJhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:51:34 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-c5GuzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:51:34 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-nnxLo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:51:34 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-SJx9bY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:51:34 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-rocGKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:51:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:51:34 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-cwu_uS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:51:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:51:35 INFO - Running setup.py install for manifestparser 18:51:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:51:35 INFO - Running setup.py install for mozcrash 18:51:35 INFO - Running setup.py install for mozdebug 18:51:35 INFO - Running setup.py install for mozdevice 18:51:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:51:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:51:35 INFO - Running setup.py install for mozfile 18:51:35 INFO - Running setup.py install for mozhttpd 18:51:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:51:35 INFO - Running setup.py install for mozinfo 18:51:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:51:36 INFO - Running setup.py install for mozInstall 18:51:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:51:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:51:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:51:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:51:36 INFO - Running setup.py install for mozleak 18:51:36 INFO - Running setup.py install for mozlog 18:51:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:51:36 INFO - Running setup.py install for moznetwork 18:51:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:51:36 INFO - Running setup.py install for mozprocess 18:51:36 INFO - Running setup.py install for mozprofile 18:51:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:51:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:51:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:51:37 INFO - Running setup.py install for mozrunner 18:51:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:51:37 INFO - Running setup.py install for mozscreenshot 18:51:37 INFO - Running setup.py install for moztest 18:51:37 INFO - Running setup.py install for mozversion 18:51:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:51:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:51:37 INFO - Cleaning up... 18:51:37 INFO - Return code: 0 18:51:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:51: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')]} 18:51:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51: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')]} 18:51:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:37 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 0x107db6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d2c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa1d5b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bcad30>, '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 0x107a30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc2b20>, '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.L230gMCPLq/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.51S0o3kzjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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 18:51:37 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 18:51:37 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 18:51:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L230gMCPLq/Render', 18:51:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:37 INFO - 'HOME': '/Users/cltbld', 18:51:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:37 INFO - 'LOGNAME': 'cltbld', 18:51:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:37 INFO - 'MOZ_NO_REMOTE': '1', 18:51:37 INFO - 'NO_EM_RESTART': '1', 18:51:37 INFO - 'PAGER': '/bin/cat', 18:51:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:37 INFO - 'PWD': '/builds/slave/test', 18:51:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:37 INFO - 'SHELL': '/bin/bash', 18:51:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.51S0o3kzjN/Listeners', 18:51:37 INFO - 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/', 18:51:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:37 INFO - 'USER': 'cltbld', 18:51:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:37 INFO - 'XPC_FLAGS': '0x0', 18:51:37 INFO - 'XPC_SERVICE_NAME': '0', 18:51:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:51:37 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-jTgsdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:51:38 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)) 18:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:51:38 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-QsL1Zo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:51:38 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)) 18:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:51:38 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-6XSyHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:51:38 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)) 18:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:51:38 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-FXzX0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:51:38 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)) 18:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:51:38 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-ed5rex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:51:38 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)) 18:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:51:38 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-BJ_QNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:51:38 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)) 18:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:51:38 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-lU9ohx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:51:38 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)) 18:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:51:38 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-LSYFr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:51:38 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)) 18:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:51:38 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-WTxuvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:51:38 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)) 18:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:51:38 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-KbUtSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:51:39 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)) 18:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:51:39 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-Kc9Z1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:51:39 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)) 18:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:51:39 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-EpNgnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:51:39 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)) 18:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:51:39 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-1WFabB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:51:39 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)) 18:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:51:39 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-1vuQaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:51:39 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)) 18:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:51:39 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-uryqQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:51:39 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)) 18:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:51:39 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-bRiSeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:51:39 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)) 18:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:51:39 INFO - Running setup.py (path:/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/pip-Ze6M_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:51:39 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)) 18:51:39 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)) 18:51:39 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)) 18:51:39 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)) 18:51:39 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)) 18:51:39 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)) 18:51:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:51:39 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 18:51:39 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 18:51:39 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 18:51:39 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 18:51:39 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 18:51:39 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 18:51:42 INFO - Downloading blessings-1.6.tar.gz 18:51:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:51:42 INFO - Installing collected packages: blessings 18:51:42 INFO - Running setup.py install for blessings 18:51:42 INFO - Successfully installed blessings 18:51:42 INFO - Cleaning up... 18:51:42 INFO - Return code: 0 18:51:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:51:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:51:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:51:42 INFO - Reading from file tmpfile_stdout 18:51:42 INFO - Current package versions: 18:51:42 INFO - blessings == 1.6 18:51:42 INFO - blobuploader == 1.2.4 18:51:42 INFO - docopt == 0.6.1 18:51:42 INFO - functools32 == 3.2.3-2 18:51:42 INFO - jsonschema == 2.5.1 18:51:42 INFO - manifestparser == 1.1 18:51:42 INFO - mozInstall == 1.12 18:51:42 INFO - mozcrash == 1.0 18:51:42 INFO - mozdebug == 0.1 18:51:42 INFO - mozdevice == 0.48 18:51:42 INFO - mozfile == 1.2 18:51:42 INFO - mozhttpd == 0.7 18:51:42 INFO - mozinfo == 0.9 18:51:42 INFO - mozleak == 0.1 18:51:42 INFO - mozlog == 3.4 18:51:42 INFO - moznetwork == 0.27 18:51:42 INFO - mozprocess == 0.23 18:51:42 INFO - mozprofile == 0.28 18:51:42 INFO - mozrunner == 6.13 18:51:42 INFO - mozscreenshot == 0.1 18:51:42 INFO - mozsystemmonitor == 0.3 18:51:42 INFO - moztest == 0.8 18:51:42 INFO - mozversion == 1.4 18:51:42 INFO - psutil == 3.1.1 18:51:42 INFO - requests == 1.2.3 18:51:42 INFO - wsgiref == 0.1.2 18:51:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:51:42 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')]} 18:51:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:43 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 0x107db6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d2c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa1d5b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bcad30>, '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 0x107a30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc2b20>, '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.L230gMCPLq/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.51S0o3kzjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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 18:51:43 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 18:51:43 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 18:51:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L230gMCPLq/Render', 18:51:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:43 INFO - 'HOME': '/Users/cltbld', 18:51:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:43 INFO - 'LOGNAME': 'cltbld', 18:51:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:43 INFO - 'MOZ_NO_REMOTE': '1', 18:51:43 INFO - 'NO_EM_RESTART': '1', 18:51:43 INFO - 'PAGER': '/bin/cat', 18:51:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:43 INFO - 'PWD': '/builds/slave/test', 18:51:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:43 INFO - 'SHELL': '/bin/bash', 18:51:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.51S0o3kzjN/Listeners', 18:51:43 INFO - 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/', 18:51:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:43 INFO - 'USER': 'cltbld', 18:51:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:43 INFO - 'XPC_FLAGS': '0x0', 18:51:43 INFO - 'XPC_SERVICE_NAME': '0', 18:51:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:43 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)) 18:51:43 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)) 18:51:43 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)) 18:51:43 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)) 18:51:43 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)) 18:51:43 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)) 18:51:43 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)) 18:51:43 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)) 18:51:43 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)) 18:51:43 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)) 18:51:43 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)) 18:51:43 INFO - Cleaning up... 18:51:43 INFO - Return code: 0 18:51:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:51:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107db6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d2c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafa1d5b5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bcad30>, '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 0x107a30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc2b20>, '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.L230gMCPLq/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.51S0o3kzjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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 18:51: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', 'jsonschema'] in /builds/slave/test/build 18:51: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 jsonschema 18:51:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L230gMCPLq/Render', 18:51:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:44 INFO - 'HOME': '/Users/cltbld', 18:51:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:44 INFO - 'LOGNAME': 'cltbld', 18:51:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:44 INFO - 'MOZ_NO_REMOTE': '1', 18:51:44 INFO - 'NO_EM_RESTART': '1', 18:51:44 INFO - 'PAGER': '/bin/cat', 18:51:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:44 INFO - 'PWD': '/builds/slave/test', 18:51:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:44 INFO - 'SHELL': '/bin/bash', 18:51:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.51S0o3kzjN/Listeners', 18:51:44 INFO - 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/', 18:51:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:44 INFO - 'USER': 'cltbld', 18:51:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:44 INFO - 'XPC_FLAGS': '0x0', 18:51:44 INFO - 'XPC_SERVICE_NAME': '0', 18:51:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:51:44 INFO - Cleaning up... 18:51:44 INFO - Return code: 0 18:51:44 INFO - Running post-action listener: _resource_record_post_action 18:51:44 INFO - Running post-action listener: _start_resource_monitoring 18:51:44 INFO - Starting resource monitoring. 18:51:44 INFO - [mozharness: 2017-01-10 02:51:44.618117Z] Finished create-virtualenv step (success) 18:51:44 INFO - [mozharness: 2017-01-10 02:51:44.619175Z] Running install step. 18:51:44 INFO - Running pre-action listener: _resource_record_pre_action 18:51:44 INFO - Running main action method: install 18:51:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:51:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:51:44 INFO - Reading from file tmpfile_stdout 18:51:44 INFO - Detecting whether we're running mozinstall >=1.0... 18:51:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:51:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:51:44 INFO - Reading from file tmpfile_stdout 18:51:44 INFO - Output received: 18:51:44 INFO - Usage: mozinstall [options] installer 18:51:44 INFO - Options: 18:51:44 INFO - -h, --help show this help message and exit 18:51:44 INFO - -d DEST, --destination=DEST 18:51:44 INFO - Directory to install application into. [default: 18:51:44 INFO - "/builds/slave/test"] 18:51:44 INFO - --app=APP Application being installed. [default: firefox] 18:51:44 INFO - mkdir: /builds/slave/test/build/application 18:51:44 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'] 18:51:44 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 18:52:15 INFO - Reading from file tmpfile_stdout 18:52:15 INFO - Output received: 18:52:15 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:52:15 INFO - Running post-action listener: _resource_record_post_action 18:52:15 INFO - [mozharness: 2017-01-10 02:52:15.797519Z] Finished install step (success) 18:52:15 INFO - [mozharness: 2017-01-10 02:52:15.797680Z] Running run-tests step. 18:52:15 INFO - Running pre-action listener: _resource_record_pre_action 18:52:15 INFO - Running main action method: run_tests 18:52:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:52:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:52:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:52:15 INFO - 2017-01-09 18:52:15.812 screenresolution[1625:7423] starting screenresolution argv=screenresolution get 18:52:15 INFO - 2017-01-09 18:52:15.815 screenresolution[1625:7423] Display 0: 1600x1200x32@60 18:52:15 INFO - 2017-01-09 18:52:15.821 screenresolution[1626:7425] starting screenresolution argv=screenresolution list 18:52:15 INFO - Available Modes on Display 0 18:52:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:52:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:52:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:52:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:52:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:52:16 INFO - Intel Iris: 18:52:16 INFO - Chipset Model: Intel Iris 18:52:16 INFO - Type: GPU 18:52:16 INFO - Bus: Built-In 18:52:16 INFO - VRAM (Dynamic, Max): 1536 MB 18:52:16 INFO - Vendor: Intel (0x8086) 18:52:16 INFO - Device ID: 0x0a2e 18:52:16 INFO - Revision ID: 0x0009 18:52:16 INFO - Displays: 18:52:16 INFO - Display: 18:52:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:52:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:52:16 INFO - Display Serial Number: 1600x1200 60 18:52:16 INFO - Main Display: Yes 18:52:16 INFO - Mirror: Off 18:52:16 INFO - Online: Yes 18:52:16 INFO - Rotation: Supported 18:52:16 INFO - Return code: 0 18:52:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:52:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:52:16 INFO - Python 2.7.3 18:52:16 INFO - Return code: 0 18:52:16 INFO - grabbing minidump binary from tooltool 18:52:16 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')]} 18:52:16 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 0x107bcad30>, '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 0x107a30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:52:16 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 18:52:16 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 18:52:16 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 18:52:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:52:16 INFO - Return code: 0 18:52:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:52:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:52:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:52:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:52:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:52:16 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/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0294', '--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 18:52:16 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/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0294 --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 18:52:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L230gMCPLq/Render', 18:52:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:16 INFO - 'HOME': '/Users/cltbld', 18:52:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:16 INFO - 'LOGNAME': 'cltbld', 18:52:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:52:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:52:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:16 INFO - 'MOZ_NO_REMOTE': '1', 18:52:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:52:16 INFO - 'NO_EM_RESTART': '1', 18:52:16 INFO - 'PAGER': '/bin/cat', 18:52:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:16 INFO - 'PWD': '/builds/slave/test', 18:52:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:52:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:16 INFO - 'SHELL': '/bin/bash', 18:52:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.51S0o3kzjN/Listeners', 18:52:16 INFO - 'TMPDIR': '/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/', 18:52:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:16 INFO - 'USER': 'cltbld', 18:52:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:16 INFO - 'XPC_FLAGS': '0x0', 18:52:16 INFO - 'XPC_SERVICE_NAME': '0', 18:52:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:16 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/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0294', '--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 18:52:16 INFO - mozversion application_buildid: 20170109162508 18:52:16 INFO - mozversion application_changeset: 08a4d4135acdf49e0db6670e1cca550767fbe2f4 18:52:16 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:52:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:52:16 INFO - mozversion application_name: Firefox 18:52:16 INFO - mozversion application_remotingname: firefox-dev 18:52:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:52:16 INFO - mozversion application_vendor: Mozilla 18:52:16 INFO - mozversion application_version: 52.0a2 18:52:16 INFO - mozversion platform_buildid: 20170109162508 18:52:16 INFO - mozversion platform_changeset: 08a4d4135acdf49e0db6670e1cca550767fbe2f4 18:52:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:52:16 INFO - mozversion platform_version: 52.0a2 18:52:16 INFO - using testdate: 1484016736 18:52:16 INFO - actual date: 1484016736 18:52:16 INFO - starting webserver on 'localhost:49251' 18:52:16 INFO - SUITE-START | Running 2 tests 18:52:16 INFO - TEST-START | dromaeo_css 18:52:16 INFO - operating with platform_type : mac_ 18:52:16 INFO - Initialising browser for dromaeo_css test... 18:52:16 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/tmpG15H5i/profile http://localhost:49251/getInfo.html) 18:52:19 INFO - PROCESS | 1636 | 2017-01-09 18:52:19.514 plugin-container[1638:7609] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 18:52:19 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:52:19 INFO - PROCESS | 1636 | 2017-01-09 18:52:19.516 plugin-container[1638:7609] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9203, name = 'com.apple.CFPasteboardClient' 18:52:19 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:52:19 INFO - PROCESS | 1636 | 2017-01-09 18:52:19.516 plugin-container[1638:7609] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:52:19 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 18:52:19 INFO - PROCESS | 1636 | colorDepth:24 18:52:19 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 18:52:19 INFO - PROCESS | 1636 | __metrics 18:52:19 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 18:52:20 INFO - TEST-INFO | 1636: exit 0 18:52:20 INFO - Browser initialized. 18:52:20 INFO - Running cycle 1/1 for dromaeo_css test... 18:52:20 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/tmpG15H5i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:52:23 INFO - PROCESS | 1639 | 2017-01-09 18:52:23.428 plugin-container[1640:7746] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 18:52:23 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:52:23 INFO - PROCESS | 1639 | 2017-01-09 18:52:23.428 plugin-container[1640:7746] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4513, name = 'com.apple.CFPasteboardClient' 18:52:23 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:52:23 INFO - PROCESS | 1639 | 2017-01-09 18:52:23.428 plugin-container[1640:7746] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:52:33 INFO - PROCESS | 1639 | RSS: Main: 229117952 18:52:33 INFO - PROCESS | 1639 | 18:55:35 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 18:55:38 INFO - PROCESS | 1639 | RSS: Main: 232812544 18:55:38 INFO - PROCESS | 1639 | 18:58:41 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 18:58:41 INFO - PROCESS | 1639 | RSS: Main: 243834880 18:58:41 INFO - PROCESS | 1639 | 19:01:43 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 19:01:44 INFO - PROCESS | 1639 | RSS: Main: 246702080 19:01:44 INFO - PROCESS | 1639 | 19:04:46 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 19:04:47 INFO - PROCESS | 1639 | RSS: Main: 245923840 19:04:47 INFO - PROCESS | 1639 | 19:07:49 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 19:07:50 INFO - PROCESS | 1639 | RSS: Main: 249458688 19:07:50 INFO - PROCESS | 1639 | 19:10:53 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 19:10:53 INFO - PROCESS | 1639 | RSS: Main: 250908672 19:10:53 INFO - PROCESS | 1639 | 19:10:53 INFO - PROCESS | 1639 | __start_tp_report 19:10:53 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 19:10:53 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 19:10:53 INFO - PROCESS | 1639 | |i|pagename|runs| 19:10:53 INFO - PROCESS | 1639 | |0;dojo.html;2870.268081639182;3361.865525378985;3366.5960084789845;3376.8142752148624;3432.9656703432966;5655.943440565593;5668.376478587352;5671.6880571568545;5740.684262365568;5742.397048309919;5716.456936591017;5784.311779070465;5799.797007104752;5821.67980761058;5843.18195452637;4308.590683885026;5156.994386094713;5219.478052194788;5291.68249905005;5300;5265.473452654742;5312.096943519592;5318;5320.521153096221;5344.118220493614;5157.432682404932;5171.896562068775;5272.657277276965;5300.072487314724;5330.760115794798;5233.424323324451;5247.317848679685;5249.107651699221;5249.396319423258;5272.551833094153;5129.384473863161;5230.163173892159;5239.947600523984;5265.526102650741;5297.073012222884;4824.879378015543;5123.487651234846;5806.738696758656;5848.3274423441;5883.676397798119;5989.161517387569;6090.634561926255;6126.816195514141;6251.562390632657;6258.718357673915;6108.0992441897915;6111.166351794787;6111.227117700271;6165.414285642857;6204.782832600882;5782.537397008271;5791.8552036199435;5824.883502329972;5842.415758424208;5876.206712093813;6205.720742566596;6255.186825712628;6291.842703932347;6301;6302.684865756785;5975.790847320323;6038.547108966845;6057.485113765291;6058.091286307089;6093.238345206849;6186.690665466709;6189.705794235746;6231.189945307081;6255.12428260044;6258.718357673915;6194.256689197326;6196.752129914753;6201.720922558496;6233.563650544509;6243.219597550306;5253.212018197301;6080.905437021379;6097.603655762361;6122.938770612237;6174.753009879644;4761.642876784185;4826.203676393426;4837.927431088537;4840.636952228527;4888.780004899788;4280.5505421930875;4297.183535128315;4301.870943871689;4330.0906809569715;4363.345498175299;4599.701019433726;5190.987757387274;5477.917831232535;5579;5730.19777231188;5728.226689396878;6073.18012068374;6117.15760190703;6133.334663919082;6143.078538219303;4750.216214324667;5623.550115990793;5875.941240587625;5898.852528686817;5899.056151015902;5296.3909150447425;5354.65194762347;5359.08895487768;5369.436209197978;5372.785088596491;6140.412224875342;6213.068039794067;6281.528885333527;6335.366463353628;6386.904196436964;5307.442718514655;6126.387361263838;6188.164597779242;6205.596636218631;6231.657258850807;5219.321488206509;6212.943799554177;6215.689215539295;6267.655278959701;6271.529635277281;5072.898542029065;6187.628742275478;6204.627722336674;6208.348123447154;6246.906791311593;5256.9211461827335;5751.677114263826;6220.284667263322;6221.035739460391;6252.343503932204;5815.069588865761;5851.21008663842;5877.970610146922;5937.673427961504;6061.484773794277;5895.380984996514;5906.173135760911;5920.467157955804;5946.375630558729;5968.403159683997;5680.744366503434;5697.116946873241;5761.827145185651;5764.769409223584;5766.221560089502;5923.940760592339;5956.463918247378;5957.582969192115;5961.314448838439;5976.641401515923;5788.0739081746715;5790.94209057904;5808.477237048686;5815.3603103657715;5815.680137592304 19:10:53 INFO - PROCESS | 1639 | |1;ext.html;487.7707477485583;512.6411511941641;517.5523172455825;519.2419068160485;546.4317110205386;37938.810305948464;40501.59498405015;40575.39136912945;40607.59392406075;40628.79685601575;32877.178070548245;41942.16115677685;41967.790161049226;41992.79003604978;42107.368389474104;9212.907870921306;9410.952945235264;9420.858687119699;9439.056094390575;9457.479838608873;9325.673601423918;9442.952785236064;9454.432734035945;9455.196308313802;9463.1483166515;9457.101575350365;9471.242300615968;9478.146966772989;9482.905170948272;9490.952545237264;9363;9387.10822471864;9465.763355916122;9473.242140628734;9481.241500679998;9294.395864268869;9394.107559781809;9395.483248421335;9401.858972115424;9410.529473526296;39075.60924390776;39108;39629.60370396317;39659.20681586381;39685.20629587421;3260.608726952757;3346.7155291800227;3383.813890236036;3861.864834730771;3961.0295477607883;23604.763952360427;29956.400871982656;30979.380412391627;31187.220319491968;31514.842425787952;3006.2183832203564;3240.54632351471;3241.5785947826867;3242.9960431869226;3305.735541156702;502558;508398.9999999963;510533;511726;511965;61059.38940610626;61157.694211529546;61353.772924541256;61464;61498.38501614927;62437.75124497485;62975;64340;64375.03437448442;64425;16226.918865405598;16437;16453.753193702105;16467.505974820775;16477.928934619213;12630.494780208688;12671.429785659668;12714.427850746739;12732.681682958;12738.872611273953;17100.0594967278;17208.31166753341;17265.82734172642;17388.217530211172;17437.564060898578;3175.396674631859;6471.579347342407;6654.26803051664;6790.388865002174;6790.932090679129;828.941974061822;912.3167755491108;968.6125549780004;1007.6271779441654;1045.8797238317538;29950.70049299479;30335.848320758254;30634.84682576573;30692.016418370662;30721.539176912363;919.8298314811781;1027.198784947742;1035.414990530348;1116.1573012375604;1153.4982282707049;15661;15663.68672626541;15666.68666626661;16428.507144785675;16900.57748556296;549289;559507;563264;565583;568086;58048;58146;58600;58730.706346467996;58799.11801322983;1384.3286006286942;1391.839938407096;1406.6483379155006;1412.555045160771;1418.6949805791805;23690.407739806644;28772.424551508433;31197.376052479278;31410;31535.84232078917;44358.447454338944;51515.22727159021;52145.69635759136;52282.47717522776;52346.73826630995;596261;599187;621939;622012;624400;26851.925922962862;26904.180577009785;27185;27274.318142045813;27335.863320684064;46164.7691761539;46332.7683361581;46536.301955471376;46541.30188047114;46653;38449.34731200532;38663.806680966416;38984.80507597444;39794.60205397909;40024;33804.66195338015;38182;38646.6135338643;39552.80223598979;39617;34706;37555.87332380033;37772.43341349827;37926;38548.036299092746;28514.287142821595;33303.00272975444;35280.823595881855;36739.08152296107;37190;24505.387365316008;24674.383140420916;24957.251282461555;25998.87000564985;26541.60187597226 19:10:53 INFO - PROCESS | 1639 | |2;jquery.html;364.64811456944045;371.03160750441344;376.766404829006;378.96778773804226;394.91595570159916;18454.525043683683;18988.90505547472;19955.401337959847;20221.393358199242;20425.28511502098;19842.90078549605;20190.495237619067;20528.07623656935;20674.379768606927;20731.792682073174;5744.856378590548;5868.17845501629;5997.220361353018;6126.23422072241;6146.8463288417925;5999.220101386813;6027.547933904974;6044.365341639131;6057.454829065383;6070.2412198475195;5706.28671416073;5900.763969441217;5928.7332070056955;5988.700564971756;6030.517558595324;5910.556708246877;5963.165156878041;5964.880702385972;5964.910526342065;5983.042713165873;4988.800447982077;5094.2103973884105;5211.817586384458;5213.53078222958;5216.608754343401;19033.238670453164;20780.896095519427;25009.499810003697;25059.49881002388;25059.872305746285;2327.0459111764094;2359.6106642404156;2449.546833835728;2480.417101981033;2494.588392915167;12835.614931552069;14838.258087095603;14921.701565968728;15047;15263.847361526354;1814.6642871068761;1816.8364847163687;1874.306506592556;1927.8554108441922;1936.2255097960788;114951;117391;117419.41290293494;118124;118442.99999999914;9995.400275983464;10958.671239862817;11040.171987101;11061.281016733887;11247.381394023407;13789.931050344683;13850.722985540233;13857.514986975408;13869.37587808551;13887.513937012156;6731.1922569292;7127;7470.925290747132;8464.365172611955;8589.355798315151;8578.399512034097;8609.053004169537;8656.6537338507;8702.260307873776;8726.171013753683;6433.839154021093;6656.70044847983;6675.666216689243;6675.966620166868;6686.866262674719;1594.4260066376096;1847.6951303034848;1877.755891734066;1881.4261650196822;1882.341180586811;735.7351353512731;877.3902138014147;914.7118657622827;949.1599934058409;1040;19142.904285478482;20334.288299909727;20639.8968005159;20710.48223794387;21615.45961350979;929.6142101027998;941.6657086734248;955.3646824861528;976.1409959235828;1002.3785253143097;10450.895491045145;11509.712257193638;11530.444322545321;11665.125115616363;11848.466818993165;491481;590621;592493;600470;625562;111400.88599114068;115320;120143;120748.3962580199;120910.39544802396;428.2185012352407;452.7532494790347;452.8822506148425;455.94984551699287;456.1265177185785;9266.721998340061;10769.676909692722;10995.670129896116;11391.658250252505;11549;43784.46754363582;52613;54760.35718928438;55372.72313638406;56423;79927.60036199781;80908;82419.5879020625;82421;83074;7808.063032436144;7809.921900781147;7961.522308661499;8330.958345208477;8373.120822313569;12113.030957523553;12635.305058221886;12849.87150128524;12891.070107750165;12896.22622642607;10967.725806854893;10974;11105.888941110485;11194.888051119384;11418.771624567627;11040.889591103985;11210;11244;11298.322100673986;11310.4910279036;11397.829032564352;11422.714432139262;11480.196386252883;11589.014933730727;11772.411379430823;7902.130765615892;8089.150639182801;8400.369972252227;8401.915980840358;8423.70517031901;8320.292775113941;10974.890251097706;11102.222844401138;11285;11374.033207177483 19:10:53 INFO - PROCESS | 1639 | |3;mootools.html;1351.6485713714435;1470.6764511807403;1644.692469486758;1671.5904603372176;1839.7332386803905;7618;7735.38116950644;7863.606819659009;8564.785880352994;8588;8073.233042860934;8386.62260198291;8406.369522285837;8411.95794021029;8494.830103397928;2304.7580004099536;2337.345543247893;2341.7658234176615;2342.824288178385;2347.882605869708;2316.6872472216287;2338.0063473023984;2367.1359953616893;2379.298107058422;2382.03527571333;2303.896324665394;2306.6770652108717;2324.1284518305633;2327.837051406402;2342.6837376954063;1899.3921944977612;2283.4519715268216;2284.942876428086;2319.860808351504;2320.060375547906;1940.8350290225349;2256.6953461282683;2278.863268203913;2304.7695230476984;2310.595645761985;8121.431499795017;8154.3884208684585;8228.30059444948;8261.545614991173;8266.710665126751;5029.7736601853;5176.5858731301405;5181.274621552986;5197.454267301917;5385.030694474993;3072.9091172633616;3252.6259480159847;3469.150058235723;3493.91265218369;3497.510348551205;1046.7958748044134;1047.5757318286107;1081.3944191252924;1096.9012788849043;1097.1935627313917;9232.676856310065;10940.671779846618;11010.669679909615;11017.669469915836;11086.002259796656;4269.573042695768;4344.304911214191;4512.278035514302;4607.170709272363;4808.855734327905;9426.198773104226;9461.716148515556;9487.288453366025;9494.905050949403;9531.237500999903;5192.143296356059;5207.609429292743;5233.188855727369;5243.056249874983;5252.763625636855;4290.528041915387;4314.676399270004;4773.498782627844;4777.211760059551;4799.160146974265;3802;3840.462335273064;3912.56961734209;3925.764454132704;3948.58539853317;1280.1998750780763;1285.2673975833873;1294.5663202827159;1300.362822217107;1306.2227974355244;736.3078706016328;864.8097418567905;909.3589019741066;916.1159481100636;925.0980294213089;5573.27547418833;5635.915461268084;5692.6015178937905;5701.059325211377;5893.941060589339;890.0343127706382;933.3852435286856;940.6190492850407;942.2085448223526;944.9480278584743;3389.7627166098628;3747.868824591181;3820.312343778091;3900.2744784660767;3955.4264631628253;9664.90335096654;10018.74953126163;10246.59013639461;10276.845847312296;10302.557641930136;9536.046395360408;10061.849072263922;10225.284230103822;10253.179745620333;10260.230482713827;7826.608669566613;7962.800929976797;7968.2430169135;7982.560959147303;7988.80027999293;5494.478024587658;5552.500274975271;5552.750126244409;5594.832155035356;5660.773569057192;9371.34400591952;9373.85939210926;9404.341696081208;9518.524073796421;9542.761430964281;9109.18017378439;9512.619495220391;9514.429134251966;9541.427514349161;9549;6716.764913228014;6919.827004324932;7086.645667716491;7234.891476627749;7383.335499805044;7677.9616101921365;7770.45606807518;7884.21157884207;7902.24928631779;7918.881216781638;6093;6110.847228819315;6964.79105626832;7108.147022357351;7221.025161603116;7068.222495525385;7087.61018144007;7112.644367781693;7146.071010768567;7171.820704482221;6421.325760795236;6441.484681225585;6525.216973962965;6526.151600291932;6532.477401807749;4877.780499877443;4891.119598472309;4916.3362946001835;4926.211806111005;4946.35697359341;4089.2230476210616;4149.27387707144;4798.640101992295;4895.192293271571;4984.277279794482 19:10:53 INFO - PROCESS | 1639 | |4;prototype.html;250.47650410641756;290.0688788987352;297.7424527783467;306.9002574163396;312.82638135834605;4439.979809186821;5211.009908117455;5313.282706834576;5351.946480535194;5373.623846330758;4989.3513843200335;5134.948650513494;5190.377154741427;5192.844214673556;5194.37667479902;3493.3537295600267;3979.4627725257155;3988.561258261589;3998.720089593722;4003.859864904729;3488.7732297400717;3823.7323387362894;3988.7008474364393;4026.4966879140106;4029.7380670256484;3266.0528446750413;3942.940855887164;3946.4017073085447;3972.2849887020325;3984.0836607580272;3854.6916246700475;3902.765834049966;3932.2135572885536;3945.644891959723;3978.462907507507;2690.4709573001314;3212.2932954749917;3284.638689744126;3420.1278673938236;3457.550518432613;4863.416390033183;5002.474740152268;5088.287639730441;5104.387473503166;5106.234064890259;3855.939616605428;4469.7541635210055;4514.6004738531165;4516.435445569304;4551.931721024187;5479.876625291846;5491.862703432406;5498.2646258333625;5509.889802203974;5546;3186.617605887285;3214.791038582508;3483.805202722269;3770.5852356240794;3783.4892289540835;5005.499450054966;5067.822626208102;5715.599908006441;5770.278715160605;5793.913091303634;3388.102152929476;3673.3020726061964;3804.201117765245;3838.1214089432924;3871.2838124946975;3893.124047089439;3898;3903.59012303704;3909.9638595772144;3910.432987216838;3788.8214276790955;3922.37242041272;4186.811593478301;4491.371208030879;4491.910161796745;4681.95318046822;4715.2513751567585;5415.591946093965;5632.352279487829;5645.322561292589;999.6900960702205;1196.8264601632889;1218.3055658274875;1250.1560198706834;1257.735875466144;776.90068552998;831.7920519870032;859.3726579596929;865.8398196333699;872.5157537566712;4425.114977004612;4864.419063804207;4872.3665923430435;4877.609791216694;4910.2880082388565;4445.110977804452;4755.405574303212;5293.602979776532;5307.575393968474;5340.412554618989;4204.26425375558;5224.268602395668;5268.9736551317765;5272.525472707398;5303.443138470482;2824.971750282512;2831.6460442444695;2841.559558268472;2842.658880934301;2845.7438830505353;4952.455229924705;5256.6320357574605;5427.945720542823;5597.776088956478;5654.773809047592;4672.719636821802;5233.476652334736;5521.558275337963;5546.528545073633;5564.137558678412;5106.642535022513;5278.419373868948;5482.517482517483;5674.829755107354;5715.628484148434;4805.918668299604;5055.924161137512;5157.587393008625;5397.892042159214;5441.31319291021;4679.859604211879;4805.8225734695225;4809.44691360484;5189.481051894781;5214.556762675215;4752.691075080109;4919.778609962489;5070.8225212117395;5586.514001269396;5606.943930560806;3093.706097920694;3562.7149828014217;3652.160003199226;3827.961720382872;3847.48059012041;5715.599908006399;5728.770849165987;5730.369659337553;5736.282964629421;5767.307923049261;5299.49654782795;5590.580706446952;5594.496495315441;5596.720163991703;5596.804111856066;5261.868453288545;5420.73935460751;5530.419305972815;5539.7784088636;5544.313031833946;4912.557869791736;4924.704517728947;5166;5320.388155362183;5333.359996800409;4870.951290487192;5627.593101724568;5640.294963129609;5640.520555752807;5660.547156227574;4593.655475839392;5471.316085489314;5484.957858006966;5489.231507588861;5490.196078431372 19:10:53 INFO - PROCESS | 1639 | |5;yui.html;625.3590070178068;692.1970514203525;708.7306823407106;737.5574655206873;783.9804004899877;14021.088629239104;14782.630434239149;14870.256487175639;14872.256387180625;14914.627134321647;13442.865571344284;13484.056116071879;13486.865131348684;13490.662733431669;13640.727185456284;658.3581008516682;683.0710234081645;809.7773112394109;820.0364571628343;837.7654256808104;822.6709316273478;835.578033093287;838.5304229631396;843.1273631791097;845.1421806866036;881.6561540998983;884.1953822021961;884.5090974509145;887.6227603268617;890.2966656341482;841.2680967558233;879.6085741844881;880.5289170293862;881.6649673124236;886.3662481325883;544.8038706065813;635.8473966248114;645.6513482719326;660.4683229999848;665.1917919727555;13497.325133743274;13584.524541640994;13764.449422023106;13775.173489590657;14533.055351402125;11565.306081635128;13815.032947693666;14085.436582536686;14231.07498012626;14366.784498232535;11159.163062770325;11171.441427928652;11357.772844543062;11460.885391146065;11485.253458525252;3591.982040089783;3671.7429779915415;3743.1390780120723;3761.3041587306434;3773.52830896138;93494.59758103635;95896.9999999993;96406.03593964041;96618;97285.51357243239;10596.576136954589;10817.621383251548;10861.511231994578;10896;11229.438528073728;21577.244796432362;21699.457513562287;21710.674339884914;21836.89081554582;21944.780552194272;7129.572225666477;7179.389751871045;7187.56874587516;7266.491345605863;7348.154962179417;3923.352646813244;4182.1008483176265;4183.937240941388;4248.638865696388;4438.001449673862;3240.756943229267;3242.9837850810595;3336.432806422914;3355.6644335566248;3379.9324013520086;583.600233839821;615.9168512250878;620.7765204526366;625.2059883947361;631.135344577922;545.5144921020295;594.2720167794417;595.2291782142137;604.2356419129804;617.6510271696477;7485.176630570633;8445;8493.362997775192;8620.482771033758;8713.956430217935;558.1627558662007;584.0331117920952;615.4860691322796;616.3836163836164;622.5922021076202;3544.220271540257;4197.8320867165585;4216.388623649615;4244.490661120685;4255.723377980421;86650.56674716587;89750.55124724466;94120.0587994125;101490;120030;65252.67373663101;70147;72377;90688;95400;79134.60432697799;84633.57683211668;85474;88064.55967720249;88955;9087.727368178965;9444.291678124306;9507.477088760184;9569.473678947725;9673.081057299545;53797;55002;55363.72318138384;56029.439705602425;56072.719636401554;74919;75105.24894751201;77092.61453692695;79948.20051799408;82138.76791848008;12050.09624278165;12372.566960156351;12408.069394795246;12475.750484990067;12518.812217816558;13464.798028029783;13770.65573360674;13932.094413863066;14153.787693184817;14378;12435.440405181607;12718.872811271769;13074.954003679873;13378.331083445593;13761.587152385444;12714.745705086032;12988;13228.40472178735;13238.933805330911;13320.33398330099;12748.553800616934;12801.295928723637;12807.48770049225;12876.678083047998;12888.291143987171;10645.840312395165;10822.621208257673;11011.834822477509;11161;11456.885431145582;8482.703105391281;8692;9559.805024371954;10127.936566660395;11025.228234023862 19:10:53 INFO - PROCESS | 1639 | __end_tp_report 19:10:53 INFO - PROCESS | 1639 | __start_cc_report 19:10:53 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,383 19:10:53 INFO - PROCESS | 1639 | __end_cc_report 19:10:53 INFO - PROCESS | 1639 | __startTimestamp1484017853389__endTimestamp 19:10:53 INFO - PROCESS | 1639 | 19:10:53 INFO - PROCESS | 1639 | ------- Summary: start ------- 19:10:53 INFO - PROCESS | 1639 | Number of tests: 6 19:10:53 INFO - PROCESS | 1639 | 19:10:53 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5642.62 Median:5815.68 stddev:651.64 (11.2%) stddev-sans-first:616.28 19:10:53 INFO - PROCESS | 1639 | Values: 2870.3 3361.9 3366.6 3376.8 3433.0 5655.9 5668.4 5671.7 5740.7 5742.4 5716.5 5784.3 5799.8 5821.7 5843.2 4308.6 5157.0 5219.5 5291.7 5300.0 5265.5 5312.1 5318.0 5320.5 5344.1 5157.4 5171.9 5272.7 5300.1 5330.8 5233.4 5247.3 5249.1 5249.4 5272.6 5129.4 5230.2 5239.9 5265.5 5297.1 4824.9 5123.5 5806.7 5848.3 5883.7 5989.2 6090.6 6126.8 6251.6 6258.7 6108.1 6111.2 6111.2 6165.4 6204.8 5782.5 5791.9 5824.9 5842.4 5876.2 6205.7 6255.2 6291.8 6301.0 6302.7 5975.8 6038.5 6057.5 6058.1 6093.2 6186.7 6189.7 6231.2 6255.1 6258.7 6194.3 6196.8 6201.7 6233.6 6243.2 5253.2 6080.9 6097.6 6122.9 6174.8 4761.6 4826.2 4837.9 4840.6 4888.8 4280.6 4297.2 4301.9 4330.1 4363.3 4599.7 5191.0 5477.9 5579.0 5730.2 5728.2 6073.2 6117.2 6133.3 6143.1 4750.2 5623.6 5875.9 5898.9 5899.1 5296.4 5354.7 5359.1 5369.4 5372.8 6140.4 6213.1 6281.5 6335.4 6386.9 5307.4 6126.4 6188.2 6205.6 6231.7 5219.3 6212.9 6215.7 6267.7 6271.5 5072.9 6187.6 6204.6 6208.3 6246.9 5256.9 5751.7 6220.3 6221.0 6252.3 5815.1 5851.2 5878.0 5937.7 6061.5 5895.4 5906.2 5920.5 5946.4 5968.4 5680.7 5697.1 5761.8 5764.8 5766.2 5923.9 5956.5 5957.6 5961.3 5976.6 5788.1 5790.9 5808.5 5815.4 5815.7 19:10:53 INFO - PROCESS | 1639 | 19:10:53 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:69433.93 Median:39672.21 stddev:150229.96 (378.7%) stddev-sans-first:150562.21 19:10:53 INFO - PROCESS | 1639 | Values: 487.8 512.6 517.6 519.2 546.4 37938.8 40501.6 40575.4 40607.6 40628.8 32877.2 41942.2 41967.8 41992.8 42107.4 9212.9 9411.0 9420.9 9439.1 9457.5 9325.7 9443.0 9454.4 9455.2 9463.1 9457.1 9471.2 9478.1 9482.9 9491.0 9363.0 9387.1 9465.8 9473.2 9481.2 9294.4 9394.1 9395.5 9401.9 9410.5 39075.6 39108.0 39629.6 39659.2 39685.2 3260.6 3346.7 3383.8 3861.9 3961.0 23604.8 29956.4 30979.4 31187.2 31514.8 3006.2 3240.5 3241.6 3243.0 3305.7 502558.0 508399.0 510533.0 511726.0 511965.0 61059.4 61157.7 61353.8 61464.0 61498.4 62437.8 62975.0 64340.0 64375.0 64425.0 16226.9 16437.0 16453.8 16467.5 16477.9 12630.5 12671.4 12714.4 12732.7 12738.9 17100.1 17208.3 17265.8 17388.2 17437.6 3175.4 6471.6 6654.3 6790.4 6790.9 828.9 912.3 968.6 1007.6 1045.9 29950.7 30335.8 30634.8 30692.0 30721.5 919.8 1027.2 1035.4 1116.2 1153.5 15661.0 15663.7 15666.7 16428.5 16900.6 549289.0 559507.0 563264.0 565583.0 568086.0 58048.0 58146.0 58600.0 58730.7 58799.1 1384.3 1391.8 1406.6 1412.6 1418.7 23690.4 28772.4 31197.4 31410.0 31535.8 44358.4 51515.2 52145.7 52282.5 52346.7 596261.0 599187.0 621939.0 622012.0 624400.0 26851.9 26904.2 27185.0 27274.3 27335.9 46164.8 46332.8 46536.3 46541.3 46653.0 38449.3 38663.8 38984.8 39794.6 40024.0 33804.7 38182.0 38646.6 39552.8 39617.0 34706.0 37555.9 37772.4 37926.0 38548.0 28514.3 33303.0 35280.8 36739.1 37190.0 24505.4 24674.4 24957.3 25998.9 26541.6 19:10:53 INFO - PROCESS | 1639 | 19:10:53 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:34129.69 Median:13752.04 stddev:97095.23 (706.0%) stddev-sans-first:97334.50 19:10:53 INFO - PROCESS | 1639 | Values: 364.6 371.0 376.8 379.0 394.9 18454.5 18988.9 19955.4 20221.4 20425.3 19842.9 20190.5 20528.1 20674.4 20731.8 5744.9 5868.2 5997.2 6126.2 6146.8 5999.2 6027.5 6044.4 6057.5 6070.2 5706.3 5900.8 5928.7 5988.7 6030.5 5910.6 5963.2 5964.9 5964.9 5983.0 4988.8 5094.2 5211.8 5213.5 5216.6 19033.2 20780.9 25009.5 25059.5 25059.9 2327.0 2359.6 2449.5 2480.4 2494.6 12835.6 14838.3 14921.7 15047.0 15263.8 1814.7 1816.8 1874.3 1927.9 1936.2 114951.0 117391.0 117419.4 118124.0 118443.0 9995.4 10958.7 11040.2 11061.3 11247.4 13789.9 13850.7 13857.5 13869.4 13887.5 6731.2 7127.0 7470.9 8464.4 8589.4 8578.4 8609.1 8656.7 8702.3 8726.2 6433.8 6656.7 6675.7 6676.0 6686.9 1594.4 1847.7 1877.8 1881.4 1882.3 735.7 877.4 914.7 949.2 1040.0 19142.9 20334.3 20639.9 20710.5 21615.5 929.6 941.7 955.4 976.1 1002.4 10450.9 11509.7 11530.4 11665.1 11848.5 491481.0 590621.0 592493.0 600470.0 625562.0 111400.9 115320.0 120143.0 120748.4 120910.4 428.2 452.8 452.9 455.9 456.1 9266.7 10769.7 10995.7 11391.7 11549.0 43784.5 52613.0 54760.4 55372.7 56423.0 79927.6 80908.0 82419.6 82421.0 83074.0 7808.1 7809.9 7961.5 8331.0 8373.1 12113.0 12635.3 12849.9 12891.1 12896.2 10967.7 10974.0 11105.9 11194.9 11418.8 11040.9 11210.0 11244.0 11298.3 11310.5 11397.8 11422.7 11480.2 11589.0 11772.4 7902.1 8089.2 8400.4 8401.9 8423.7 8320.3 10974.9 11102.2 11285.0 11374.0 19:10:53 INFO - PROCESS | 1639 | 19:10:53 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5430.19 Median:5194.80 stddev:2979.56 (57.4%) stddev-sans-first:2972.15 19:10:53 INFO - PROCESS | 1639 | Values: 1351.6 1470.7 1644.7 1671.6 1839.7 7618.0 7735.4 7863.6 8564.8 8588.0 8073.2 8386.6 8406.4 8412.0 8494.8 2304.8 2337.3 2341.8 2342.8 2347.9 2316.7 2338.0 2367.1 2379.3 2382.0 2303.9 2306.7 2324.1 2327.8 2342.7 1899.4 2283.5 2284.9 2319.9 2320.1 1940.8 2256.7 2278.9 2304.8 2310.6 8121.4 8154.4 8228.3 8261.5 8266.7 5029.8 5176.6 5181.3 5197.5 5385.0 3072.9 3252.6 3469.2 3493.9 3497.5 1046.8 1047.6 1081.4 1096.9 1097.2 9232.7 10940.7 11010.7 11017.7 11086.0 4269.6 4344.3 4512.3 4607.2 4808.9 9426.2 9461.7 9487.3 9494.9 9531.2 5192.1 5207.6 5233.2 5243.1 5252.8 4290.5 4314.7 4773.5 4777.2 4799.2 3802.0 3840.5 3912.6 3925.8 3948.6 1280.2 1285.3 1294.6 1300.4 1306.2 736.3 864.8 909.4 916.1 925.1 5573.3 5635.9 5692.6 5701.1 5893.9 890.0 933.4 940.6 942.2 944.9 3389.8 3747.9 3820.3 3900.3 3955.4 9664.9 10018.7 10246.6 10276.8 10302.6 9536.0 10061.8 10225.3 10253.2 10260.2 7826.6 7962.8 7968.2 7982.6 7988.8 5494.5 5552.5 5552.8 5594.8 5660.8 9371.3 9373.9 9404.3 9518.5 9542.8 9109.2 9512.6 9514.4 9541.4 9549.0 6716.8 6919.8 7086.6 7234.9 7383.3 7678.0 7770.5 7884.2 7902.2 7918.9 6093.0 6110.8 6964.8 7108.1 7221.0 7068.2 7087.6 7112.6 7146.1 7171.8 6421.3 6441.5 6525.2 6526.2 6532.5 4877.8 4891.1 4916.3 4926.2 4946.4 4089.2 4149.3 4798.6 4895.2 4984.3 19:10:53 INFO - PROCESS | 1639 | 19:10:53 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4378.01 Median:4922.24 stddev:1355.79 (27.5%) stddev-sans-first:1323.72 19:10:53 INFO - PROCESS | 1639 | Values: 250.5 290.1 297.7 306.9 312.8 4440.0 5211.0 5313.3 5351.9 5373.6 4989.4 5134.9 5190.4 5192.8 5194.4 3493.4 3979.5 3988.6 3998.7 4003.9 3488.8 3823.7 3988.7 4026.5 4029.7 3266.1 3942.9 3946.4 3972.3 3984.1 3854.7 3902.8 3932.2 3945.6 3978.5 2690.5 3212.3 3284.6 3420.1 3457.6 4863.4 5002.5 5088.3 5104.4 5106.2 3855.9 4469.8 4514.6 4516.4 4551.9 5479.9 5491.9 5498.3 5509.9 5546.0 3186.6 3214.8 3483.8 3770.6 3783.5 5005.5 5067.8 5715.6 5770.3 5793.9 3388.1 3673.3 3804.2 3838.1 3871.3 3893.1 3898.0 3903.6 3910.0 3910.4 3788.8 3922.4 4186.8 4491.4 4491.9 4682.0 4715.3 5415.6 5632.4 5645.3 999.7 1196.8 1218.3 1250.2 1257.7 776.9 831.8 859.4 865.8 872.5 4425.1 4864.4 4872.4 4877.6 4910.3 4445.1 4755.4 5293.6 5307.6 5340.4 4204.3 5224.3 5269.0 5272.5 5303.4 2825.0 2831.6 2841.6 2842.7 2845.7 4952.5 5256.6 5427.9 5597.8 5654.8 4672.7 5233.5 5521.6 5546.5 5564.1 5106.6 5278.4 5482.5 5674.8 5715.6 4805.9 5055.9 5157.6 5397.9 5441.3 4679.9 4805.8 4809.4 5189.5 5214.6 4752.7 4919.8 5070.8 5586.5 5606.9 3093.7 3562.7 3652.2 3828.0 3847.5 5715.6 5728.8 5730.4 5736.3 5767.3 5299.5 5590.6 5594.5 5596.7 5596.8 5261.9 5420.7 5530.4 5539.8 5544.3 4912.6 4924.7 5166.0 5320.4 5333.4 4871.0 5627.6 5640.3 5640.5 5660.5 4593.7 5471.3 5485.0 5489.2 5490.2 19:10:53 INFO - PROCESS | 1639 | 19:10:53 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20105.91 Median:545.16 stddev:28981.18 (5316.1%) stddev-sans-first:29025.57 19:10:53 INFO - PROCESS | 1639 | Values: 625.4 692.2 708.7 737.6 784.0 14021.1 14782.6 14870.3 14872.3 14914.6 13442.9 13484.1 13486.9 13490.7 13640.7 658.4 683.1 809.8 820.0 837.8 822.7 835.6 838.5 843.1 845.1 881.7 884.2 884.5 887.6 890.3 841.3 879.6 880.5 881.7 886.4 544.8 635.8 645.7 660.5 665.2 13497.3 13584.5 13764.4 13775.2 14533.1 11565.3 13815.0 14085.4 14231.1 14366.8 11159.2 11171.4 11357.8 11460.9 11485.3 3592.0 3671.7 3743.1 3761.3 3773.5 93494.6 95897.0 96406.0 96618.0 97285.5 10596.6 10817.6 10861.5 10896.0 11229.4 21577.2 21699.5 21710.7 21836.9 21944.8 7129.6 7179.4 7187.6 7266.5 7348.2 3923.4 4182.1 4183.9 4248.6 4438.0 3240.8 3243.0 3336.4 3355.7 3379.9 583.6 615.9 620.8 625.2 631.1 545.5 594.3 595.2 604.2 617.7 7485.2 8445.0 8493.4 8620.5 8714.0 558.2 584.0 615.5 616.4 622.6 3544.2 4197.8 4216.4 4244.5 4255.7 86650.6 89750.6 94120.1 101490.0 120030.0 65252.7 70147.0 72377.0 90688.0 95400.0 79134.6 84633.6 85474.0 88064.6 88955.0 9087.7 9444.3 9507.5 9569.5 9673.1 53797.0 55002.0 55363.7 56029.4 56072.7 74919.0 75105.2 77092.6 79948.2 82138.8 12050.1 12372.6 12408.1 12475.8 12518.8 13464.8 13770.7 13932.1 14153.8 14378.0 12435.4 12718.9 13075.0 13378.3 13761.6 12714.7 12988.0 13228.4 13238.9 13320.3 12748.6 12801.3 12807.5 12876.7 12888.3 10645.8 10822.6 11011.8 11161.0 11456.9 8482.7 8692.0 9559.8 10127.9 11025.2 19:10:53 INFO - PROCESS | 1639 | -------- Summary: end -------- 19:10:53 INFO - PROCESS | 1639 | 19:10:54 INFO - TEST-INFO | 1639: exit 0 19:10:54 INFO - TEST-OK | dromaeo_css | took 1117683ms 19:10:54 INFO - TEST-START | kraken 19:10:54 INFO - operating with platform_type : mac_ 19:10:54 INFO - Initialising browser for kraken test... 19:10:54 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/tmpGVU5l0/profile http://localhost:49251/getInfo.html) 19:10:57 INFO - PROCESS | 1651 | 2017-01-09 19:10:57.172 plugin-container[1652:10073] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x894b, name = 'com.apple.tsm.portname' 19:10:57 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:10:57 INFO - PROCESS | 1651 | 2017-01-09 19:10:57.175 plugin-container[1652:10073] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8633, name = 'com.apple.CFPasteboardClient' 19:10:57 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:10:57 INFO - PROCESS | 1651 | 2017-01-09 19:10:57.175 plugin-container[1652:10073] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:10:57 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 19:10:57 INFO - PROCESS | 1651 | colorDepth:24 19:10:57 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 19:10:57 INFO - PROCESS | 1651 | __metrics 19:10:57 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 19:10:58 INFO - TEST-INFO | 1651: exit 0 19:10:58 INFO - Browser initialized. 19:10:58 INFO - Running cycle 1/1 for kraken test... 19:10:58 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/T/tmpGVU5l0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:11:00 INFO - PROCESS | 1653 | 2017-01-09 19:11:00.940 plugin-container[1654:10198] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 19:11:00 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:11:00 INFO - PROCESS | 1653 | 2017-01-09 19:11:00.940 plugin-container[1654:10198] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c03, name = 'com.apple.CFPasteboardClient' 19:11:00 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:11:00 INFO - PROCESS | 1653 | 2017-01-09 19:11:00.940 plugin-container[1654:10198] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:11:10 INFO - PROCESS | 1653 | RSS: Main: 228945920 19:11:10 INFO - PROCESS | 1653 | 19:11:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 19:11:14 INFO - PROCESS | 1653 | RSS: Main: 241332224 19:11:14 INFO - PROCESS | 1653 | 19:11:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 19:11:17 INFO - PROCESS | 1653 | RSS: Main: 249544704 19:11:17 INFO - PROCESS | 1653 | 19:11:21 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 19:11:21 INFO - PROCESS | 1653 | RSS: Main: 253136896 19:11:21 INFO - PROCESS | 1653 | 19:11:23 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 19:11:24 INFO - PROCESS | 1653 | RSS: Main: 253857792 19:11:24 INFO - PROCESS | 1653 | 19:11:26 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:11:26 INFO - PROCESS | 1653 | RSS: Main: 254828544 19:11:26 INFO - PROCESS | 1653 | 19:11:29 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 19:11:30 INFO - PROCESS | 1653 | RSS: Main: 258703360 19:11:30 INFO - PROCESS | 1653 | 19:11:33 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 19:11:34 INFO - PROCESS | 1653 | RSS: Main: 258420736 19:11:34 INFO - PROCESS | 1653 | 19:11:37 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 19:11:37 INFO - PROCESS | 1653 | RSS: Main: 258924544 19:11:37 INFO - PROCESS | 1653 | 19:11:39 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:11:40 INFO - PROCESS | 1653 | RSS: Main: 257630208 19:11:40 INFO - PROCESS | 1653 | 19:11:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:11:42 INFO - PROCESS | 1653 | RSS: Main: 257622016 19:11:42 INFO - PROCESS | 1653 | 19:11:44 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:11:45 INFO - PROCESS | 1653 | RSS: Main: 257515520 19:11:45 INFO - PROCESS | 1653 | 19:11:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:11:49 INFO - PROCESS | 1653 | RSS: Main: 257822720 19:11:49 INFO - PROCESS | 1653 | 19:11:52 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:11:52 INFO - PROCESS | 1653 | RSS: Main: 257855488 19:11:52 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 19:11:54 INFO - PROCESS | 1653 | RSS: Main: 259305472 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | __start_tp_report 19:11:54 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 19:11:54 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 19:11:54 INFO - PROCESS | 1653 | |i|pagename|runs| 19:11:54 INFO - PROCESS | 1653 | |0;ai-astar;116;103;174;168;139;119;119;119;120;118 19:11:54 INFO - PROCESS | 1653 | |1;audio-beat-detection;145;144;97;186;150;146;119;114;142;148 19:11:54 INFO - PROCESS | 1653 | |2;audio-dft;195;200;198;233;260;200;201;195;196;201 19:11:54 INFO - PROCESS | 1653 | |3;audio-fft;83;59;60;86;58;60;60;59;79;63 19:11:54 INFO - PROCESS | 1653 | |4;audio-oscillator;83;71;92;73;71;71;82;69;72;90 19:11:54 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;137;132;89;88;89;102;90;88;90;122 19:11:54 INFO - PROCESS | 1653 | |6;imaging-darkroom;133;97;147;99;138;92;132;91;89;133 19:11:54 INFO - PROCESS | 1653 | |7;imaging-desaturate;95;89;94;85;77;135;146;92;89;88 19:11:54 INFO - PROCESS | 1653 | |8;json-parse-financial;56;58;56;58;59;64;82;78;81;90 19:11:54 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;70;77;74;108;78;69;64;68;71;65 19:11:54 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;82;92;97;85;84;86;99;102;106;104 19:11:54 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;140;141;139;140;143;145;143;147;191;144 19:11:54 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;166;170;170;165;238;175;168;165;169;166 19:11:54 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;69;67;71;81;88;87;70;67;64;68 19:11:54 INFO - PROCESS | 1653 | __end_tp_report 19:11:54 INFO - PROCESS | 1653 | __start_cc_report 19:11:54 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1006 19:11:54 INFO - PROCESS | 1653 | __end_cc_report 19:11:54 INFO - PROCESS | 1653 | __startTimestamp1484017914777__endTimestamp 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | ------- Summary: start ------- 19:11:54 INFO - PROCESS | 1653 | Number of tests: 14 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:129.50 Median:119.50 stddev:23.55 (19.7%) stddev-sans-first:24.46 19:11:54 INFO - PROCESS | 1653 | Values: 116.0 103.0 174.0 168.0 139.0 119.0 119.0 119.0 120.0 118.0 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:147.00 stddev:24.31 (16.5%) stddev-sans-first:25.69 19:11:54 INFO - PROCESS | 1653 | Values: 145.0 144.0 97.0 186.0 150.0 146.0 119.0 114.0 142.0 148.0 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:207.90 Median:200.50 stddev:21.44 (10.7%) stddev-sans-first:22.23 19:11:54 INFO - PROCESS | 1653 | Values: 195.0 200.0 198.0 233.0 260.0 200.0 201.0 195.0 196.0 201.0 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:66.70 Median:61.50 stddev:11.22 (18.2%) stddev-sans-first:10.23 19:11:54 INFO - PROCESS | 1653 | Values: 83.0 59.0 60.0 86.0 58.0 60.0 60.0 59.0 79.0 63.0 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:77.50 stddev:8.60 (11.1%) stddev-sans-first:8.89 19:11:54 INFO - PROCESS | 1653 | Values: 83.0 71.0 92.0 73.0 71.0 71.0 82.0 69.0 72.0 90.0 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:102.70 Median:88.50 stddev:19.83 (22.4%) stddev-sans-first:16.70 19:11:54 INFO - PROCESS | 1653 | Values: 137.0 132.0 89.0 88.0 89.0 102.0 90.0 88.0 90.0 122.0 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:115.10 Median:90.00 stddev:23.22 (25.8%) stddev-sans-first:23.70 19:11:54 INFO - PROCESS | 1653 | Values: 133.0 97.0 147.0 99.0 138.0 92.0 132.0 91.0 89.0 133.0 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:99.00 Median:89.00 stddev:22.60 (25.4%) stddev-sans-first:23.92 19:11:54 INFO - PROCESS | 1653 | Values: 95.0 89.0 94.0 85.0 77.0 135.0 146.0 92.0 89.0 88.0 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:68.20 Median:71.00 stddev:13.05 (18.4%) stddev-sans-first:13.08 19:11:54 INFO - PROCESS | 1653 | Values: 56.0 58.0 56.0 58.0 59.0 64.0 82.0 78.0 81.0 90.0 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:74.40 Median:70.50 stddev:12.68 (18.0%) stddev-sans-first:13.35 19:11:54 INFO - PROCESS | 1653 | Values: 70.0 77.0 74.0 108.0 78.0 69.0 64.0 68.0 71.0 65.0 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:93.70 Median:85.50 stddev:9.03 (10.6%) stddev-sans-first:8.53 19:11:54 INFO - PROCESS | 1653 | Values: 82.0 92.0 97.0 85.0 84.0 86.0 99.0 102.0 106.0 104.0 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:143.50 stddev:15.56 (10.8%) stddev-sans-first:16.27 19:11:54 INFO - PROCESS | 1653 | Values: 140.0 141.0 139.0 140.0 143.0 145.0 143.0 147.0 191.0 144.0 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:169.50 stddev:22.28 (13.1%) stddev-sans-first:23.38 19:11:54 INFO - PROCESS | 1653 | Values: 166.0 170.0 170.0 165.0 238.0 175.0 168.0 165.0 169.0 166.0 19:11:54 INFO - PROCESS | 1653 | 19:11:54 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:70.50 stddev:8.77 (12.4%) stddev-sans-first:9.17 19:11:54 INFO - PROCESS | 1653 | Values: 69.0 67.0 71.0 81.0 88.0 87.0 70.0 67.0 64.0 68.0 19:11:54 INFO - PROCESS | 1653 | -------- Summary: end -------- 19:11:54 INFO - PROCESS | 1653 | 19:11:55 INFO - TEST-INFO | 1653: exit 0 19:11:55 INFO - TEST-OK | kraken | took 61771ms 19:11:55 INFO - SUITE-END | took 1179s 19:11:55 INFO - Completed test suite (00:19:39) 19:11:55 INFO - javascript benchmark 19:11:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7102.858426905676, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2870.268081639182, 3361.865525378985, 3366.5960084789845, 3376.8142752148624, 3432.9656703432966, 5655.943440565593, 5668.376478587352, 5671.6880571568545, 5740.684262365568, 5742.397048309919, 5716.456936591017, 5784.311779070465, 5799.797007104752, 5821.67980761058, 5843.18195452637, 4308.590683885026, 5156.994386094713, 5219.478052194788, 5291.68249905005, 5300.0, 5265.473452654742, 5312.096943519592, 5318.0, 5320.521153096221, 5344.118220493614, 5157.432682404932, 5171.896562068775, 5272.657277276965, 5300.072487314724, 5330.760115794798, 5233.424323324451, 5247.317848679685, 5249.107651699221, 5249.396319423258, 5272.551833094153, 5129.384473863161, 5230.163173892159, 5239.947600523984, 5265.526102650741, 5297.073012222884, 4824.879378015543, 5123.487651234846, 5806.738696758656, 5848.3274423441, 5883.676397798119, 5989.161517387569, 6090.634561926255, 6126.816195514141, 6251.562390632657, 6258.718357673915, 6108.0992441897915, 6111.166351794787, 6111.227117700271, 6165.414285642857, 6204.782832600882, 5782.537397008271, 5791.8552036199435, 5824.883502329972, 5842.415758424208, 5876.206712093813, 6205.720742566596, 6255.186825712628, 6291.842703932347, 6301.0, 6302.684865756785, 5975.790847320323, 6038.547108966845, 6057.485113765291, 6058.091286307089, 6093.238345206849, 6186.690665466709, 6189.705794235746, 6231.189945307081, 6255.12428260044, 6258.718357673915, 6194.256689197326, 6196.752129914753, 6201.720922558496, 6233.563650544509, 6243.219597550306, 5253.212018197301, 6080.905437021379, 6097.603655762361, 6122.938770612237, 6174.753009879644, 4761.642876784185, 4826.203676393426, 4837.927431088537, 4840.636952228527, 4888.780004899788, 4280.5505421930875, 4297.183535128315, 4301.870943871689, 4330.0906809569715, 4363.345498175299, 4599.701019433726, 5190.987757387274, 5477.917831232535, 5579.0, 5730.19777231188, 5728.226689396878, 6073.18012068374, 6117.15760190703, 6133.334663919082, 6143.078538219303, 4750.216214324667, 5623.550115990793, 5875.941240587625, 5898.852528686817, 5899.056151015902, 5296.3909150447425, 5354.65194762347, 5359.08895487768, 5369.436209197978, 5372.785088596491, 6140.412224875342, 6213.068039794067, 6281.528885333527, 6335.366463353628, 6386.904196436964, 5307.442718514655, 6126.387361263838, 6188.164597779242, 6205.596636218631, 6231.657258850807, 5219.321488206509, 6212.943799554177, 6215.689215539295, 6267.655278959701, 6271.529635277281, 5072.898542029065, 6187.628742275478, 6204.627722336674, 6208.348123447154, 6246.906791311593, 5256.9211461827335, 5751.677114263826, 6220.284667263322, 6221.035739460391, 6252.343503932204, 5815.069588865761, 5851.21008663842, 5877.970610146922, 5937.673427961504, 6061.484773794277, 5895.380984996514, 5906.173135760911, 5920.467157955804, 5946.375630558729, 5968.403159683997, 5680.744366503434, 5697.116946873241, 5761.827145185651, 5764.769409223584, 5766.221560089502, 5923.940760592339, 5956.463918247378, 5957.582969192115, 5961.314448838439, 5976.641401515923, 5788.0739081746715, 5790.94209057904, 5808.477237048686, 5815.3603103657715, 5815.680137592304], "value": 5602.647213560272, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.7707477485583, 512.6411511941641, 517.5523172455825, 519.2419068160485, 546.4317110205386, 37938.810305948464, 40501.59498405015, 40575.39136912945, 40607.59392406075, 40628.79685601575, 32877.178070548245, 41942.16115677685, 41967.790161049226, 41992.79003604978, 42107.368389474104, 9212.907870921306, 9410.952945235264, 9420.858687119699, 9439.056094390575, 9457.479838608873, 9325.673601423918, 9442.952785236064, 9454.432734035945, 9455.196308313802, 9463.1483166515, 9457.101575350365, 9471.242300615968, 9478.146966772989, 9482.905170948272, 9490.952545237264, 9363.0, 9387.10822471864, 9465.763355916122, 9473.242140628734, 9481.241500679998, 9294.395864268869, 9394.107559781809, 9395.483248421335, 9401.858972115424, 9410.529473526296, 39075.60924390776, 39108.0, 39629.60370396317, 39659.20681586381, 39685.20629587421, 3260.608726952757, 3346.7155291800227, 3383.813890236036, 3861.864834730771, 3961.0295477607883, 23604.763952360427, 29956.400871982656, 30979.380412391627, 31187.220319491968, 31514.842425787952, 3006.2183832203564, 3240.54632351471, 3241.5785947826867, 3242.9960431869226, 3305.735541156702, 502558.0, 508398.9999999963, 510533.0, 511726.0, 511965.0, 61059.38940610626, 61157.694211529546, 61353.772924541256, 61464.0, 61498.38501614927, 62437.75124497485, 62975.0, 64340.0, 64375.03437448442, 64425.0, 16226.918865405598, 16437.0, 16453.753193702105, 16467.505974820775, 16477.928934619213, 12630.494780208688, 12671.429785659668, 12714.427850746739, 12732.681682958, 12738.872611273953, 17100.0594967278, 17208.31166753341, 17265.82734172642, 17388.217530211172, 17437.564060898578, 3175.396674631859, 6471.579347342407, 6654.26803051664, 6790.388865002174, 6790.932090679129, 828.941974061822, 912.3167755491108, 968.6125549780004, 1007.6271779441654, 1045.8797238317538, 29950.70049299479, 30335.848320758254, 30634.84682576573, 30692.016418370662, 30721.539176912363, 919.8298314811781, 1027.198784947742, 1035.414990530348, 1116.1573012375604, 1153.4982282707049, 15661.0, 15663.68672626541, 15666.68666626661, 16428.507144785675, 16900.57748556296, 549289.0, 559507.0, 563264.0, 565583.0, 568086.0, 58048.0, 58146.0, 58600.0, 58730.706346467996, 58799.11801322983, 1384.3286006286942, 1391.839938407096, 1406.6483379155006, 1412.555045160771, 1418.6949805791805, 23690.407739806644, 28772.424551508433, 31197.376052479278, 31410.0, 31535.84232078917, 44358.447454338944, 51515.22727159021, 52145.69635759136, 52282.47717522776, 52346.73826630995, 596261.0, 599187.0, 621939.0, 622012.0, 624400.0, 26851.925922962862, 26904.180577009785, 27185.0, 27274.318142045813, 27335.863320684064, 46164.7691761539, 46332.7683361581, 46536.301955471376, 46541.30188047114, 46653.0, 38449.34731200532, 38663.806680966416, 38984.80507597444, 39794.60205397909, 40024.0, 33804.66195338015, 38182.0, 38646.6135338643, 39552.80223598979, 39617.0, 34706.0, 37555.87332380033, 37772.43341349827, 37926.0, 38548.036299092746, 28514.287142821595, 33303.00272975444, 35280.823595881855, 36739.08152296107, 37190.0, 24505.387365316008, 24674.383140420916, 24957.251282461555, 25998.87000564985, 26541.60187597226], "value": 19749.12613081997, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.64811456944045, 371.03160750441344, 376.766404829006, 378.96778773804226, 394.91595570159916, 18454.525043683683, 18988.90505547472, 19955.401337959847, 20221.393358199242, 20425.28511502098, 19842.90078549605, 20190.495237619067, 20528.07623656935, 20674.379768606927, 20731.792682073174, 5744.856378590548, 5868.17845501629, 5997.220361353018, 6126.23422072241, 6146.8463288417925, 5999.220101386813, 6027.547933904974, 6044.365341639131, 6057.454829065383, 6070.2412198475195, 5706.28671416073, 5900.763969441217, 5928.7332070056955, 5988.700564971756, 6030.517558595324, 5910.556708246877, 5963.165156878041, 5964.880702385972, 5964.910526342065, 5983.042713165873, 4988.800447982077, 5094.2103973884105, 5211.817586384458, 5213.53078222958, 5216.608754343401, 19033.238670453164, 20780.896095519427, 25009.499810003697, 25059.49881002388, 25059.872305746285, 2327.0459111764094, 2359.6106642404156, 2449.546833835728, 2480.417101981033, 2494.588392915167, 12835.614931552069, 14838.258087095603, 14921.701565968728, 15047.0, 15263.847361526354, 1814.6642871068761, 1816.8364847163687, 1874.306506592556, 1927.8554108441922, 1936.2255097960788, 114951.0, 117391.0, 117419.41290293494, 118124.0, 118442.99999999914, 9995.400275983464, 10958.671239862817, 11040.171987101, 11061.281016733887, 11247.381394023407, 13789.931050344683, 13850.722985540233, 13857.514986975408, 13869.37587808551, 13887.513937012156, 6731.1922569292, 7127.0, 7470.925290747132, 8464.365172611955, 8589.355798315151, 8578.399512034097, 8609.053004169537, 8656.6537338507, 8702.260307873776, 8726.171013753683, 6433.839154021093, 6656.70044847983, 6675.666216689243, 6675.966620166868, 6686.866262674719, 1594.4260066376096, 1847.6951303034848, 1877.755891734066, 1881.4261650196822, 1882.341180586811, 735.7351353512731, 877.3902138014147, 914.7118657622827, 949.1599934058409, 1040.0, 19142.904285478482, 20334.288299909727, 20639.8968005159, 20710.48223794387, 21615.45961350979, 929.6142101027998, 941.6657086734248, 955.3646824861528, 976.1409959235828, 1002.3785253143097, 10450.895491045145, 11509.712257193638, 11530.444322545321, 11665.125115616363, 11848.466818993165, 491481.0, 590621.0, 592493.0, 600470.0, 625562.0, 111400.88599114068, 115320.0, 120143.0, 120748.3962580199, 120910.39544802396, 428.2185012352407, 452.7532494790347, 452.8822506148425, 455.94984551699287, 456.1265177185785, 9266.721998340061, 10769.676909692722, 10995.670129896116, 11391.658250252505, 11549.0, 43784.46754363582, 52613.0, 54760.35718928438, 55372.72313638406, 56423.0, 79927.60036199781, 80908.0, 82419.5879020625, 82421.0, 83074.0, 7808.063032436144, 7809.921900781147, 7961.522308661499, 8330.958345208477, 8373.120822313569, 12113.030957523553, 12635.305058221886, 12849.87150128524, 12891.070107750165, 12896.22622642607, 10967.725806854893, 10974.0, 11105.888941110485, 11194.888051119384, 11418.771624567627, 11040.889591103985, 11210.0, 11244.0, 11298.322100673986, 11310.4910279036, 11397.829032564352, 11422.714432139262, 11480.196386252883, 11589.014933730727, 11772.411379430823, 7902.130765615892, 8089.150639182801, 8400.369972252227, 8401.915980840358, 8423.70517031901, 8320.292775113941, 10974.890251097706, 11102.222844401138, 11285.0, 11374.033207177483], "value": 9320.046606468939, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.6485713714435, 1470.6764511807403, 1644.692469486758, 1671.5904603372176, 1839.7332386803905, 7618.0, 7735.38116950644, 7863.606819659009, 8564.785880352994, 8588.0, 8073.233042860934, 8386.62260198291, 8406.369522285837, 8411.95794021029, 8494.830103397928, 2304.7580004099536, 2337.345543247893, 2341.7658234176615, 2342.824288178385, 2347.882605869708, 2316.6872472216287, 2338.0063473023984, 2367.1359953616893, 2379.298107058422, 2382.03527571333, 2303.896324665394, 2306.6770652108717, 2324.1284518305633, 2327.837051406402, 2342.6837376954063, 1899.3921944977612, 2283.4519715268216, 2284.942876428086, 2319.860808351504, 2320.060375547906, 1940.8350290225349, 2256.6953461282683, 2278.863268203913, 2304.7695230476984, 2310.595645761985, 8121.431499795017, 8154.3884208684585, 8228.30059444948, 8261.545614991173, 8266.710665126751, 5029.7736601853, 5176.5858731301405, 5181.274621552986, 5197.454267301917, 5385.030694474993, 3072.9091172633616, 3252.6259480159847, 3469.150058235723, 3493.91265218369, 3497.510348551205, 1046.7958748044134, 1047.5757318286107, 1081.3944191252924, 1096.9012788849043, 1097.1935627313917, 9232.676856310065, 10940.671779846618, 11010.669679909615, 11017.669469915836, 11086.002259796656, 4269.573042695768, 4344.304911214191, 4512.278035514302, 4607.170709272363, 4808.855734327905, 9426.198773104226, 9461.716148515556, 9487.288453366025, 9494.905050949403, 9531.237500999903, 5192.143296356059, 5207.609429292743, 5233.188855727369, 5243.056249874983, 5252.763625636855, 4290.528041915387, 4314.676399270004, 4773.498782627844, 4777.211760059551, 4799.160146974265, 3802.0, 3840.462335273064, 3912.56961734209, 3925.764454132704, 3948.58539853317, 1280.1998750780763, 1285.2673975833873, 1294.5663202827159, 1300.362822217107, 1306.2227974355244, 736.3078706016328, 864.8097418567905, 909.3589019741066, 916.1159481100636, 925.0980294213089, 5573.27547418833, 5635.915461268084, 5692.6015178937905, 5701.059325211377, 5893.941060589339, 890.0343127706382, 933.3852435286856, 940.6190492850407, 942.2085448223526, 944.9480278584743, 3389.7627166098628, 3747.868824591181, 3820.312343778091, 3900.2744784660767, 3955.4264631628253, 9664.90335096654, 10018.74953126163, 10246.59013639461, 10276.845847312296, 10302.557641930136, 9536.046395360408, 10061.849072263922, 10225.284230103822, 10253.179745620333, 10260.230482713827, 7826.608669566613, 7962.800929976797, 7968.2430169135, 7982.560959147303, 7988.80027999293, 5494.478024587658, 5552.500274975271, 5552.750126244409, 5594.832155035356, 5660.773569057192, 9371.34400591952, 9373.85939210926, 9404.341696081208, 9518.524073796421, 9542.761430964281, 9109.18017378439, 9512.619495220391, 9514.429134251966, 9541.427514349161, 9549.0, 6716.764913228014, 6919.827004324932, 7086.645667716491, 7234.891476627749, 7383.335499805044, 7677.9616101921365, 7770.45606807518, 7884.21157884207, 7902.24928631779, 7918.881216781638, 6093.0, 6110.847228819315, 6964.79105626832, 7108.147022357351, 7221.025161603116, 7068.222495525385, 7087.61018144007, 7112.644367781693, 7146.071010768567, 7171.820704482221, 6421.325760795236, 6441.484681225585, 6525.216973962965, 6526.151600291932, 6532.477401807749, 4877.780499877443, 4891.119598472309, 4916.3362946001835, 4926.211806111005, 4946.35697359341, 4089.2230476210616, 4149.27387707144, 4798.640101992295, 4895.192293271571, 4984.277279794482], "value": 4406.638328946602, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.47650410641756, 290.0688788987352, 297.7424527783467, 306.9002574163396, 312.82638135834605, 4439.979809186821, 5211.009908117455, 5313.282706834576, 5351.946480535194, 5373.623846330758, 4989.3513843200335, 5134.948650513494, 5190.377154741427, 5192.844214673556, 5194.37667479902, 3493.3537295600267, 3979.4627725257155, 3988.561258261589, 3998.720089593722, 4003.859864904729, 3488.7732297400717, 3823.7323387362894, 3988.7008474364393, 4026.4966879140106, 4029.7380670256484, 3266.0528446750413, 3942.940855887164, 3946.4017073085447, 3972.2849887020325, 3984.0836607580272, 3854.6916246700475, 3902.765834049966, 3932.2135572885536, 3945.644891959723, 3978.462907507507, 2690.4709573001314, 3212.2932954749917, 3284.638689744126, 3420.1278673938236, 3457.550518432613, 4863.416390033183, 5002.474740152268, 5088.287639730441, 5104.387473503166, 5106.234064890259, 3855.939616605428, 4469.7541635210055, 4514.6004738531165, 4516.435445569304, 4551.931721024187, 5479.876625291846, 5491.862703432406, 5498.2646258333625, 5509.889802203974, 5546.0, 3186.617605887285, 3214.791038582508, 3483.805202722269, 3770.5852356240794, 3783.4892289540835, 5005.499450054966, 5067.822626208102, 5715.599908006441, 5770.278715160605, 5793.913091303634, 3388.102152929476, 3673.3020726061964, 3804.201117765245, 3838.1214089432924, 3871.2838124946975, 3893.124047089439, 3898.0, 3903.59012303704, 3909.9638595772144, 3910.432987216838, 3788.8214276790955, 3922.37242041272, 4186.811593478301, 4491.371208030879, 4491.910161796745, 4681.95318046822, 4715.2513751567585, 5415.591946093965, 5632.352279487829, 5645.322561292589, 999.6900960702205, 1196.8264601632889, 1218.3055658274875, 1250.1560198706834, 1257.735875466144, 776.90068552998, 831.7920519870032, 859.3726579596929, 865.8398196333699, 872.5157537566712, 4425.114977004612, 4864.419063804207, 4872.3665923430435, 4877.609791216694, 4910.2880082388565, 4445.110977804452, 4755.405574303212, 5293.602979776532, 5307.575393968474, 5340.412554618989, 4204.26425375558, 5224.268602395668, 5268.9736551317765, 5272.525472707398, 5303.443138470482, 2824.971750282512, 2831.6460442444695, 2841.559558268472, 2842.658880934301, 2845.7438830505353, 4952.455229924705, 5256.6320357574605, 5427.945720542823, 5597.776088956478, 5654.773809047592, 4672.719636821802, 5233.476652334736, 5521.558275337963, 5546.528545073633, 5564.137558678412, 5106.642535022513, 5278.419373868948, 5482.517482517483, 5674.829755107354, 5715.628484148434, 4805.918668299604, 5055.924161137512, 5157.587393008625, 5397.892042159214, 5441.31319291021, 4679.859604211879, 4805.8225734695225, 4809.44691360484, 5189.481051894781, 5214.556762675215, 4752.691075080109, 4919.778609962489, 5070.8225212117395, 5586.514001269396, 5606.943930560806, 3093.706097920694, 3562.7149828014217, 3652.160003199226, 3827.961720382872, 3847.48059012041, 5715.599908006399, 5728.770849165987, 5730.369659337553, 5736.282964629421, 5767.307923049261, 5299.49654782795, 5590.580706446952, 5594.496495315441, 5596.720163991703, 5596.804111856066, 5261.868453288545, 5420.73935460751, 5530.419305972815, 5539.7784088636, 5544.313031833946, 4912.557869791736, 4924.704517728947, 5166.0, 5320.388155362183, 5333.359996800409, 4870.951290487192, 5627.593101724568, 5640.294963129609, 5640.520555752807, 5660.547156227574, 4593.655475839392, 5471.316085489314, 5484.957858006966, 5489.231507588861, 5490.196078431372], "value": 3939.029920149299, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [625.3590070178068, 692.1970514203525, 708.7306823407106, 737.5574655206873, 783.9804004899877, 14021.088629239104, 14782.630434239149, 14870.256487175639, 14872.256387180625, 14914.627134321647, 13442.865571344284, 13484.056116071879, 13486.865131348684, 13490.662733431669, 13640.727185456284, 658.3581008516682, 683.0710234081645, 809.7773112394109, 820.0364571628343, 837.7654256808104, 822.6709316273478, 835.578033093287, 838.5304229631396, 843.1273631791097, 845.1421806866036, 881.6561540998983, 884.1953822021961, 884.5090974509145, 887.6227603268617, 890.2966656341482, 841.2680967558233, 879.6085741844881, 880.5289170293862, 881.6649673124236, 886.3662481325883, 544.8038706065813, 635.8473966248114, 645.6513482719326, 660.4683229999848, 665.1917919727555, 13497.325133743274, 13584.524541640994, 13764.449422023106, 13775.173489590657, 14533.055351402125, 11565.306081635128, 13815.032947693666, 14085.436582536686, 14231.07498012626, 14366.784498232535, 11159.163062770325, 11171.441427928652, 11357.772844543062, 11460.885391146065, 11485.253458525252, 3591.982040089783, 3671.7429779915415, 3743.1390780120723, 3761.3041587306434, 3773.52830896138, 93494.59758103635, 95896.9999999993, 96406.03593964041, 96618.0, 97285.51357243239, 10596.576136954589, 10817.621383251548, 10861.511231994578, 10896.0, 11229.438528073728, 21577.244796432362, 21699.457513562287, 21710.674339884914, 21836.89081554582, 21944.780552194272, 7129.572225666477, 7179.389751871045, 7187.56874587516, 7266.491345605863, 7348.154962179417, 3923.352646813244, 4182.1008483176265, 4183.937240941388, 4248.638865696388, 4438.001449673862, 3240.756943229267, 3242.9837850810595, 3336.432806422914, 3355.6644335566248, 3379.9324013520086, 583.600233839821, 615.9168512250878, 620.7765204526366, 625.2059883947361, 631.135344577922, 545.5144921020295, 594.2720167794417, 595.2291782142137, 604.2356419129804, 617.6510271696477, 7485.176630570633, 8445.0, 8493.362997775192, 8620.482771033758, 8713.956430217935, 558.1627558662007, 584.0331117920952, 615.4860691322796, 616.3836163836164, 622.5922021076202, 3544.220271540257, 4197.8320867165585, 4216.388623649615, 4244.490661120685, 4255.723377980421, 86650.56674716587, 89750.55124724466, 94120.0587994125, 101490.0, 120030.0, 65252.67373663101, 70147.0, 72377.0, 90688.0, 95400.0, 79134.60432697799, 84633.57683211668, 85474.0, 88064.55967720249, 88955.0, 9087.727368178965, 9444.291678124306, 9507.477088760184, 9569.473678947725, 9673.081057299545, 53797.0, 55002.0, 55363.72318138384, 56029.439705602425, 56072.719636401554, 74919.0, 75105.24894751201, 77092.61453692695, 79948.20051799408, 82138.76791848008, 12050.09624278165, 12372.566960156351, 12408.069394795246, 12475.750484990067, 12518.812217816558, 13464.798028029783, 13770.65573360674, 13932.094413863066, 14153.787693184817, 14378.0, 12435.440405181607, 12718.872811271769, 13074.954003679873, 13378.331083445593, 13761.587152385444, 12714.745705086032, 12988.0, 13228.40472178735, 13238.933805330911, 13320.33398330099, 12748.553800616934, 12801.295928723637, 12807.48770049225, 12876.678083047998, 12888.291143987171, 10645.840312395165, 10822.621208257673, 11011.834822477509, 11161.0, 11456.885431145582, 8482.703105391281, 8692.0, 9559.805024371954, 10127.936566660395, 11025.228234023862], "value": 7172.8943581776575, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1569.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 103.0, 174.0, 168.0, 139.0, 119.0, 119.0, 119.0, 120.0, 118.0], "value": 129.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 144.0, 97.0, 186.0, 150.0, 146.0, 119.0, 114.0, 142.0, 148.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 200.0, 198.0, 233.0, 260.0, 200.0, 201.0, 195.0, 196.0, 201.0], "value": 207.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 60.0, 86.0, 58.0, 60.0, 60.0, 59.0, 79.0, 63.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 92.0, 73.0, 71.0, 71.0, 82.0, 69.0, 72.0, 90.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 132.0, 89.0, 88.0, 89.0, 102.0, 90.0, 88.0, 90.0, 122.0], "value": 102.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 97.0, 147.0, 99.0, 138.0, 92.0, 132.0, 91.0, 89.0, 133.0], "value": 115.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 89.0, 94.0, 85.0, 77.0, 135.0, 146.0, 92.0, 89.0, 88.0], "value": 99.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 56.0, 58.0, 59.0, 64.0, 82.0, 78.0, 81.0, 90.0], "value": 68.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 74.0, 108.0, 78.0, 69.0, 64.0, 68.0, 71.0, 65.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 92.0, 97.0, 85.0, 84.0, 86.0, 99.0, 102.0, 106.0, 104.0], "value": 93.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 139.0, 140.0, 143.0, 145.0, 143.0, 147.0, 191.0, 144.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 170.0, 170.0, 165.0, 238.0, 175.0, 168.0, 165.0, 169.0, 166.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 71.0, 81.0, 88.0, 87.0, 70.0, 67.0, 64.0, 68.0], "value": 73.2, "unit": "score"}]}]} 19:11:56 INFO - Return code: 0 19:11:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:11:56 ERROR - # TBPL SUCCESS # 19:11:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:11:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:11:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:11:56 INFO - 2017-01-09 19:11:56.095 screenresolution[1658:10574] starting screenresolution argv=screenresolution get 19:11:56 INFO - 2017-01-09 19:11:56.098 screenresolution[1658:10574] Display 0: 1600x1200x32@60 19:11:56 INFO - 2017-01-09 19:11:56.104 screenresolution[1659:10577] starting screenresolution argv=screenresolution list 19:11:56 INFO - Available Modes on Display 0 19:11:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:11:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:11:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:11:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:11:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:11:56 INFO - Intel Iris: 19:11:56 INFO - Chipset Model: Intel Iris 19:11:56 INFO - Type: GPU 19:11:56 INFO - Bus: Built-In 19:11:56 INFO - VRAM (Dynamic, Max): 1536 MB 19:11:56 INFO - Vendor: Intel (0x8086) 19:11:56 INFO - Device ID: 0x0a2e 19:11:56 INFO - Revision ID: 0x0009 19:11:56 INFO - Displays: 19:11:56 INFO - Display: 19:11:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:11:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:11:56 INFO - Display Serial Number: 1600x1200 60 19:11:56 INFO - Main Display: Yes 19:11:56 INFO - Mirror: Off 19:11:56 INFO - Online: Yes 19:11:56 INFO - Rotation: Supported 19:11:56 INFO - Return code: 0 19:11:56 INFO - Running post-action listener: _resource_record_post_action 19:11:56 INFO - [mozharness: 2017-01-10 03:11:56.346946Z] Finished run-tests step (success) 19:11:56 INFO - Running post-run listener: _resource_record_post_run 19:11:56 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 38775296; Write bytes: 661496320; Read time: 246; Write time: 1918 19:11:56 INFO - TinderboxPrint: CPU usage
54.5% 19:11:56 INFO - TinderboxPrint: I/O read bytes / time
38,775,296 / 246 19:11:56 INFO - TinderboxPrint: I/O write bytes / time
661,496,320 / 1,918 19:11:56 INFO - TinderboxPrint: CPU idle
2,203.4 (45.5%) 19:11:56 INFO - TinderboxPrint: CPU system
984.7 (20.3%) 19:11:56 INFO - TinderboxPrint: CPU user
1,657.6 (34.2%) 19:11:56 INFO - TinderboxPrint: Swap in / out
388,247,552 / 0 19:11:56 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 348488192; Write bytes: 346093568; Read time: 26896; Write time: 714 19:11:56 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 35387904; Write bytes: 308095488; Read time: 220; Write time: 1173 19:11:56 INFO - Running post-run listener: _upload_blobber_files 19:11:56 INFO - Blob upload gear active. 19:11:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:11:56 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 19:11:56 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'] 19:11:56 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 19:11:57 INFO - (blobuploader) - INFO - Open directory for files ... 19:11:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:11:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:11:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:11:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:11:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:11:58 INFO - (blobuploader) - INFO - Done attempting. 19:11:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:11:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:11:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:11:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:11:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:11:59 INFO - (blobuploader) - INFO - Done attempting. 19:11:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:11:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:11:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:12:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:12:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:12:01 INFO - (blobuploader) - INFO - Done attempting. 19:12:01 INFO - (blobuploader) - INFO - Iteration through files over. 19:12:01 INFO - Return code: 0 19:12:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:12:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:12:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0814d61e1bc5acc6547666b3be7a5b7b7e0587c0c75b68d14cc88adc4753943ca4bcc075393512755e220d5d2014683d51656969c138e7071caa76fc1eddd99b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7eaa2c50ba17aae08436932ef0e22ca75b26621cee3a94b65eccb94d15ad757bf07393f75af16fe6ee7fd1064f59d55a63cf78968aedcba55e22173dfbac91bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d3a5b0842df7174d171a3ff30448ee7915e01567156838517d3f6ea157546390ded7c4e9bdca8840dddaa90e98323700baa2c8dbe7999eab8745c99940c58b4"} 19:12:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:12:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:12:01 INFO - Contents: 19:12:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0814d61e1bc5acc6547666b3be7a5b7b7e0587c0c75b68d14cc88adc4753943ca4bcc075393512755e220d5d2014683d51656969c138e7071caa76fc1eddd99b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7eaa2c50ba17aae08436932ef0e22ca75b26621cee3a94b65eccb94d15ad757bf07393f75af16fe6ee7fd1064f59d55a63cf78968aedcba55e22173dfbac91bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d3a5b0842df7174d171a3ff30448ee7915e01567156838517d3f6ea157546390ded7c4e9bdca8840dddaa90e98323700baa2c8dbe7999eab8745c99940c58b4"} 19:12:01 INFO - Running post-run listener: copy_logs_to_upload_dir 19:12:01 INFO - Copying logs to upload dir... 19:12:01 INFO - mkdir: /builds/slave/test/build/upload/logs 19:12:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.549288 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-09 19:12:01.831798) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 19:12:01.835363) ========= 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.L230gMCPLq/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.51S0o3kzjN/Listeners TMPDIR=/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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/0814d61e1bc5acc6547666b3be7a5b7b7e0587c0c75b68d14cc88adc4753943ca4bcc075393512755e220d5d2014683d51656969c138e7071caa76fc1eddd99b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7eaa2c50ba17aae08436932ef0e22ca75b26621cee3a94b65eccb94d15ad757bf07393f75af16fe6ee7fd1064f59d55a63cf78968aedcba55e22173dfbac91bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d3a5b0842df7174d171a3ff30448ee7915e01567156838517d3f6ea157546390ded7c4e9bdca8840dddaa90e98323700baa2c8dbe7999eab8745c99940c58b4"} build_url:https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010999 build_url: 'https://queue.taskcluster.net/v1/task/PeaqoU9pQFKKQQWmD4iYvQ/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/0814d61e1bc5acc6547666b3be7a5b7b7e0587c0c75b68d14cc88adc4753943ca4bcc075393512755e220d5d2014683d51656969c138e7071caa76fc1eddd99b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7eaa2c50ba17aae08436932ef0e22ca75b26621cee3a94b65eccb94d15ad757bf07393f75af16fe6ee7fd1064f59d55a63cf78968aedcba55e22173dfbac91bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d3a5b0842df7174d171a3ff30448ee7915e01567156838517d3f6ea157546390ded7c4e9bdca8840dddaa90e98323700baa2c8dbe7999eab8745c99940c58b4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 19:12:01.874656) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 19:12:01.875021) ========= 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.L230gMCPLq/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.51S0o3kzjN/Listeners TMPDIR=/var/folders/07/z5h29ykn58v73zfg5g95jh1r00000w/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.005057 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 19:12:01.923338) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 19:12:01.923702) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 19:12:01.924013) ========= ========= Total master_lag: 0.20 =========