builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0142 starttime: 1487958342.86 results: success (0) buildid: 20170224081323 builduid: 46a63a6eaf884267ac649c7ee294e9f2 revision: 6f2117c0b9895dfeb06fa74d2dc91bff660386ce ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-24 09:45:42.859510) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-24 09:45:42.859942) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-24 09:45:42.860217) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-24 09:45:42.887367) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 09:45:42.887746) ========= 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.ZSj10jtavz/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.gzivvfPfNC/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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-02-24 09:45:42-- 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% 53.8M=0s 2017-02-24 09:45:43 (53.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.335119 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 09:45:43.247288) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 09:45:43.247634) ========= 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.ZSj10jtavz/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.gzivvfPfNC/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.027963 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 09:45:43.297715) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 09:45:43.298050) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6f2117c0b9895dfeb06fa74d2dc91bff660386ce --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 6f2117c0b9895dfeb06fa74d2dc91bff660386ce --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZSj10jtavz/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.gzivvfPfNC/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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-02-24 09:45:43,403 truncating revision to first 12 chars 2017-02-24 09:45:43,403 Setting DEBUG logging. 2017-02-24 09:45:43,403 attempt 1/10 2017-02-24 09:45:43,404 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6f2117c0b989?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-24 09:45:44,111 unpacking tar archive at: mozilla-central-6f2117c0b989/testing/mozharness/ program finished with exit code 0 elapsedTime=0.970007 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 09:45:44.292834) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-24 09:45:44.293447) ========= script_repo_revision: 6f2117c0b9895dfeb06fa74d2dc91bff660386ce ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-24 09:45:44.294084) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-24 09:45:44.294614) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-24 09:45:44.315343) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-02-24 09:45:44.315733) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZSj10jtavz/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.gzivvfPfNC/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:45:44 INFO - MultiFileLogger online at 20170224 09:45:44 in /builds/slave/test 09:45:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:45:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:45:44 INFO - {'append_to_log': False, 09:45:44 INFO - 'base_work_dir': '/builds/slave/test', 09:45:44 INFO - 'blob_upload_branch': 'Firefox', 09:45:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:45:44 INFO - 'branch': 'Firefox', 09:45:44 INFO - 'buildbot_json_path': 'buildprops.json', 09:45:44 INFO - 'config_files': ('talos/mac_config.py',), 09:45:44 INFO - 'default_actions': ('clobber', 09:45:44 INFO - 'read-buildbot-config', 09:45:44 INFO - 'download-and-extract', 09:45:44 INFO - 'populate-webroot', 09:45:44 INFO - 'create-virtualenv', 09:45:44 INFO - 'install', 09:45:44 INFO - 'run-tests'), 09:45:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:45:44 INFO - 'download_minidump_stackwalk': True, 09:45:44 INFO - 'download_symbols': 'ondemand', 09:45:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:45:44 INFO - 'tooltool.py': '/tools/tooltool.py', 09:45:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:45:44 INFO - '/tools/misc-python/virtualenv.py')}, 09:45:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:45:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:45:44 INFO - 'gecko_profile': False, 09:45:44 INFO - 'gecko_profile_interval': 0, 09:45:44 INFO - 'installer_path': 'installer.exe', 09:45:44 INFO - 'log_level': 'info', 09:45:44 INFO - 'log_name': 'talos', 09:45:44 INFO - 'log_to_console': True, 09:45:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:45:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:45:44 INFO - 'opt_config_files': (), 09:45:44 INFO - 'pip_index': False, 09:45:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:45:44 INFO - 'cmd': ('bash', 09:45:44 INFO - '-c', 09:45:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:45:44 INFO - 'enabled': True, 09:45:44 INFO - 'halt_on_failure': False, 09:45:44 INFO - 'name': 'check_screen_resolution'},), 09:45:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:45:44 INFO - 'cmd': ('bash', 09:45:44 INFO - '-c', 09:45:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:45:44 INFO - 'enabled': True, 09:45:44 INFO - 'halt_on_failure': False, 09:45:44 INFO - 'name': 'check_screen_resolution'},), 09:45:44 INFO - 'run_cmd_checks_enabled': True, 09:45:44 INFO - 'suite': 'dromaeojs-e10s', 09:45:44 INFO - 'system_bits': '32', 09:45:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:45:44 INFO - 'title': 't-yosemite-r7-0142', 09:45:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:45:44 INFO - 'use_talos_json': True, 09:45:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:45:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:45:44 INFO - 'work_dir': 'build'} 09:45:44 INFO - [mozharness: 2017-02-24 17:45:44.539210Z] Running clobber step. 09:45:44 INFO - Running pre-action listener: _resource_record_pre_action 09:45:44 INFO - Running main action method: clobber 09:45:44 INFO - rmtree: /builds/slave/test/build 09:45:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:45:47 INFO - Running post-action listener: _resource_record_post_action 09:45:47 INFO - [mozharness: 2017-02-24 17:45:47.081585Z] Finished clobber step (success) 09:45:47 INFO - [mozharness: 2017-02-24 17:45:47.081718Z] Running read-buildbot-config step. 09:45:47 INFO - Running pre-action listener: _resource_record_pre_action 09:45:47 INFO - Running main action method: read_buildbot_config 09:45:47 INFO - Using buildbot properties: 09:45:47 INFO - { 09:45:47 INFO - "project": "", 09:45:47 INFO - "product": "firefox", 09:45:47 INFO - "script_repo_revision": "production", 09:45:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:45:47 INFO - "repository": "", 09:45:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:45:47 INFO - "buildid": "20170224081323", 09:45:47 INFO - "pgo_build": "False", 09:45:47 INFO - "basedir": "/builds/slave/test", 09:45:47 INFO - "buildnumber": 2, 09:45:47 INFO - "slavename": "t-yosemite-r7-0142", 09:45:47 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:45:47 INFO - "platform": "yosemite_r7", 09:45:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:45:47 INFO - "branch": "mozilla-central", 09:45:47 INFO - "repo_path": "mozilla-central", 09:45:47 INFO - "revision": "6f2117c0b9895dfeb06fa74d2dc91bff660386ce", 09:45:47 INFO - "stage_platform": "macosx64", 09:45:47 INFO - "builduid": "46a63a6eaf884267ac649c7ee294e9f2", 09:45:47 INFO - "slavebuilddir": "test" 09:45:47 INFO - } 09:45:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 09:45:47 INFO - Running post-action listener: _resource_record_post_action 09:45:47 INFO - [mozharness: 2017-02-24 17:45:47.084954Z] Finished read-buildbot-config step (success) 09:45:47 INFO - [mozharness: 2017-02-24 17:45:47.085064Z] Running download-and-extract step. 09:45:47 INFO - Running pre-action listener: _resource_record_pre_action 09:45:47 INFO - Running main action method: download_and_extract 09:45:47 INFO - mkdir: /builds/slave/test/build/tests 09:45:47 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')]} 09:45:47 INFO - trying https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 09:45:47 INFO - Downloading https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:45:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 09:45:48 INFO - Downloaded 1199 bytes. 09:45:48 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:45:48 INFO - Using the following test package requirements: 09:45:48 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:45:48 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:45:48 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 09:45:48 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:45:48 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 09:45:48 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:45:48 INFO - u'jsshell-mac.zip'], 09:45:48 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:45:48 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 09:45:48 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:45:48 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:45:48 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 09:45:48 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:45:48 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 09:45:48 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:45:48 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 09:45:48 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:45:48 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 09:45:48 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:45:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:45:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:45:48 INFO - Fetch https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:45:49 INFO - Expected file size: 51455041 09:45:49 INFO - Obtained file size: 51455041 09:46:05 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:46:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:46:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:46:05 INFO - Fetch https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:46:07 INFO - Expected file size: 51455041 09:46:07 INFO - Obtained file size: 51455041 09:46:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 09:46:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:46:22 INFO - Fetch https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 09:46:24 INFO - Expected file size: 14404306 09:46:24 INFO - Obtained file size: 14404306 09:46: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')]} 09:46:24 INFO - trying https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:46:24 INFO - Downloading https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 09:46:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 09:46:26 INFO - Downloaded 74945035 bytes. 09:46:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:46:26 INFO - mkdir: /builds/slave/test/properties 09:46:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:46:26 INFO - Writing to file /builds/slave/test/properties/build_url 09:46:26 INFO - Contents: 09:46:26 INFO - build_url:https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:46:27 INFO - Running post-action listener: _resource_record_post_action 09:46:27 INFO - Running post-action listener: set_extra_try_arguments 09:46:27 INFO - [mozharness: 2017-02-24 17:46:27.684658Z] Finished download-and-extract step (success) 09:46:27 INFO - [mozharness: 2017-02-24 17:46:27.684807Z] Running populate-webroot step. 09:46:27 INFO - Running pre-action listener: _resource_record_pre_action 09:46:27 INFO - Running main action method: populate_webroot 09:46:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:46:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:46:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:46:27 INFO - '--tppagecycles', 09:46:27 INFO - '7'], 09:46:27 INFO - 'tests': ['tsvgm']}}, 09:46:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:46:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:46:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:46:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:46:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:46:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:46:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:46:27 INFO - 'talos_options': ['--disable-e10s'], 09:46:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:46:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:46:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:46:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:46:27 INFO - 'talos_options': ['--disable-e10s'], 09:46:27 INFO - 'tests': ['damp', 'tps']}, 09:46:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:46:27 INFO - 'tests': ['damp', 'tps']}, 09:46:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:46:27 INFO - 'tests': ['dromaeo_dom']}, 09:46:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:46:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:46:27 INFO - 'tests': ['basic_compositor_video']}, 09:46:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:46:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:46:27 INFO - 'tests': ['a11yr', 09:46:27 INFO - 'ts_paint', 09:46:27 INFO - 'tpaint', 09:46:27 INFO - 'sessionrestore', 09:46:27 INFO - 'sessionrestore_no_auto_restore', 09:46:27 INFO - 'tabpaint']}, 09:46:27 INFO - 'other-e10s': {'tests': ['a11yr', 09:46:27 INFO - 'ts_paint', 09:46:27 INFO - 'tpaint', 09:46:27 INFO - 'sessionrestore', 09:46:27 INFO - 'sessionrestore_no_auto_restore', 09:46:27 INFO - 'tabpaint']}, 09:46:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:46:27 INFO - 'ts_paint', 09:46:27 INFO - 'tpaint', 09:46:27 INFO - 'sessionrestore', 09:46:27 INFO - 'sessionrestore_no_auto_restore', 09:46:27 INFO - 'tabpaint']}, 09:46:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:46:27 INFO - 'ts_paint', 09:46:27 INFO - 'tpaint', 09:46:27 INFO - 'sessionrestore', 09:46:27 INFO - 'sessionrestore_no_auto_restore', 09:46:27 INFO - 'tabpaint']}, 09:46:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:46:27 INFO - 'tests': ['a11yr', 09:46:27 INFO - 'ts_paint', 09:46:27 INFO - 'tpaint', 09:46:27 INFO - 'sessionrestore', 09:46:27 INFO - 'sessionrestore_no_auto_restore', 09:46:27 INFO - 'tabpaint']}, 09:46:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:46:27 INFO - 'tests': ['a11yr', 09:46:27 INFO - 'ts_paint', 09:46:27 INFO - 'tpaint', 09:46:27 INFO - 'sessionrestore', 09:46:27 INFO - 'sessionrestore_no_auto_restore', 09:46:27 INFO - 'tabpaint']}, 09:46:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:46:27 INFO - 'tests': ['tsvgx', 09:46:27 INFO - 'tsvgr_opacity', 09:46:27 INFO - 'tart', 09:46:27 INFO - 'tscrollx', 09:46:27 INFO - 'cart', 09:46:27 INFO - 'tsvg_static']}, 09:46:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:46:27 INFO - 'tsvgr_opacity', 09:46:27 INFO - 'tart', 09:46:27 INFO - 'tscrollx', 09:46:27 INFO - 'cart', 09:46:27 INFO - 'tsvg_static']}, 09:46:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:46:27 INFO - 'talos_options': ['--disable-e10s'], 09:46:27 INFO - 'tests': ['tp5o']}, 09:46:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:46:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:46:27 INFO - 'talos_options': ['--disable-e10s', 09:46:27 INFO - '--xperf_path', 09:46:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:46:27 INFO - 'tests': ['tp5n']}, 09:46:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:46:27 INFO - 'talos_options': ['--xperf_path', 09:46:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:46:27 INFO - 'tests': ['tp5n']}}, 09:46:27 INFO - 'talos.zip': {'path': '', 09:46:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:46:27 INFO - Running post-action listener: _resource_record_post_action 09:46:27 INFO - [mozharness: 2017-02-24 17:46:27.703648Z] Finished populate-webroot step (success) 09:46:27 INFO - [mozharness: 2017-02-24 17:46:27.703762Z] Running create-virtualenv step. 09:46:27 INFO - Running pre-action listener: _resource_record_pre_action 09:46:27 INFO - Running main action method: create_virtualenv 09:46:27 INFO - Creating virtualenv /builds/slave/test/build/venv 09:46:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:46:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:46:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:46:27 INFO - Using real prefix '/tools/python27' 09:46:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:46:28 INFO - Installing distribute.............................................................................................................................................................................................done. 09:46:31 INFO - Installing pip.................done. 09:46:31 INFO - Return code: 0 09:46:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:46:31 INFO - Reading from file tmpfile_stdout 09:46:31 INFO - Output received: 09:46:31 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:46:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:46:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101e4eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e51768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa46bcbabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c2cd30>, '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 0x101b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c21be8>, '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.ZSj10jtavz/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.gzivvfPfNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:46:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:46:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZSj10jtavz/Render', 09:46:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:31 INFO - 'HOME': '/Users/cltbld', 09:46:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:31 INFO - 'LOGNAME': 'cltbld', 09:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:31 INFO - 'MOZ_NO_REMOTE': '1', 09:46:31 INFO - 'NO_EM_RESTART': '1', 09:46:31 INFO - 'PAGER': '/bin/cat', 09:46:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:31 INFO - 'PWD': '/builds/slave/test', 09:46:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:31 INFO - 'SHELL': '/bin/bash', 09:46:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzivvfPfNC/Listeners', 09:46:31 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:46:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:31 INFO - 'USER': 'cltbld', 09:46:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:31 INFO - 'XPC_FLAGS': '0x0', 09:46:31 INFO - 'XPC_SERVICE_NAME': '0', 09:46:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:32 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 09:46:32 INFO - Cleaning up... 09:46:32 INFO - Return code: 0 09:46:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:46:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:32 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 0x101e4eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e51768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa46bcbabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c2cd30>, '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 0x101b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c21be8>, '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.ZSj10jtavz/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.gzivvfPfNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:46:32 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 09:46:32 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 09:46:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZSj10jtavz/Render', 09:46:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:32 INFO - 'HOME': '/Users/cltbld', 09:46:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:32 INFO - 'LOGNAME': 'cltbld', 09:46:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:32 INFO - 'MOZ_NO_REMOTE': '1', 09:46:32 INFO - 'NO_EM_RESTART': '1', 09:46:32 INFO - 'PAGER': '/bin/cat', 09:46:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:32 INFO - 'PWD': '/builds/slave/test', 09:46:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:32 INFO - 'SHELL': '/bin/bash', 09:46:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzivvfPfNC/Listeners', 09:46:32 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:46:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:32 INFO - 'USER': 'cltbld', 09:46:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:32 INFO - 'XPC_FLAGS': '0x0', 09:46:32 INFO - 'XPC_SERVICE_NAME': '0', 09:46:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:32 INFO - Downloading/unpacking psutil>=3.1.1 09:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:46:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:46:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:46:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:46:35 INFO - Installing collected packages: psutil 09:46:35 INFO - Running setup.py install for psutil 09:46:35 INFO - building 'psutil._psutil_osx' extension 09:46:35 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 09:46:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:46:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:46:36 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 09:46:36 INFO - building 'psutil._psutil_posix' extension 09:46:36 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 09:46:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:46:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:46:36 INFO - ^ 09:46:36 INFO - 1 warning generated. 09:46:36 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 09:46:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:46:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:46:37 INFO - Successfully installed psutil 09:46:37 INFO - Cleaning up... 09:46:37 INFO - Return code: 0 09:46:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101e4eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e51768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa46bcbabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c2cd30>, '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 0x101b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c21be8>, '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.ZSj10jtavz/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.gzivvfPfNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:46:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:46:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZSj10jtavz/Render', 09:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:37 INFO - 'HOME': '/Users/cltbld', 09:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:37 INFO - 'LOGNAME': 'cltbld', 09:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:37 INFO - 'MOZ_NO_REMOTE': '1', 09:46:37 INFO - 'NO_EM_RESTART': '1', 09:46:37 INFO - 'PAGER': '/bin/cat', 09:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:37 INFO - 'PWD': '/builds/slave/test', 09:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:37 INFO - 'SHELL': '/bin/bash', 09:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzivvfPfNC/Listeners', 09:46:37 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:37 INFO - 'USER': 'cltbld', 09:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:37 INFO - 'XPC_FLAGS': '0x0', 09:46:37 INFO - 'XPC_SERVICE_NAME': '0', 09:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:46:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:46:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:46:40 INFO - Installing collected packages: mozsystemmonitor 09:46:40 INFO - Running setup.py install for mozsystemmonitor 09:46:40 INFO - Successfully installed mozsystemmonitor 09:46:40 INFO - Cleaning up... 09:46:40 INFO - Return code: 0 09:46:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:41 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 0x101e4eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e51768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa46bcbabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c2cd30>, '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 0x101b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c21be8>, '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.ZSj10jtavz/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.gzivvfPfNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:46:41 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 09:46:41 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 09:46:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZSj10jtavz/Render', 09:46:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:41 INFO - 'HOME': '/Users/cltbld', 09:46:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:41 INFO - 'LOGNAME': 'cltbld', 09:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:41 INFO - 'MOZ_NO_REMOTE': '1', 09:46:41 INFO - 'NO_EM_RESTART': '1', 09:46:41 INFO - 'PAGER': '/bin/cat', 09:46:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:41 INFO - 'PWD': '/builds/slave/test', 09:46:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:41 INFO - 'SHELL': '/bin/bash', 09:46:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzivvfPfNC/Listeners', 09:46:41 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:46:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:41 INFO - 'USER': 'cltbld', 09:46:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:41 INFO - 'XPC_FLAGS': '0x0', 09:46:41 INFO - 'XPC_SERVICE_NAME': '0', 09:46:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:41 INFO - Downloading/unpacking jsonschema==2.5.1 09:46:41 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 09:46:41 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 09:46:41 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 09:46:41 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 09:46:41 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 09:46:41 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 09:46:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:46:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:46:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:44 INFO - Downloading functools32-3.2.3-2.tar.gz 09:46:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:46:44 INFO - warning: no files found matching '*.txt' 09:46:44 INFO - no previously-included directories found matching 'build' 09:46:44 INFO - no previously-included directories found matching 'dist' 09:46:44 INFO - no previously-included directories found matching '.git*' 09:46:44 INFO - Installing collected packages: jsonschema, functools32 09:46:44 INFO - Running setup.py install for functools32 09:46:45 INFO - warning: no files found matching '*.txt' 09:46:45 INFO - no previously-included directories found matching 'build' 09:46:45 INFO - no previously-included directories found matching 'dist' 09:46:45 INFO - no previously-included directories found matching '.git*' 09:46:45 INFO - Successfully installed jsonschema functools32 09:46:45 INFO - Cleaning up... 09:46:45 INFO - Return code: 0 09:46:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:46:45 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')]} 09:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:45 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')]} 09:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:45 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 0x101e4eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e51768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa46bcbabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c2cd30>, '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 0x101b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c21be8>, '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.ZSj10jtavz/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.gzivvfPfNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:46:45 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 09:46:45 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 09:46:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZSj10jtavz/Render', 09:46:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:45 INFO - 'HOME': '/Users/cltbld', 09:46:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:45 INFO - 'LOGNAME': 'cltbld', 09:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:45 INFO - 'MOZ_NO_REMOTE': '1', 09:46:45 INFO - 'NO_EM_RESTART': '1', 09:46:45 INFO - 'PAGER': '/bin/cat', 09:46:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:45 INFO - 'PWD': '/builds/slave/test', 09:46:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:45 INFO - 'SHELL': '/bin/bash', 09:46:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzivvfPfNC/Listeners', 09:46:45 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:46:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:45 INFO - 'USER': 'cltbld', 09:46:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:45 INFO - 'XPC_FLAGS': '0x0', 09:46:45 INFO - 'XPC_SERVICE_NAME': '0', 09:46:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:46:45 INFO - Cleaning up... 09:46:45 INFO - Return code: 0 09:46:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:46:45 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')]} 09:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:45 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')]} 09:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:45 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 0x101e4eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e51768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa46bcbabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c2cd30>, '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 0x101b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c21be8>, '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.ZSj10jtavz/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.gzivvfPfNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:46:45 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 09:46:45 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 09:46:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZSj10jtavz/Render', 09:46:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:45 INFO - 'HOME': '/Users/cltbld', 09:46:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:45 INFO - 'LOGNAME': 'cltbld', 09:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:45 INFO - 'MOZ_NO_REMOTE': '1', 09:46:45 INFO - 'NO_EM_RESTART': '1', 09:46:45 INFO - 'PAGER': '/bin/cat', 09:46:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:45 INFO - 'PWD': '/builds/slave/test', 09:46:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:45 INFO - 'SHELL': '/bin/bash', 09:46:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzivvfPfNC/Listeners', 09:46:45 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:46:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:45 INFO - 'USER': 'cltbld', 09:46:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:45 INFO - 'XPC_FLAGS': '0x0', 09:46:45 INFO - 'XPC_SERVICE_NAME': '0', 09:46:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:45 INFO - Downloading/unpacking blobuploader==1.2.4 09:46:45 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 09:46:45 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 09:46:45 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 09:46:45 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 09:46:45 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 09:46:45 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 09:46:48 INFO - Downloading blobuploader-1.2.4.tar.gz 09:46:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:46:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:46:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:46:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:46:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:46:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:46:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:49 INFO - Downloading docopt-0.6.1.tar.gz 09:46:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:46:49 INFO - Installing collected packages: blobuploader, requests, docopt 09:46:49 INFO - Running setup.py install for blobuploader 09:46:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:46:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:46:49 INFO - Running setup.py install for requests 09:46:50 INFO - Running setup.py install for docopt 09:46:50 INFO - Successfully installed blobuploader requests docopt 09:46:50 INFO - Cleaning up... 09:46:50 INFO - Return code: 0 09:46:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:46: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')]} 09:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46: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')]} 09:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:50 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 0x101e4eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e51768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa46bcbabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c2cd30>, '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 0x101b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c21be8>, '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.ZSj10jtavz/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.gzivvfPfNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:46:50 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 09:46:50 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 09:46:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZSj10jtavz/Render', 09:46:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:50 INFO - 'HOME': '/Users/cltbld', 09:46:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:50 INFO - 'LOGNAME': 'cltbld', 09:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:50 INFO - 'MOZ_NO_REMOTE': '1', 09:46:50 INFO - 'NO_EM_RESTART': '1', 09:46:50 INFO - 'PAGER': '/bin/cat', 09:46:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:50 INFO - 'PWD': '/builds/slave/test', 09:46:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:50 INFO - 'SHELL': '/bin/bash', 09:46:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzivvfPfNC/Listeners', 09:46:50 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:46:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:50 INFO - 'USER': 'cltbld', 09:46:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:50 INFO - 'XPC_FLAGS': '0x0', 09:46:50 INFO - 'XPC_SERVICE_NAME': '0', 09:46:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:46:50 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-gUjpkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:46:51 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-uiD7sY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:46:51 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-xmwqXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:46:51 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-wicvxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:46:51 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-JFTSae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:46:51 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-KQgQvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:46:51 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-2hlGK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:46:51 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-evPWRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:46:51 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-VvrHgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:46:51 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-SuMdC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:46:51 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-zZ61Qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:46:52 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-h3Qm6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:46:52 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Xr3WlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:46:52 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-dHqS6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:46:52 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-cerLT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:46:52 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-YHyIVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:46:52 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-n3aLlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:46:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:46:52 INFO - Running setup.py install for manifestparser 09:46:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:46:52 INFO - Running setup.py install for mozcrash 09:46:52 INFO - Running setup.py install for mozdebug 09:46:53 INFO - Running setup.py install for mozdevice 09:46:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:46:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:46:53 INFO - Running setup.py install for mozfile 09:46:53 INFO - Running setup.py install for mozhttpd 09:46:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:46:53 INFO - Running setup.py install for mozinfo 09:46:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:46:53 INFO - Running setup.py install for mozInstall 09:46:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:46:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:46:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:46:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:46:53 INFO - Running setup.py install for mozleak 09:46:53 INFO - Running setup.py install for mozlog 09:46:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:46:54 INFO - Running setup.py install for moznetwork 09:46:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:46:54 INFO - Running setup.py install for mozprocess 09:46:54 INFO - Running setup.py install for mozprofile 09:46:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:46:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:46:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:46:54 INFO - Running setup.py install for mozrunner 09:46:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:46:54 INFO - Running setup.py install for mozscreenshot 09:46:54 INFO - Running setup.py install for moztest 09:46:55 INFO - Running setup.py install for mozversion 09:46:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:46:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:46:55 INFO - Cleaning up... 09:46:55 INFO - Return code: 0 09:46:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:55 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 0x101e4eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e51768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa46bcbabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c2cd30>, '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 0x101b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c21be8>, '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.ZSj10jtavz/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.gzivvfPfNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:46:55 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 09:46:55 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 09:46:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZSj10jtavz/Render', 09:46:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:55 INFO - 'HOME': '/Users/cltbld', 09:46:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:55 INFO - 'LOGNAME': 'cltbld', 09:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:55 INFO - 'MOZ_NO_REMOTE': '1', 09:46:55 INFO - 'NO_EM_RESTART': '1', 09:46:55 INFO - 'PAGER': '/bin/cat', 09:46:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:55 INFO - 'PWD': '/builds/slave/test', 09:46:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:55 INFO - 'SHELL': '/bin/bash', 09:46:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzivvfPfNC/Listeners', 09:46:55 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:46:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:55 INFO - 'USER': 'cltbld', 09:46:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:55 INFO - 'XPC_FLAGS': '0x0', 09:46:55 INFO - 'XPC_SERVICE_NAME': '0', 09:46:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:46:55 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-wh05CC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:46:55 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)) 09:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:46:55 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-y3Fu1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:46:55 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)) 09:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:46:55 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-6inxZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:46:55 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)) 09:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:46:55 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-G0DjoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:46:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 09:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:46:55 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-KLhwbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:46:56 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)) 09:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:46:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-1fsAo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:46:56 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)) 09:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:46:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-DrJtWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:46:56 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)) 09:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:46:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-mDkH3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:46:56 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)) 09:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:46:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-gdZGgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:46:56 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)) 09:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:46:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-qJVGuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:46:56 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)) 09:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:46:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-5KzanP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:46:56 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)) 09:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:46:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-TxnZqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 09:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:46:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-S0zPFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:46:56 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)) 09:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:46:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-ZvrrgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:46:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-hhMsrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:46:57 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)) 09:46:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:46:57 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-BmXqBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:46:57 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)) 09:46:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:46:57 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-LzIXlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:46:57 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)) 09:46:57 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)) 09:46:57 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)) 09:46:57 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)) 09:46:57 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:46:57 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:46:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:46:57 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 09:46:57 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 09:46:57 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 09:46:57 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 09:46:57 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 09:46:57 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 09:46:59 INFO - Downloading blessings-1.6.tar.gz 09:46:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:46:59 INFO - Installing collected packages: blessings 09:46:59 INFO - Running setup.py install for blessings 09:47:00 INFO - Successfully installed blessings 09:47:00 INFO - Cleaning up... 09:47:00 INFO - Return code: 0 09:47:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:47:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:00 INFO - Reading from file tmpfile_stdout 09:47:00 INFO - Current package versions: 09:47:00 INFO - blessings == 1.6 09:47:00 INFO - blobuploader == 1.2.4 09:47:00 INFO - docopt == 0.6.1 09:47:00 INFO - functools32 == 3.2.3-2 09:47:00 INFO - jsonschema == 2.5.1 09:47:00 INFO - manifestparser == 1.1 09:47:00 INFO - mozInstall == 1.12 09:47:00 INFO - mozcrash == 1.0 09:47:00 INFO - mozdebug == 0.1 09:47:00 INFO - mozdevice == 0.49 09:47:00 INFO - mozfile == 1.2 09:47:00 INFO - mozhttpd == 0.7 09:47:00 INFO - mozinfo == 0.9 09:47:00 INFO - mozleak == 0.1 09:47:00 INFO - mozlog == 3.4 09:47:00 INFO - moznetwork == 0.27 09:47:00 INFO - mozprocess == 0.25 09:47:00 INFO - mozprofile == 0.28 09:47:00 INFO - mozrunner == 6.13 09:47:00 INFO - mozscreenshot == 0.1 09:47:00 INFO - mozsystemmonitor == 0.3 09:47:00 INFO - moztest == 0.8 09:47:00 INFO - mozversion == 1.4 09:47:00 INFO - psutil == 3.1.1 09:47:00 INFO - requests == 1.2.3 09:47:00 INFO - wsgiref == 0.1.2 09:47:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:00 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 0x101e4eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e51768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa46bcbabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c2cd30>, '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 0x101b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c21be8>, '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.ZSj10jtavz/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.gzivvfPfNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:47:00 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 09:47:00 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 09:47:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZSj10jtavz/Render', 09:47:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:00 INFO - 'HOME': '/Users/cltbld', 09:47:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:00 INFO - 'LOGNAME': 'cltbld', 09:47:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:00 INFO - 'MOZ_NO_REMOTE': '1', 09:47:00 INFO - 'NO_EM_RESTART': '1', 09:47:00 INFO - 'PAGER': '/bin/cat', 09:47:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:00 INFO - 'PWD': '/builds/slave/test', 09:47:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:00 INFO - 'SHELL': '/bin/bash', 09:47:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzivvfPfNC/Listeners', 09:47:00 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:47:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:00 INFO - 'USER': 'cltbld', 09:47:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:00 INFO - 'XPC_FLAGS': '0x0', 09:47:00 INFO - 'XPC_SERVICE_NAME': '0', 09:47:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:00 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)) 09:47:00 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)) 09:47:00 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)) 09:47:00 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)) 09:47:00 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)) 09:47:00 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)) 09:47:00 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)) 09:47:00 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)) 09:47:00 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)) 09:47:00 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)) 09:47:00 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)) 09:47:00 INFO - Cleaning up... 09:47:00 INFO - Return code: 0 09:47:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101e4eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e51768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa46bcbabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c2cd30>, '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 0x101b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c21be8>, '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.ZSj10jtavz/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.gzivvfPfNC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 09:47:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:47:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:47:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZSj10jtavz/Render', 09:47:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:00 INFO - 'HOME': '/Users/cltbld', 09:47:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:00 INFO - 'LOGNAME': 'cltbld', 09:47:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:00 INFO - 'MOZ_NO_REMOTE': '1', 09:47:00 INFO - 'NO_EM_RESTART': '1', 09:47:00 INFO - 'PAGER': '/bin/cat', 09:47:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:00 INFO - 'PWD': '/builds/slave/test', 09:47:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:00 INFO - 'SHELL': '/bin/bash', 09:47:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzivvfPfNC/Listeners', 09:47:00 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:47:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:00 INFO - 'USER': 'cltbld', 09:47:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:00 INFO - 'XPC_FLAGS': '0x0', 09:47:00 INFO - 'XPC_SERVICE_NAME': '0', 09:47:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:47:01 INFO - Cleaning up... 09:47:01 INFO - Return code: 0 09:47:01 INFO - Running post-action listener: _resource_record_post_action 09:47:01 INFO - Running post-action listener: _start_resource_monitoring 09:47:01 INFO - Starting resource monitoring. 09:47:01 INFO - [mozharness: 2017-02-24 17:47:01.046156Z] Finished create-virtualenv step (success) 09:47:01 INFO - [mozharness: 2017-02-24 17:47:01.047114Z] Running install step. 09:47:01 INFO - Running pre-action listener: _resource_record_pre_action 09:47:01 INFO - Running main action method: install 09:47:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:01 INFO - Reading from file tmpfile_stdout 09:47:01 INFO - Detecting whether we're running mozinstall >=1.0... 09:47:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:47:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:47:01 INFO - Reading from file tmpfile_stdout 09:47:01 INFO - Output received: 09:47:01 INFO - Usage: mozinstall [options] installer 09:47:01 INFO - Options: 09:47:01 INFO - -h, --help show this help message and exit 09:47:01 INFO - -d DEST, --destination=DEST 09:47:01 INFO - Directory to install application into. [default: 09:47:01 INFO - "/builds/slave/test"] 09:47:01 INFO - --app=APP Application being installed. [default: firefox] 09:47:01 INFO - mkdir: /builds/slave/test/build/application 09:47:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:47:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:47:22 INFO - Reading from file tmpfile_stdout 09:47:22 INFO - Output received: 09:47:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:47:22 INFO - Running post-action listener: _resource_record_post_action 09:47:22 INFO - [mozharness: 2017-02-24 17:47:22.205946Z] Finished install step (success) 09:47:22 INFO - [mozharness: 2017-02-24 17:47:22.206091Z] Running run-tests step. 09:47:22 INFO - Running pre-action listener: _resource_record_pre_action 09:47:22 INFO - Running main action method: run_tests 09:47:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:47:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:47:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:47:22 INFO - 2017-02-24 09:47:22.221 screenresolution[1623:7378] starting screenresolution argv=screenresolution get 09:47:22 INFO - 2017-02-24 09:47:22.223 screenresolution[1623:7378] Display 0: 1600x1200x32@60 09:47:22 INFO - 2017-02-24 09:47:22.229 screenresolution[1624:7380] starting screenresolution argv=screenresolution list 09:47:22 INFO - Available Modes on Display 0 09:47:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:47:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:47:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:47:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:47:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:47:22 INFO - Intel Iris: 09:47:22 INFO - Chipset Model: Intel Iris 09:47:22 INFO - Type: GPU 09:47:22 INFO - Bus: Built-In 09:47:22 INFO - VRAM (Dynamic, Max): 1536 MB 09:47:22 INFO - Vendor: Intel (0x8086) 09:47:22 INFO - Device ID: 0x0a2e 09:47:22 INFO - Revision ID: 0x0009 09:47:22 INFO - Displays: 09:47:22 INFO - Display: 09:47:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:47:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:47:22 INFO - Display Serial Number: 1600x1200 60 09:47:22 INFO - Main Display: Yes 09:47:22 INFO - Mirror: Off 09:47:22 INFO - Online: Yes 09:47:22 INFO - Rotation: Supported 09:47:22 INFO - Return code: 0 09:47:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:47:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:47:22 INFO - Python 2.7.3 09:47:22 INFO - Return code: 0 09:47:22 INFO - grabbing minidump binary from tooltool 09:47:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:22 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 0x101c2cd30>, '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 0x101b53db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c21be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:47:22 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 09:47:22 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 09:47:22 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 09:47:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:47:22 INFO - Return code: 0 09:47:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:47:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:47:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:47:22 INFO - ENV: RUST_BACKTRACE is now 1 09:47:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:47:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:47:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--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 09:47:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0142 --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 09:47:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZSj10jtavz/Render', 09:47:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:22 INFO - 'HOME': '/Users/cltbld', 09:47:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:22 INFO - 'LOGNAME': 'cltbld', 09:47:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:47:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:47:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:22 INFO - 'MOZ_NO_REMOTE': '1', 09:47:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:47:22 INFO - 'NO_EM_RESTART': '1', 09:47:22 INFO - 'PAGER': '/bin/cat', 09:47:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:22 INFO - 'PWD': '/builds/slave/test', 09:47:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:47:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:22 INFO - 'RUST_BACKTRACE': '1', 09:47:22 INFO - 'SHELL': '/bin/bash', 09:47:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gzivvfPfNC/Listeners', 09:47:22 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 09:47:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:22 INFO - 'USER': 'cltbld', 09:47:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:22 INFO - 'XPC_FLAGS': '0x0', 09:47:22 INFO - 'XPC_SERVICE_NAME': '0', 09:47:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--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 09:47:22 INFO - mozversion application_buildid: 20170224081323 09:47:22 INFO - mozversion application_changeset: 6f2117c0b9895dfeb06fa74d2dc91bff660386ce 09:47:22 INFO - mozversion application_display_name: Nightly 09:47:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:47:22 INFO - mozversion application_name: Firefox 09:47:22 INFO - mozversion application_remotingname: firefox 09:47:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:47:22 INFO - mozversion application_vendor: Mozilla 09:47:22 INFO - mozversion application_version: 54.0a1 09:47:22 INFO - mozversion platform_buildid: 20170224081323 09:47:22 INFO - mozversion platform_changeset: 6f2117c0b9895dfeb06fa74d2dc91bff660386ce 09:47:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:47:22 INFO - mozversion platform_version: 54.0a1 09:47:22 INFO - using testdate: 1487958442 09:47:22 INFO - actual date: 1487958442 09:47:22 INFO - starting webserver on 'localhost:49240' 09:47:22 INFO - SUITE-START | Running 2 tests 09:47:22 INFO - TEST-START | dromaeo_css 09:47:22 INFO - operating with platform_type : mac_ 09:47:22 INFO - Initialising browser for dromaeo_css test... 09:47:22 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpt3UhyG/profile http://localhost:49240/getInfo.html) 09:47:25 INFO - PROCESS | 1634 | 2017-02-24 09:47:25.892 plugin-container[1636:7557] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f37, name = 'com.apple.tsm.portname' 09:47:25 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:25 INFO - PROCESS | 1634 | 2017-02-24 09:47:25.895 plugin-container[1636:7557] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x911b, name = 'com.apple.CFPasteboardClient' 09:47:25 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:25 INFO - PROCESS | 1634 | 2017-02-24 09:47:25.895 plugin-container[1636:7557] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:47:25 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 09:47:25 INFO - PROCESS | 1634 | colorDepth:24 09:47:25 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 09:47:25 INFO - PROCESS | 1634 | __metrics 09:47:26 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 09:47:26 INFO - TEST-INFO | 1634: exit 0 09:47:26 INFO - Browser initialized. 09:47:26 INFO - Running cycle 1/1 for dromaeo_css test... 09:47:26 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpt3UhyG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:47:29 INFO - PROCESS | 1637 | 2017-02-24 09:47:29.181 plugin-container[1638:7691] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:47:29 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:29 INFO - PROCESS | 1637 | 2017-02-24 09:47:29.182 plugin-container[1638:7691] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 09:47:29 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:29 INFO - PROCESS | 1637 | 2017-02-24 09:47:29.182 plugin-container[1638:7691] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:47:38 INFO - PROCESS | 1637 | RSS: Main: 227270656 09:47:38 INFO - PROCESS | 1637 | 09:50:41 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 09:50:44 INFO - PROCESS | 1637 | RSS: Main: 248766464 09:50:44 INFO - PROCESS | 1637 | 09:52:27 INFO - PROCESS | 1637 | ************************* 09:52:27 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 09:52:27 INFO - PROCESS | 1637 | 09:52:27 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:52:27 INFO - PROCESS | 1637 | Full stack: 09:52:27 INFO - PROCESS | 1637 | ************************* 09:53:46 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 09:53:47 INFO - PROCESS | 1637 | RSS: Main: 255569920 09:53:47 INFO - PROCESS | 1637 | 09:56:49 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 09:56:50 INFO - PROCESS | 1637 | RSS: Main: 267845632 09:56:50 INFO - PROCESS | 1637 | 09:59:52 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 09:59:52 INFO - PROCESS | 1637 | RSS: Main: 260792320 09:59:52 INFO - PROCESS | 1637 | 10:02:55 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 10:02:56 INFO - PROCESS | 1637 | RSS: Main: 270823424 10:02:56 INFO - PROCESS | 1637 | 10:05:59 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 10:05:59 INFO - PROCESS | 1637 | RSS: Main: 264650752 10:05:59 INFO - PROCESS | 1637 | 10:05:59 INFO - PROCESS | 1637 | __start_tp_report 10:05:59 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 10:05:59 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 10:05:59 INFO - PROCESS | 1637 | |i|pagename|runs| 10:05:59 INFO - PROCESS | 1637 | |0;dojo.html;2676.2640273924667;3244.6430892601816;3332.616749073857;3376.1559610097474;3389.3051924355495;4563.3839431676715;5431.728413579325;5519.365272993597;5538.252335934648;5667.149927510875;5193.792248310073;5198.064348417283;5443;5475.904819036189;5504.944950550494;5008.524190201934;5036.697798132105;5139.640225184238;5188.896222075557;5211.45279745627;5069.391672999245;5149.768260428271;5189.870253243661;5193.16909294513;5205.505476979681;5054.166062599685;5160.741962901859;5173.741312934339;5196.922046169291;5217.634765566412;5147.510986456281;5158.561522270612;5203.375594928614;5205.193195054772;5253.737313134367;4970.980948905466;4974.9751251243515;4980.427250866161;5048.747562621854;5104.61715371345;4567.543245675439;5465.781368745245;5561.805336813232;5576.9163462548095;5619.297587801525;5397.595180361449;6166.630002199882;6167.136600875881;6187.752489900398;6217;6114.449699527064;6135.87728245437;6143.266246520256;6245.968770156165;6252.155958945529;5664.40523745005;5673.631213971079;5711.685857277889;5738.282714660668;5781.88436231273;6236.095766113888;6255.812325630239;6284.088807122942;6295.716692748837;6310.337414571496;5793.102069179284;6024.668643224665;6077.301110372276;6090.908636370458;6111.877762444727;6042.6978651067275;6076.635401875901;6098.908516372258;6115.970584430425;6137.815865524041;6112.108359148532;6131.132004888128;6136.325004249529;6143.788236234952;6154.846128846814;5116.9744151279;5891.3814049525035;5910.290765108214;6079.300880398722;6151.984922487829;4766.642501812378;4863.781129849165;4877.6829506081995;4889.608831293488;4914.115459217375;4401.295792673157;4403.515613282536;4411;4444.955550444519;4450.888727781767;4598.287265473857;5737.569682273846;5807.332156801937;5807.738651760681;5822.155787410887;6046.697665116727;6108.969455152695;6152.630842149485;6198.659073750897;6210.4100110489435;5845.181674565564;5863.941360586339;5867.765289388462;5900.881982360329;5919;5373.489518495737;5409.945060713123;5411.918821217685;5437.32033495813;5443.564514838803;6212.97485914819;6233.314335423191;6250.968745156245;6279.780207692709;6315.147455093504;6153.969230153821;6228.4705800007505;6253.593516421418;6259.34276900937;6297.118403423433;6276.874462510816;6297.685115744102;6298;6307.400796924285;6325.367463253638;4971.875703107306;5784.248047753933;6139.907901381573;6184.226971628546;6207.503399727921;6086.02623580225;6105.450509453993;6133.977404287468;6229.626222426669;6258.624482531062;5941.632375853997;6006.579539432372;6018.638881666939;6020.6989650516425;6068.6965651716355;4352.347147927836;5622.493975542221;6010.87978240424;6041.697915104315;6054.273487181566;4310.676699247506;5475.890482190413;5577.721113944368;5584.9947009537045;5668.603197776116;4754.928676069931;5737.881113182889;5756.3380211276235;5763.308402991668;5819.76720931158;5617.297837770278;5643.71781410936;5660.830175094754;5664.631798933056;5694.943050569607 10:05:59 INFO - PROCESS | 1637 | |1;ext.html;458.10897803771655;499.061763883898;499.49153556260075;499.8100721725745;537.7015756255278;29635.962741304058;34310.485342719876;40396.798016009954;40410.7979460103;40686;41754.58245417545;42066.579334206646;42339.36490952638;42373.7287881364;42547;7547.54714717116;7695;9255.16703496687;9518.001219743828;11401.087912966943;11344;11405.429728513582;11451.828222576669;11472.827907581393;11476.655700329004;11487.48306326213;11498.540058397653;11516.193866429354;11554.422278886064;11555.711107222345;11406.885931140665;11474.827877581843;11526.884731152706;11535.59625413115;11543.653690389217;11417.486213120346;11442.656720298404;11508.712282192928;11511.884881151165;11513.424328783527;39918.800405998074;40116.79941600273;40270.39594406086;40319;40335;3756.6994640428975;3762.811859407046;3814.618538146191;3823.674987626055;3978.8607398740905;29272.507858941248;29423;30583.694163058306;30736.692633073606;30887.073387798177;3046.7105624965593;3140.5917230760083;3190.5054716518976;3242.8702851885896;3243.2864769750854;427708.86145569384;428179;430991.3801723948;432076;434069;48530.02939941181;49805.252921206215;59167.70416147892;60258;60835.39164608386;62317.688411557654;62485.43902890131;62679.37320626827;62732.41309662511;62818.37181628217;12988.740225195443;15299.46451874179;15303.770443443358;15619.140947247783;15654.37382504687;9940.40357578533;11670.649880503599;11827.940860295643;11941.58204462857;11950.701232469259;15894.841051589567;15903.840961590467;15934.760978585331;15991.360345586047;16008.679826403639;7253.347198752138;7327.487075904635;7338.633068346561;7375.594342311224;7400.444966627417;830.5224495914873;836.6820608168857;838.1785849867165;844.607257625195;853.2576658307188;29025.56461653077;29056.854715726287;29253.7074629251;29360;29834.701652983193;929.3540989012571;999.5052449037773;1022.8210063238903;1059.9453543724044;1063.8563793887881;14646.853531464762;15040.32318545668;15207.923960380127;15485.303161357766;15589.844101559067;543610;546783;547902;549411;549738;59106.70446647739;59399;59418.10872836911;59478.10782838261;59571.40428595659;1203.2179083595734;1327.920324780497;1403.78943158525;1415.1367665723901;1419.0492370112256;23053.88473057624;23700.52598948046;28778.42443151167;28804;29464.705352946195;47464.52535474601;48046.5195348056;48379.51620483891;48550.5144948546;48672.75663621659;463509;463957.6802115968;465684;466946;467150;30514.694853051184;30830.537541937338;31492.842535787175;31502.054938351885;38131.046723831016;45488.31767523556;45587;46108.30837537372;46338.76830615825;47174;36762.080947975446;37094;37654.62345376511;38292.80853595714;38318;36623.98568078675;37059.25881482301;37116.6288337124;38006.42990355092;38720;36505.63494365022;36608;37275.44086838645;37300.880973570835;37457.625423746504;27232.863835680695;32045.557949891845;33506.32987340288;33522;33677.821276256196;22324.21865234709;23955.921983511133;24166.75833241645;24452.877735611208;24684.876575617007 10:05:59 INFO - PROCESS | 1637 | |2;jquery.html;345.9982700086499;356.7021537016591;380.47684433903385;394.10144869697103;401.06551734458714;19635.01824908754;20918.790812091873;21554.56890862182;21555.568888622238;21748.891255543702;21178.57642847142;21615.3515394538;21891.12435502582;21905.452363690874;22196.778032219714;5749.798757043504;5854.853628659285;5940.70296485174;6023.849403764897;6025.367336429677;5497.725113744316;5850.210221620069;5902.675352855591;5917.911231331534;6211.192544969148;6214.782482613109;6216.720247588869;6228.968855155718;6235.345288744685;6245.687715614224;5026.447090820006;5507.917381239285;5872.412758724115;6236;6247.875042499125;4239.448871646666;6121.571489995702;6137.877242455171;6143.232095988002;6155.661438620874;12931.806022909665;13632.318384080756;13658.180509169382;13687;14937.775933361008;2152.214441728773;2495.1017633651873;2545.338212064858;2560.50070236304;2972.7027297270315;9310.208632266265;9339.019402962658;9739.853902191473;10044.799104017879;10048.79902401948;1761.9647607047787;1806.8735188536807;1808.4303444414968;1818.0909545227387;1881.5390229393886;90987;119721;124090;124666.37666811698;125186;10283.485825708685;10494.632687855888;10885.129189664807;10933.453327333602;11861.229020113666;13245.933770331087;13305.135166214164;13320.800187997187;13350.933245333905;13354.599362019155;7569.772906812805;8012.679492820222;8191.344692424592;8227.670893164206;8322.334213262924;8156.877646835303;8159.592020398956;8182.099969003405;8348.499090054616;8389.748307550783;4125.463689720317;4219.240536703362;4225.767582782915;4275.807588658456;4283.807228674655;2052.7331446911808;2077.5325551750734;2087.9478013049797;2103.2323202031453;2105.315772373954;967.7000129959735;972.1736523954582;973.6154219083564;978.1001478639669;1014.3508154780947;20706.275280365415;20756.377308680763;20773.89613051925;20848.478788030116;20854.687179692315;949.515746969037;958.1807554540907;984.886738025122;998.0767789794411;998.4159266828987;10006.649767258112;10172.542235599267;10352.068313851789;10816.296940698829;10822.837657434988;681488;694464;707798;711038;729438;111103.44448277706;125996;144081;149425;150985.73521397187;942.3450701762209;1040.625374865048;1057.381431862369;1101.0420933787657;1101.7245688577855;18409.815901841346;18882.71675924835;19732.80267197367;19871.40385788429;19893.60212795765;42495;59328.70335648294;59449.811003780545;60269;60371.09443358441;86289;87068;87593;88503.55748221217;89099.87201023773;7973.681052757825;8107.918920810716;8324;8405.411621186458;8415;10325.63860264887;10580.682579522627;12196.634100976986;12558.811617825922;13180.406881690538;9832;10634.149268058381;10706.903474290964;10725.463726813785;10904.836427453436;10607.310524815863;10713.410762408143;10843.783124337628;10918.6724398265;10976.23166378346;10693.57225710994;11060.391678457761;11070.003699667068;11076.446177690923;11116.66500049998;6772.288909664414;8105.8378832424205;8271.503709777433;8306.377021723274;8320.875186872081;9212.263018958602;10694.73263168427;10789.892101078889;10893.56425742993;10900.127989760642 10:05:59 INFO - PROCESS | 1637 | |3;mootools.html;1419.1768774111017;1433.3406632948843;1437.6055226430358;1440.5030264558727;1455.1123814473174;7847.686092556298;8161.55111468869;8233.300169485594;8242.34061275098;8325.209105135018;8335.374846886492;8421.578921053953;8454.619542120605;8481.533515656653;8500.574971251428;1926.5857840564277;2256.815172034687;2283.748787633367;2301.827362947777;2307.6423154411095;2254.368776742515;2299.620562607176;2301.7007788987494;2305.2162264829954;2309.145616122037;2072.8652637578507;2098.8771007510986;2260.242818655752;2285.462916214688;2288.9542209155807;2166.382580964433;2242.2264318809985;2253.0874995626796;2253.188852013274;2266.705328307318;1902.9809701902943;1989.7711763147279;2252.526969336425;2254.481469262079;2288.439332363582;7949.046114466301;8162.673493060271;8188.058373287088;8218.260356567878;8264.628091735887;5052.46949070346;5193.740312984336;5229.346331708536;5229.921551176735;5234.659747116425;3339.448990916496;3351.413502637053;3367.326534693071;3446.1556918554866;3477.565304336958;1178.7465694875643;1191.1007189571903;1211.5517258614368;1227.3863068465766;1236.8330275412793;8620.224179823846;10633.680989570403;10649.25455218135;10796.838047429295;10804.5678172873;3627.510286111394;3692.1692619160476;4065.9593404066172;4396.07682386696;4442.267025940748;8500.70247541333;8576.013758417737;8595.31237501011;8616.526091064927;8618;4723.149833030087;5027.84916452507;5028.296038554606;5034.496550345009;5045.520675535819;4438.423005009393;4528.0943811237885;4691.9530804692195;4703.882402939954;4713.269443236304;2972.4946759051013;3542.8405721742583;3558.3594952908206;3611.0972256935765;3630.582483014487;998.8002399520125;1005.5575546759402;1009.4902074452501;1012.9797404052025;1016.7508960304625;749.9137599176056;853.88899443072;868.769776009358;873.5282947208564;932.9440233586007;5276.868078297996;5319.521243088063;5566.916496252559;5574.219609254707;5722.856928576819;528.2525226804106;569.866081470854;907.9591418386188;965.2567523007245;987.782687808681;3532.8586856525453;3576.946345804815;3826.4260360945523;3857.8263978121054;3881.0491429599365;9320.440773553608;9444.574994125282;9742.415455072576;9757.268204884662;9904.455254960902;9826.65606703762;9829.262805289633;9839.163671087894;9848.310618256653;9875.259355548362;7681.654325555363;7836.412269079728;7877.409194310335;7877.527348359117;7881.290683838597;5476.644018138808;5580.302462192226;5793.507551857971;5800.1589769484035;5835.183074369676;8878.689245876363;9245.815083698422;9253.352265341362;9254.074592540692;9264.166225039779;7027.648617569203;9065.954670226607;9185.908140918506;9255.86116208244;9324.020977797234;5513.61404701667;7210.315020073084;7252.311030452099;7254.383377412979;7260.128784545888;7833.060032796101;7884.093329266978;7888.3689304856625;7891.210878912063;7910.288074073361;6955.895661565181;7023.789286321418;7090.539114957512;7129.429645628441;7143.1428228612895;6931.514793964374;6965.895511567229;6990.685419156049;7036.155661320674;7050.541714788523;6265.49876009909;6342.524310676626;6385.393387628168;6402.455791257609;6406.903896441464;4016.7297192082424;4067.064575147792;4870.31815545831;4975.253711943237;5051.898962020812;3617.35410388278;4153.605407486284;4976.427710813303;5029.270755740324;5123.180291153547 10:05:59 INFO - PROCESS | 1637 | |4;prototype.html;355.3461630599696;357.6977454051326;360.55831606282305;369.112284954684;371.52630396244786;5200.8699782505455;5253.737313134342;5293.788248470061;5306.151015837462;5381.757820898059;4661.925443177787;5181.279828090254;5253.763580638871;5288.985278391318;5318.388385335687;3560.1099725068734;3935.8425662973445;4044.9797751011206;4097.85657501986;4101.589841015897;4031.838726450953;4059.249038927793;4093.9590604093873;4094.8362065517344;4111.629953304202;3529.205928666055;3901.7463864848837;4028.9597104028876;4067.0645751477177;4094.3858421236905;3367.24237046665;3906.726529142961;3965.4250133730447;4103.733257338264;4104.281750693616;2902.490704833475;2914.9562756558666;3430.262493563871;3468.5490886184634;3489.9127521812156;4311.461067366579;4523.5024147343765;4573.725576465423;5173.198154286054;5199.922001169985;4295.2912769393015;4588.990422107131;4629.356578414648;4648.209804333272;4650.488446270908;4470.965710601701;5130.461301563357;5637.746301416446;5639.407862174454;5670.574706896958;3856.2287542491613;3861.5366156061173;3866.574676785551;3870.7290489665734;3929.960700392988;4787.712737235742;4886.75566221691;5437.861221146019;5772.365039845656;5974.8506287342725;3454.1537323355938;3687.2256826066828;3816.8931010006786;3832.616738326145;3846.2307538492414;3665.376885929398;3895.7857317847793;3937.291287568265;3955.5253369595835;3959.5842436544326;3793.715471339661;3871.7289789715014;4577.336286238543;4593.507110189202;4604.355390245369;5157.839486115594;5470.316210473691;5612.550995920316;5643.492085712305;5661.3206415229615;1154.1113342726053;1272.4401263444056;1279.6161151654653;1280.225463594512;1291.4640424223842;708.9613715906222;805.8468890910708;864.7665130414879;880.8942926848819;881.2949640287744;4122.381642753611;4401.801918913657;4678.228092364721;5120.974395128075;5129.8717532062;4637.837675681335;5160.174372100446;5348.598855085884;5581.469760372758;5586.664800112007;4424.535423780457;4606.56237657422;4764.8332308369045;4898.110115032148;5139.58883289336;2580.0711743772226;2673.5136323219203;2699.78509670649;2768.335599456156;2933.559966005116;5238.92141617876;5469.906018796257;5830.941690583125;5868.559858010666;5883.529317654577;4940.925886111711;5335.132703553725;5730.312362516524;5737.770489180386;5972.983643260212;5563.638363506278;6067.24159480065;6106.908396374147;6110.969445152746;6114.838180745644;4811;5583.994880921473;5679.914801278067;5799.217105690846;5859.619124756877;4985.853253751585;5472.181826359349;5771.422857714195;5818.359980402068;5835.7373918174635;4844.539768721966;5729.799457018984;5911.793087241926;6082.391760823883;6100.664463454375;3722.7021838253418;3809.2381523694817;3857.787821669835;3899.0229965435665;3957.821898014539;5137.974310128426;5816.9127463088935;5832.075415113088;5834.095715164156;5844.503217226584;5192.935448233178;5330.893382132258;5525.834224973257;5593.664380137205;5646.764926305271;5329.120695085423;5619.325680918316;5640.85897852557;5659.801906933402;5669.433056694297;4873.390826146731;5212.348456442945;5379.569634429315;5459.235707000944;5568.805091821767;5283.441384791573;5504.394516603091;5567;5625.46558076982;5630.18362337467;5021.372328458942;5034.874128146826;5501.284832971688;5518.392976772632;5547.722613869371 10:05:59 INFO - PROCESS | 1637 | |5;yui.html;641.9935800641993;691.5539477037311;717.0033653222023;732.1433922310894;734.1190571314424;12330.32183229922;14191.716165676693;15651.373945042213;15691;15940.760888586652;14047;14140.363683634261;14391.568252952402;14470.565883023526;14492.492762753305;876.3996662286338;889.0664801957919;892.6518657723493;896.3232759266745;897.3763234551989;871.9433236839617;893.4282059481905;897.8742975983367;900.158351940934;911.0843602179833;934.7476181431009;936.9484678342689;937.5452905340904;937.6249500199906;938.5870217104452;932.5244125495979;933.4865823796919;936.6347124621403;937.6577549194495;937.9906200937971;695.0720787748362;695.9965200174017;698.0157977252101;698.2703075286338;699.3356311504092;14622.488212912604;15135;15372;15414.691706165813;15430.768538471932;12838.844503994593;13892.652683682887;13898.374573144132;14026.662168397366;14221.360038798279;10322.690319290434;11489.655310340702;11525.07605215864;11551;11750.706232344175;3618.6743193112484;3628.782273063598;3653.598104208535;3653.598104208535;3693.759905606127;97985;99854.50072749589;100054;100269.9973000268;100449.9910001796;10648;10676.145908327315;10816.296940698829;10985.670429887115;11183.608573699881;21863.12547498114;21924.23265185711;22311;22342;22354.32937011892;7196.136463624294;7239;7332.926670733331;7346.265373462611;7488.363489103378;4062.73592216505;4164.6251837334785;4169.666426685919;4174.812133453941;4207.86387675332;3568.8572457101427;3581.086822860154;3611.530501034849;3670.5134420559716;3686.870959516458;545.3973359437844;561.6658088437373;647.7959442775605;659.1562799616498;661.1504217081027;613.982277108018;627.6014730645982;649.2760571962228;675.3280485916545;687.1410736579276;9849.606015759433;10075.59697612102;10079.5464204111;10151.543180556893;10258.487075646337;633.8845516680345;691.4122995453824;700.8668353012911;705.693023534764;726.5422783646269;4491.88770280739;4800.9106882129945;4889.65772395936;4894.926576101361;4900.534449227318;99235.50382248043;102994;103428;104750.47624761828;108183.45908270408;87105.56447217724;88837;89794;90770.36918523317;90784.1843163133;62103.378966210665;62445.68777156085;65264;68531.97202041974;71022.28977710157;9842.261830362895;10504;10573.577056917637;10601.575936962743;10630.25588208849;44855.32717009313;47615.952644025645;49557.752211238716;50318;51632;58579.70710146422;64521.354786451535;67712;68121.93451294802;68578.65710671415;12255.571055013388;12353.752924941271;12668.239905605693;12757.617271481871;12951.158174718614;11369.54521819151;14206.786898196742;14383.928080359532;15009.474668386554;15039.473618423302;12726.745465090831;12982.610521684364;13122.223943162406;13303.733925321632;13675.24786136772;12868.356582171042;13153.079284449997;13154.934225328812;13293.800592991307;14027.368768405242;12754.42605082792;12786.872131278567;13433.126846754929;13541.526046588322;13727.931360343135;10181.949090254502;10615.522301496298;10721.892781072089;10723.892761072602;11326.716832079264;8534.658613655385;8913.420627659181;9539.856902146612;10378.792424151323;10630.202734794766 10:05:59 INFO - PROCESS | 1637 | __end_tp_report 10:05:59 INFO - PROCESS | 1637 | __start_cc_report 10:05:59 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,429 10:05:59 INFO - PROCESS | 1637 | __end_cc_report 10:05:59 INFO - PROCESS | 1637 | __startTimestamp1487959559741__endTimestamp 10:05:59 INFO - PROCESS | 1637 | 10:05:59 INFO - PROCESS | 1637 | ------- Summary: start ------- 10:05:59 INFO - PROCESS | 1637 | Number of tests: 6 10:05:59 INFO - PROCESS | 1637 | 10:05:59 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5589.79 Median:5738.28 stddev:678.46 (11.8%) stddev-sans-first:640.89 10:05:59 INFO - PROCESS | 1637 | Values: 2676.3 3244.6 3332.6 3376.2 3389.3 4563.4 5431.7 5519.4 5538.3 5667.1 5193.8 5198.1 5443.0 5475.9 5504.9 5008.5 5036.7 5139.6 5188.9 5211.5 5069.4 5149.8 5189.9 5193.2 5205.5 5054.2 5160.7 5173.7 5196.9 5217.6 5147.5 5158.6 5203.4 5205.2 5253.7 4971.0 4975.0 4980.4 5048.7 5104.6 4567.5 5465.8 5561.8 5576.9 5619.3 5397.6 6166.6 6167.1 6187.8 6217.0 6114.4 6135.9 6143.3 6246.0 6252.2 5664.4 5673.6 5711.7 5738.3 5781.9 6236.1 6255.8 6284.1 6295.7 6310.3 5793.1 6024.7 6077.3 6090.9 6111.9 6042.7 6076.6 6098.9 6116.0 6137.8 6112.1 6131.1 6136.3 6143.8 6154.8 5117.0 5891.4 5910.3 6079.3 6152.0 4766.6 4863.8 4877.7 4889.6 4914.1 4401.3 4403.5 4411.0 4445.0 4450.9 4598.3 5737.6 5807.3 5807.7 5822.2 6046.7 6109.0 6152.6 6198.7 6210.4 5845.2 5863.9 5867.8 5900.9 5919.0 5373.5 5409.9 5411.9 5437.3 5443.6 6213.0 6233.3 6251.0 6279.8 6315.1 6154.0 6228.5 6253.6 6259.3 6297.1 6276.9 6297.7 6298.0 6307.4 6325.4 4971.9 5784.2 6139.9 6184.2 6207.5 6086.0 6105.5 6134.0 6229.6 6258.6 5941.6 6006.6 6018.6 6020.7 6068.7 4352.3 5622.5 6010.9 6041.7 6054.3 4310.7 5475.9 5577.7 5585.0 5668.6 4754.9 5737.9 5756.3 5763.3 5819.8 5617.3 5643.7 5660.8 5664.6 5694.9 10:05:59 INFO - PROCESS | 1637 | 10:05:59 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:62571.56 Median:37196.03 stddev:128549.49 (345.6%) stddev-sans-first:128825.52 10:05:59 INFO - PROCESS | 1637 | Values: 458.1 499.1 499.5 499.8 537.7 29636.0 34310.5 40396.8 40410.8 40686.0 41754.6 42066.6 42339.4 42373.7 42547.0 7547.5 7695.0 9255.2 9518.0 11401.1 11344.0 11405.4 11451.8 11472.8 11476.7 11487.5 11498.5 11516.2 11554.4 11555.7 11406.9 11474.8 11526.9 11535.6 11543.7 11417.5 11442.7 11508.7 11511.9 11513.4 39918.8 40116.8 40270.4 40319.0 40335.0 3756.7 3762.8 3814.6 3823.7 3978.9 29272.5 29423.0 30583.7 30736.7 30887.1 3046.7 3140.6 3190.5 3242.9 3243.3 427708.9 428179.0 430991.4 432076.0 434069.0 48530.0 49805.3 59167.7 60258.0 60835.4 62317.7 62485.4 62679.4 62732.4 62818.4 12988.7 15299.5 15303.8 15619.1 15654.4 9940.4 11670.6 11827.9 11941.6 11950.7 15894.8 15903.8 15934.8 15991.4 16008.7 7253.3 7327.5 7338.6 7375.6 7400.4 830.5 836.7 838.2 844.6 853.3 29025.6 29056.9 29253.7 29360.0 29834.7 929.4 999.5 1022.8 1059.9 1063.9 14646.9 15040.3 15207.9 15485.3 15589.8 543610.0 546783.0 547902.0 549411.0 549738.0 59106.7 59399.0 59418.1 59478.1 59571.4 1203.2 1327.9 1403.8 1415.1 1419.0 23053.9 23700.5 28778.4 28804.0 29464.7 47464.5 48046.5 48379.5 48550.5 48672.8 463509.0 463957.7 465684.0 466946.0 467150.0 30514.7 30830.5 31492.8 31502.1 38131.0 45488.3 45587.0 46108.3 46338.8 47174.0 36762.1 37094.0 37654.6 38292.8 38318.0 36624.0 37059.3 37116.6 38006.4 38720.0 36505.6 36608.0 37275.4 37300.9 37457.6 27232.9 32045.6 33506.3 33522.0 33677.8 22324.2 23955.9 24166.8 24452.9 24684.9 10:05:59 INFO - PROCESS | 1637 | 10:05:59 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:38168.09 Median:2520.22 stddev:117194.95 (4650.2%) stddev-sans-first:117489.30 10:05:59 INFO - PROCESS | 1637 | Values: 346.0 356.7 380.5 394.1 401.1 19635.0 20918.8 21554.6 21555.6 21748.9 21178.6 21615.4 21891.1 21905.5 22196.8 5749.8 5854.9 5940.7 6023.8 6025.4 5497.7 5850.2 5902.7 5917.9 6211.2 6214.8 6216.7 6229.0 6235.3 6245.7 5026.4 5507.9 5872.4 6236.0 6247.9 4239.4 6121.6 6137.9 6143.2 6155.7 12931.8 13632.3 13658.2 13687.0 14937.8 2152.2 2495.1 2545.3 2560.5 2972.7 9310.2 9339.0 9739.9 10044.8 10048.8 1762.0 1806.9 1808.4 1818.1 1881.5 90987.0 119721.0 124090.0 124666.4 125186.0 10283.5 10494.6 10885.1 10933.5 11861.2 13245.9 13305.1 13320.8 13350.9 13354.6 7569.8 8012.7 8191.3 8227.7 8322.3 8156.9 8159.6 8182.1 8348.5 8389.7 4125.5 4219.2 4225.8 4275.8 4283.8 2052.7 2077.5 2087.9 2103.2 2105.3 967.7 972.2 973.6 978.1 1014.4 20706.3 20756.4 20773.9 20848.5 20854.7 949.5 958.2 984.9 998.1 998.4 10006.6 10172.5 10352.1 10816.3 10822.8 681488.0 694464.0 707798.0 711038.0 729438.0 111103.4 125996.0 144081.0 149425.0 150985.7 942.3 1040.6 1057.4 1101.0 1101.7 18409.8 18882.7 19732.8 19871.4 19893.6 42495.0 59328.7 59449.8 60269.0 60371.1 86289.0 87068.0 87593.0 88503.6 89099.9 7973.7 8107.9 8324.0 8405.4 8415.0 10325.6 10580.7 12196.6 12558.8 13180.4 9832.0 10634.1 10706.9 10725.5 10904.8 10607.3 10713.4 10843.8 10918.7 10976.2 10693.6 11060.4 11070.0 11076.4 11116.7 6772.3 8105.8 8271.5 8306.4 8320.9 9212.3 10694.7 10789.9 10893.6 10900.1 10:05:59 INFO - PROCESS | 1637 | 10:05:59 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5285.27 Median:5398.08 stddev:2917.83 (54.1%) stddev-sans-first:2911.55 10:05:59 INFO - PROCESS | 1637 | Values: 1419.2 1433.3 1437.6 1440.5 1455.1 7847.7 8161.6 8233.3 8242.3 8325.2 8335.4 8421.6 8454.6 8481.5 8500.6 1926.6 2256.8 2283.7 2301.8 2307.6 2254.4 2299.6 2301.7 2305.2 2309.1 2072.9 2098.9 2260.2 2285.5 2289.0 2166.4 2242.2 2253.1 2253.2 2266.7 1903.0 1989.8 2252.5 2254.5 2288.4 7949.0 8162.7 8188.1 8218.3 8264.6 5052.5 5193.7 5229.3 5229.9 5234.7 3339.4 3351.4 3367.3 3446.2 3477.6 1178.7 1191.1 1211.6 1227.4 1236.8 8620.2 10633.7 10649.3 10796.8 10804.6 3627.5 3692.2 4066.0 4396.1 4442.3 8500.7 8576.0 8595.3 8616.5 8618.0 4723.1 5027.8 5028.3 5034.5 5045.5 4438.4 4528.1 4692.0 4703.9 4713.3 2972.5 3542.8 3558.4 3611.1 3630.6 998.8 1005.6 1009.5 1013.0 1016.8 749.9 853.9 868.8 873.5 932.9 5276.9 5319.5 5566.9 5574.2 5722.9 528.3 569.9 908.0 965.3 987.8 3532.9 3576.9 3826.4 3857.8 3881.0 9320.4 9444.6 9742.4 9757.3 9904.5 9826.7 9829.3 9839.2 9848.3 9875.3 7681.7 7836.4 7877.4 7877.5 7881.3 5476.6 5580.3 5793.5 5800.2 5835.2 8878.7 9245.8 9253.4 9254.1 9264.2 7027.6 9066.0 9185.9 9255.9 9324.0 5513.6 7210.3 7252.3 7254.4 7260.1 7833.1 7884.1 7888.4 7891.2 7910.3 6955.9 7023.8 7090.5 7129.4 7143.1 6931.5 6965.9 6990.7 7036.2 7050.5 6265.5 6342.5 6385.4 6402.5 6406.9 4016.7 4067.1 4870.3 4975.3 5051.9 3617.4 4153.6 4976.4 5029.3 5123.2 10:05:59 INFO - PROCESS | 1637 | 10:05:59 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4485.39 Median:5028.12 stddev:1396.49 (27.8%) stddev-sans-first:1365.57 10:05:59 INFO - PROCESS | 1637 | Values: 355.3 357.7 360.6 369.1 371.5 5200.9 5253.7 5293.8 5306.2 5381.8 4661.9 5181.3 5253.8 5289.0 5318.4 3560.1 3935.8 4045.0 4097.9 4101.6 4031.8 4059.2 4094.0 4094.8 4111.6 3529.2 3901.7 4029.0 4067.1 4094.4 3367.2 3906.7 3965.4 4103.7 4104.3 2902.5 2915.0 3430.3 3468.5 3489.9 4311.5 4523.5 4573.7 5173.2 5199.9 4295.3 4589.0 4629.4 4648.2 4650.5 4471.0 5130.5 5637.7 5639.4 5670.6 3856.2 3861.5 3866.6 3870.7 3930.0 4787.7 4886.8 5437.9 5772.4 5974.9 3454.2 3687.2 3816.9 3832.6 3846.2 3665.4 3895.8 3937.3 3955.5 3959.6 3793.7 3871.7 4577.3 4593.5 4604.4 5157.8 5470.3 5612.6 5643.5 5661.3 1154.1 1272.4 1279.6 1280.2 1291.5 709.0 805.8 864.8 880.9 881.3 4122.4 4401.8 4678.2 5121.0 5129.9 4637.8 5160.2 5348.6 5581.5 5586.7 4424.5 4606.6 4764.8 4898.1 5139.6 2580.1 2673.5 2699.8 2768.3 2933.6 5238.9 5469.9 5830.9 5868.6 5883.5 4940.9 5335.1 5730.3 5737.8 5973.0 5563.6 6067.2 6106.9 6111.0 6114.8 4811.0 5584.0 5679.9 5799.2 5859.6 4985.9 5472.2 5771.4 5818.4 5835.7 4844.5 5729.8 5911.8 6082.4 6100.7 3722.7 3809.2 3857.8 3899.0 3957.8 5138.0 5816.9 5832.1 5834.1 5844.5 5192.9 5330.9 5525.8 5593.7 5646.8 5329.1 5619.3 5640.9 5659.8 5669.4 4873.4 5212.3 5379.6 5459.2 5568.8 5283.4 5504.4 5567.0 5625.5 5630.2 5021.4 5034.9 5501.3 5518.4 5547.7 10:05:59 INFO - PROCESS | 1637 | 10:05:59 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19809.94 Median:3690.32 stddev:28284.74 (766.5%) stddev-sans-first:28327.47 10:05:59 INFO - PROCESS | 1637 | Values: 642.0 691.6 717.0 732.1 734.1 12330.3 14191.7 15651.4 15691.0 15940.8 14047.0 14140.4 14391.6 14470.6 14492.5 876.4 889.1 892.7 896.3 897.4 871.9 893.4 897.9 900.2 911.1 934.7 936.9 937.5 937.6 938.6 932.5 933.5 936.6 937.7 938.0 695.1 696.0 698.0 698.3 699.3 14622.5 15135.0 15372.0 15414.7 15430.8 12838.8 13892.7 13898.4 14026.7 14221.4 10322.7 11489.7 11525.1 11551.0 11750.7 3618.7 3628.8 3653.6 3653.6 3693.8 97985.0 99854.5 100054.0 100270.0 100450.0 10648.0 10676.1 10816.3 10985.7 11183.6 21863.1 21924.2 22311.0 22342.0 22354.3 7196.1 7239.0 7332.9 7346.3 7488.4 4062.7 4164.6 4169.7 4174.8 4207.9 3568.9 3581.1 3611.5 3670.5 3686.9 545.4 561.7 647.8 659.2 661.2 614.0 627.6 649.3 675.3 687.1 9849.6 10075.6 10079.5 10151.5 10258.5 633.9 691.4 700.9 705.7 726.5 4491.9 4800.9 4889.7 4894.9 4900.5 99235.5 102994.0 103428.0 104750.5 108183.5 87105.6 88837.0 89794.0 90770.4 90784.2 62103.4 62445.7 65264.0 68532.0 71022.3 9842.3 10504.0 10573.6 10601.6 10630.3 44855.3 47616.0 49557.8 50318.0 51632.0 58579.7 64521.4 67712.0 68121.9 68578.7 12255.6 12353.8 12668.2 12757.6 12951.2 11369.5 14206.8 14383.9 15009.5 15039.5 12726.7 12982.6 13122.2 13303.7 13675.2 12868.4 13153.1 13154.9 13293.8 14027.4 12754.4 12786.9 13433.1 13541.5 13727.9 10181.9 10615.5 10721.9 10723.9 11326.7 8534.7 8913.4 9539.9 10378.8 10630.2 10:05:59 INFO - PROCESS | 1637 | -------- Summary: end -------- 10:05:59 INFO - PROCESS | 1637 | 10:06:00 INFO - TEST-INFO | 1637: exit 0 10:06:00 INFO - TEST-OK | dromaeo_css | took 1117597ms 10:06:00 INFO - TEST-START | kraken 10:06:00 INFO - operating with platform_type : mac_ 10:06:00 INFO - Initialising browser for kraken test... 10:06:00 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpP_OsHu/profile http://localhost:49240/getInfo.html) 10:06:03 INFO - PROCESS | 1648 | 2017-02-24 10:06:03.504 plugin-container[1649:10167] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3b, name = 'com.apple.tsm.portname' 10:06:03 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:03 INFO - PROCESS | 1648 | 2017-02-24 10:06:03.507 plugin-container[1649:10167] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 10:06:03 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:03 INFO - PROCESS | 1648 | 2017-02-24 10:06:03.507 plugin-container[1649:10167] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:03 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 10:06:03 INFO - PROCESS | 1648 | colorDepth:24 10:06:03 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 10:06:03 INFO - PROCESS | 1648 | __metrics 10:06:03 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 10:06:04 INFO - TEST-INFO | 1648: exit 0 10:06:04 INFO - Browser initialized. 10:06:04 INFO - Running cycle 1/1 for kraken test... 10:06:04 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpP_OsHu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:06:07 INFO - PROCESS | 1650 | 2017-02-24 10:06:07.340 plugin-container[1651:10296] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3b, name = 'com.apple.tsm.portname' 10:06:07 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:07 INFO - PROCESS | 1650 | 2017-02-24 10:06:07.341 plugin-container[1651:10296] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1b, name = 'com.apple.CFPasteboardClient' 10:06:07 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:07 INFO - PROCESS | 1650 | 2017-02-24 10:06:07.341 plugin-container[1651:10296] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:16 INFO - PROCESS | 1650 | RSS: Main: 227569664 10:06:16 INFO - PROCESS | 1650 | 10:06:20 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 10:06:21 INFO - PROCESS | 1650 | RSS: Main: 240779264 10:06:21 INFO - PROCESS | 1650 | 10:06:24 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 10:06:24 INFO - PROCESS | 1650 | RSS: Main: 248758272 10:06:24 INFO - PROCESS | 1650 | 10:06:28 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 10:06:28 INFO - PROCESS | 1650 | RSS: Main: 251482112 10:06:28 INFO - PROCESS | 1650 | 10:06:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 10:06:31 INFO - PROCESS | 1650 | RSS: Main: 252534784 10:06:31 INFO - PROCESS | 1650 | 10:06:33 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:06:34 INFO - PROCESS | 1650 | RSS: Main: 252956672 10:06:34 INFO - PROCESS | 1650 | 10:06:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 10:06:37 INFO - PROCESS | 1650 | RSS: Main: 260251648 10:06:37 INFO - PROCESS | 1650 | 10:06:40 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 10:06:41 INFO - PROCESS | 1650 | RSS: Main: 259235840 10:06:41 INFO - PROCESS | 1650 | 10:06:44 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 10:06:45 INFO - PROCESS | 1650 | RSS: Main: 259223552 10:06:45 INFO - PROCESS | 1650 | 10:06:46 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:06:47 INFO - PROCESS | 1650 | RSS: Main: 258834432 10:06:47 INFO - PROCESS | 1650 | 10:06:49 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:06:50 INFO - PROCESS | 1650 | RSS: Main: 258863104 10:06:50 INFO - PROCESS | 1650 | 10:06:52 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:06:53 INFO - PROCESS | 1650 | RSS: Main: 258899968 10:06:53 INFO - PROCESS | 1650 | 10:06:56 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:06:56 INFO - PROCESS | 1650 | RSS: Main: 259235840 10:06:56 INFO - PROCESS | 1650 | 10:06:59 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:07:00 INFO - PROCESS | 1650 | RSS: Main: 259047424 10:07:00 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 10:07:02 INFO - PROCESS | 1650 | RSS: Main: 262189056 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | __start_tp_report 10:07:02 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 10:07:02 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 10:07:02 INFO - PROCESS | 1650 | |i|pagename|runs| 10:07:02 INFO - PROCESS | 1650 | |0;ai-astar;144;180;139;121;119;119;104;173;177;126 10:07:02 INFO - PROCESS | 1650 | |1;audio-beat-detection;147;114;149;176;107;122;139;108;160;146 10:07:02 INFO - PROCESS | 1650 | |2;audio-dft;261;276;236;202;193;202;194;204;199;195 10:07:02 INFO - PROCESS | 1650 | |3;audio-fft;84;66;85;78;81;57;66;53;75;83 10:07:02 INFO - PROCESS | 1650 | |4;audio-oscillator;86;72;90;87;104;73;86;95;103;115 10:07:02 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;120;122;136;137;93;92;94;92;93;97 10:07:02 INFO - PROCESS | 1650 | |6;imaging-darkroom;100;101;99;100;100;100;100;100;100;100 10:07:02 INFO - PROCESS | 1650 | |7;imaging-desaturate;121;144;100;93;78;146;144;96;96;98 10:07:02 INFO - PROCESS | 1650 | |8;json-parse-financial;68;66;66;68;67;68;66;69;68;66 10:07:02 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;70;74;77;80;75;70;68;71;71;69 10:07:02 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;88;90;88;88;91;91;91;88;89;102 10:07:02 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;145;152;182;154;146;149;151;159;193;147 10:07:02 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;179;179;176;179;207;244;233;176;177;174 10:07:02 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;68;70;69;66;69;70;69;69;70;69 10:07:02 INFO - PROCESS | 1650 | __end_tp_report 10:07:02 INFO - PROCESS | 1650 | __start_cc_report 10:07:02 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1004 10:07:02 INFO - PROCESS | 1650 | __end_cc_report 10:07:02 INFO - PROCESS | 1650 | __startTimestamp1487959622530__endTimestamp 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | ------- Summary: start ------- 10:07:02 INFO - PROCESS | 1650 | Number of tests: 14 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:140.20 Median:141.50 stddev:27.51 (19.4%) stddev-sans-first:29.14 10:07:02 INFO - PROCESS | 1650 | Values: 144.0 180.0 139.0 121.0 119.0 119.0 104.0 173.0 177.0 126.0 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:146.50 stddev:23.27 (15.9%) stddev-sans-first:24.39 10:07:02 INFO - PROCESS | 1650 | Values: 147.0 114.0 149.0 176.0 107.0 122.0 139.0 108.0 160.0 146.0 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:216.20 Median:203.00 stddev:30.37 (15.0%) stddev-sans-first:27.55 10:07:02 INFO - PROCESS | 1650 | Values: 261.0 276.0 236.0 202.0 193.0 202.0 194.0 204.0 199.0 195.0 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:72.80 Median:79.50 stddev:11.60 (14.6%) stddev-sans-first:11.58 10:07:02 INFO - PROCESS | 1650 | Values: 84.0 66.0 85.0 78.0 81.0 57.0 66.0 53.0 75.0 83.0 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:86.00 stddev:13.57 (15.8%) stddev-sans-first:14.27 10:07:02 INFO - PROCESS | 1650 | Values: 86.0 72.0 90.0 87.0 104.0 73.0 86.0 95.0 103.0 115.0 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:107.60 Median:92.50 stddev:18.98 (20.5%) stddev-sans-first:19.59 10:07:02 INFO - PROCESS | 1650 | Values: 120.0 122.0 136.0 137.0 93.0 92.0 94.0 92.0 93.0 97.0 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:100.00 Median:100.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 10:07:02 INFO - PROCESS | 1650 | Values: 100.0 101.0 99.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:111.60 Median:85.50 stddev:25.06 (29.3%) stddev-sans-first:26.35 10:07:02 INFO - PROCESS | 1650 | Values: 121.0 144.0 100.0 93.0 78.0 146.0 144.0 96.0 96.0 98.0 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:68.00 stddev:1.14 (1.7%) stddev-sans-first:1.17 10:07:02 INFO - PROCESS | 1650 | Values: 68.0 66.0 66.0 68.0 67.0 68.0 66.0 69.0 68.0 66.0 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:72.50 stddev:3.87 (5.3%) stddev-sans-first:3.99 10:07:02 INFO - PROCESS | 1650 | Values: 70.0 74.0 77.0 80.0 75.0 70.0 68.0 71.0 71.0 69.0 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:89.50 stddev:4.22 (4.7%) stddev-sans-first:4.37 10:07:02 INFO - PROCESS | 1650 | Values: 88.0 90.0 88.0 88.0 91.0 91.0 91.0 88.0 89.0 102.0 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:157.80 Median:153.00 stddev:16.39 (10.7%) stddev-sans-first:16.72 10:07:02 INFO - PROCESS | 1650 | Values: 145.0 152.0 182.0 154.0 146.0 149.0 151.0 159.0 193.0 147.0 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.40 Median:179.00 stddev:26.20 (14.6%) stddev-sans-first:27.33 10:07:02 INFO - PROCESS | 1650 | Values: 179.0 179.0 176.0 179.0 207.0 244.0 233.0 176.0 177.0 174.0 10:07:02 INFO - PROCESS | 1650 | 10:07:02 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:1.20 (1.7%) stddev-sans-first:1.22 10:07:02 INFO - PROCESS | 1650 | Values: 68.0 70.0 69.0 66.0 69.0 70.0 69.0 69.0 70.0 69.0 10:07:02 INFO - PROCESS | 1650 | -------- Summary: end -------- 10:07:02 INFO - PROCESS | 1650 | 10:07:03 INFO - TEST-INFO | 1650: exit 0 10:07:03 INFO - TEST-OK | kraken | took 62996ms 10:07:03 INFO - SUITE-END | took 1180s 10:07:03 INFO - Completed test suite (00:19:40) 10:07:03 INFO - javascript benchmark 10:07:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7133.822609152818, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2676.2640273924667, 3244.6430892601816, 3332.616749073857, 3376.1559610097474, 3389.3051924355495, 4563.3839431676715, 5431.728413579325, 5519.365272993597, 5538.252335934648, 5667.149927510875, 5193.792248310073, 5198.064348417283, 5443.0, 5475.904819036189, 5504.944950550494, 5008.524190201934, 5036.697798132105, 5139.640225184238, 5188.896222075557, 5211.45279745627, 5069.391672999245, 5149.768260428271, 5189.870253243661, 5193.16909294513, 5205.505476979681, 5054.166062599685, 5160.741962901859, 5173.741312934339, 5196.922046169291, 5217.634765566412, 5147.510986456281, 5158.561522270612, 5203.375594928614, 5205.193195054772, 5253.737313134367, 4970.980948905466, 4974.9751251243515, 4980.427250866161, 5048.747562621854, 5104.61715371345, 4567.543245675439, 5465.781368745245, 5561.805336813232, 5576.9163462548095, 5619.297587801525, 5397.595180361449, 6166.630002199882, 6167.136600875881, 6187.752489900398, 6217.0, 6114.449699527064, 6135.87728245437, 6143.266246520256, 6245.968770156165, 6252.155958945529, 5664.40523745005, 5673.631213971079, 5711.685857277889, 5738.282714660668, 5781.88436231273, 6236.095766113888, 6255.812325630239, 6284.088807122942, 6295.716692748837, 6310.337414571496, 5793.102069179284, 6024.668643224665, 6077.301110372276, 6090.908636370458, 6111.877762444727, 6042.6978651067275, 6076.635401875901, 6098.908516372258, 6115.970584430425, 6137.815865524041, 6112.108359148532, 6131.132004888128, 6136.325004249529, 6143.788236234952, 6154.846128846814, 5116.9744151279, 5891.3814049525035, 5910.290765108214, 6079.300880398722, 6151.984922487829, 4766.642501812378, 4863.781129849165, 4877.6829506081995, 4889.608831293488, 4914.115459217375, 4401.295792673157, 4403.515613282536, 4411.0, 4444.955550444519, 4450.888727781767, 4598.287265473857, 5737.569682273846, 5807.332156801937, 5807.738651760681, 5822.155787410887, 6046.697665116727, 6108.969455152695, 6152.630842149485, 6198.659073750897, 6210.4100110489435, 5845.181674565564, 5863.941360586339, 5867.765289388462, 5900.881982360329, 5919.0, 5373.489518495737, 5409.945060713123, 5411.918821217685, 5437.32033495813, 5443.564514838803, 6212.97485914819, 6233.314335423191, 6250.968745156245, 6279.780207692709, 6315.147455093504, 6153.969230153821, 6228.4705800007505, 6253.593516421418, 6259.34276900937, 6297.118403423433, 6276.874462510816, 6297.685115744102, 6298.0, 6307.400796924285, 6325.367463253638, 4971.875703107306, 5784.248047753933, 6139.907901381573, 6184.226971628546, 6207.503399727921, 6086.02623580225, 6105.450509453993, 6133.977404287468, 6229.626222426669, 6258.624482531062, 5941.632375853997, 6006.579539432372, 6018.638881666939, 6020.6989650516425, 6068.6965651716355, 4352.347147927836, 5622.493975542221, 6010.87978240424, 6041.697915104315, 6054.273487181566, 4310.676699247506, 5475.890482190413, 5577.721113944368, 5584.9947009537045, 5668.603197776116, 4754.928676069931, 5737.881113182889, 5756.3380211276235, 5763.308402991668, 5819.76720931158, 5617.297837770278, 5643.71781410936, 5660.830175094754, 5664.631798933056, 5694.943050569607], "value": 5547.683980878104, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.10897803771655, 499.061763883898, 499.49153556260075, 499.8100721725745, 537.7015756255278, 29635.962741304058, 34310.485342719876, 40396.798016009954, 40410.7979460103, 40686.0, 41754.58245417545, 42066.579334206646, 42339.36490952638, 42373.7287881364, 42547.0, 7547.54714717116, 7695.0, 9255.16703496687, 9518.001219743828, 11401.087912966943, 11344.0, 11405.429728513582, 11451.828222576669, 11472.827907581393, 11476.655700329004, 11487.48306326213, 11498.540058397653, 11516.193866429354, 11554.422278886064, 11555.711107222345, 11406.885931140665, 11474.827877581843, 11526.884731152706, 11535.59625413115, 11543.653690389217, 11417.486213120346, 11442.656720298404, 11508.712282192928, 11511.884881151165, 11513.424328783527, 39918.800405998074, 40116.79941600273, 40270.39594406086, 40319.0, 40335.0, 3756.6994640428975, 3762.811859407046, 3814.618538146191, 3823.674987626055, 3978.8607398740905, 29272.507858941248, 29423.0, 30583.694163058306, 30736.692633073606, 30887.073387798177, 3046.7105624965593, 3140.5917230760083, 3190.5054716518976, 3242.8702851885896, 3243.2864769750854, 427708.86145569384, 428179.0, 430991.3801723948, 432076.0, 434069.0, 48530.02939941181, 49805.252921206215, 59167.70416147892, 60258.0, 60835.39164608386, 62317.688411557654, 62485.43902890131, 62679.37320626827, 62732.41309662511, 62818.37181628217, 12988.740225195443, 15299.46451874179, 15303.770443443358, 15619.140947247783, 15654.37382504687, 9940.40357578533, 11670.649880503599, 11827.940860295643, 11941.58204462857, 11950.701232469259, 15894.841051589567, 15903.840961590467, 15934.760978585331, 15991.360345586047, 16008.679826403639, 7253.347198752138, 7327.487075904635, 7338.633068346561, 7375.594342311224, 7400.444966627417, 830.5224495914873, 836.6820608168857, 838.1785849867165, 844.607257625195, 853.2576658307188, 29025.56461653077, 29056.854715726287, 29253.7074629251, 29360.0, 29834.701652983193, 929.3540989012571, 999.5052449037773, 1022.8210063238903, 1059.9453543724044, 1063.8563793887881, 14646.853531464762, 15040.32318545668, 15207.923960380127, 15485.303161357766, 15589.844101559067, 543610.0, 546783.0, 547902.0, 549411.0, 549738.0, 59106.70446647739, 59399.0, 59418.10872836911, 59478.10782838261, 59571.40428595659, 1203.2179083595734, 1327.920324780497, 1403.78943158525, 1415.1367665723901, 1419.0492370112256, 23053.88473057624, 23700.52598948046, 28778.42443151167, 28804.0, 29464.705352946195, 47464.52535474601, 48046.5195348056, 48379.51620483891, 48550.5144948546, 48672.75663621659, 463509.0, 463957.6802115968, 465684.0, 466946.0, 467150.0, 30514.694853051184, 30830.537541937338, 31492.842535787175, 31502.054938351885, 38131.046723831016, 45488.31767523556, 45587.0, 46108.30837537372, 46338.76830615825, 47174.0, 36762.080947975446, 37094.0, 37654.62345376511, 38292.80853595714, 38318.0, 36623.98568078675, 37059.25881482301, 37116.6288337124, 38006.42990355092, 38720.0, 36505.63494365022, 36608.0, 37275.44086838645, 37300.880973570835, 37457.625423746504, 27232.863835680695, 32045.557949891845, 33506.32987340288, 33522.0, 33677.821276256196, 22324.21865234709, 23955.921983511133, 24166.75833241645, 24452.877735611208, 24684.876575617007], "value": 19599.724174966854, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.9982700086499, 356.7021537016591, 380.47684433903385, 394.10144869697103, 401.06551734458714, 19635.01824908754, 20918.790812091873, 21554.56890862182, 21555.568888622238, 21748.891255543702, 21178.57642847142, 21615.3515394538, 21891.12435502582, 21905.452363690874, 22196.778032219714, 5749.798757043504, 5854.853628659285, 5940.70296485174, 6023.849403764897, 6025.367336429677, 5497.725113744316, 5850.210221620069, 5902.675352855591, 5917.911231331534, 6211.192544969148, 6214.782482613109, 6216.720247588869, 6228.968855155718, 6235.345288744685, 6245.687715614224, 5026.447090820006, 5507.917381239285, 5872.412758724115, 6236.0, 6247.875042499125, 4239.448871646666, 6121.571489995702, 6137.877242455171, 6143.232095988002, 6155.661438620874, 12931.806022909665, 13632.318384080756, 13658.180509169382, 13687.0, 14937.775933361008, 2152.214441728773, 2495.1017633651873, 2545.338212064858, 2560.50070236304, 2972.7027297270315, 9310.208632266265, 9339.019402962658, 9739.853902191473, 10044.799104017879, 10048.79902401948, 1761.9647607047787, 1806.8735188536807, 1808.4303444414968, 1818.0909545227387, 1881.5390229393886, 90987.0, 119721.0, 124090.0, 124666.37666811698, 125186.0, 10283.485825708685, 10494.632687855888, 10885.129189664807, 10933.453327333602, 11861.229020113666, 13245.933770331087, 13305.135166214164, 13320.800187997187, 13350.933245333905, 13354.599362019155, 7569.772906812805, 8012.679492820222, 8191.344692424592, 8227.670893164206, 8322.334213262924, 8156.877646835303, 8159.592020398956, 8182.099969003405, 8348.499090054616, 8389.748307550783, 4125.463689720317, 4219.240536703362, 4225.767582782915, 4275.807588658456, 4283.807228674655, 2052.7331446911808, 2077.5325551750734, 2087.9478013049797, 2103.2323202031453, 2105.315772373954, 967.7000129959735, 972.1736523954582, 973.6154219083564, 978.1001478639669, 1014.3508154780947, 20706.275280365415, 20756.377308680763, 20773.89613051925, 20848.478788030116, 20854.687179692315, 949.515746969037, 958.1807554540907, 984.886738025122, 998.0767789794411, 998.4159266828987, 10006.649767258112, 10172.542235599267, 10352.068313851789, 10816.296940698829, 10822.837657434988, 681488.0, 694464.0, 707798.0, 711038.0, 729438.0, 111103.44448277706, 125996.0, 144081.0, 149425.0, 150985.73521397187, 942.3450701762209, 1040.625374865048, 1057.381431862369, 1101.0420933787657, 1101.7245688577855, 18409.815901841346, 18882.71675924835, 19732.80267197367, 19871.40385788429, 19893.60212795765, 42495.0, 59328.70335648294, 59449.811003780545, 60269.0, 60371.09443358441, 86289.0, 87068.0, 87593.0, 88503.55748221217, 89099.87201023773, 7973.681052757825, 8107.918920810716, 8324.0, 8405.411621186458, 8415.0, 10325.63860264887, 10580.682579522627, 12196.634100976986, 12558.811617825922, 13180.406881690538, 9832.0, 10634.149268058381, 10706.903474290964, 10725.463726813785, 10904.836427453436, 10607.310524815863, 10713.410762408143, 10843.783124337628, 10918.6724398265, 10976.23166378346, 10693.57225710994, 11060.391678457761, 11070.003699667068, 11076.446177690923, 11116.66500049998, 6772.288909664414, 8105.8378832424205, 8271.503709777433, 8306.377021723274, 8320.875186872081, 9212.263018958602, 10694.73263168427, 10789.892101078889, 10893.56425742993, 10900.127989760642], "value": 9498.043829492946, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.1768774111017, 1433.3406632948843, 1437.6055226430358, 1440.5030264558727, 1455.1123814473174, 7847.686092556298, 8161.55111468869, 8233.300169485594, 8242.34061275098, 8325.209105135018, 8335.374846886492, 8421.578921053953, 8454.619542120605, 8481.533515656653, 8500.574971251428, 1926.5857840564277, 2256.815172034687, 2283.748787633367, 2301.827362947777, 2307.6423154411095, 2254.368776742515, 2299.620562607176, 2301.7007788987494, 2305.2162264829954, 2309.145616122037, 2072.8652637578507, 2098.8771007510986, 2260.242818655752, 2285.462916214688, 2288.9542209155807, 2166.382580964433, 2242.2264318809985, 2253.0874995626796, 2253.188852013274, 2266.705328307318, 1902.9809701902943, 1989.7711763147279, 2252.526969336425, 2254.481469262079, 2288.439332363582, 7949.046114466301, 8162.673493060271, 8188.058373287088, 8218.260356567878, 8264.628091735887, 5052.46949070346, 5193.740312984336, 5229.346331708536, 5229.921551176735, 5234.659747116425, 3339.448990916496, 3351.413502637053, 3367.326534693071, 3446.1556918554866, 3477.565304336958, 1178.7465694875643, 1191.1007189571903, 1211.5517258614368, 1227.3863068465766, 1236.8330275412793, 8620.224179823846, 10633.680989570403, 10649.25455218135, 10796.838047429295, 10804.5678172873, 3627.510286111394, 3692.1692619160476, 4065.9593404066172, 4396.07682386696, 4442.267025940748, 8500.70247541333, 8576.013758417737, 8595.31237501011, 8616.526091064927, 8618.0, 4723.149833030087, 5027.84916452507, 5028.296038554606, 5034.496550345009, 5045.520675535819, 4438.423005009393, 4528.0943811237885, 4691.9530804692195, 4703.882402939954, 4713.269443236304, 2972.4946759051013, 3542.8405721742583, 3558.3594952908206, 3611.0972256935765, 3630.582483014487, 998.8002399520125, 1005.5575546759402, 1009.4902074452501, 1012.9797404052025, 1016.7508960304625, 749.9137599176056, 853.88899443072, 868.769776009358, 873.5282947208564, 932.9440233586007, 5276.868078297996, 5319.521243088063, 5566.916496252559, 5574.219609254707, 5722.856928576819, 528.2525226804106, 569.866081470854, 907.9591418386188, 965.2567523007245, 987.782687808681, 3532.8586856525453, 3576.946345804815, 3826.4260360945523, 3857.8263978121054, 3881.0491429599365, 9320.440773553608, 9444.574994125282, 9742.415455072576, 9757.268204884662, 9904.455254960902, 9826.65606703762, 9829.262805289633, 9839.163671087894, 9848.310618256653, 9875.259355548362, 7681.654325555363, 7836.412269079728, 7877.409194310335, 7877.527348359117, 7881.290683838597, 5476.644018138808, 5580.302462192226, 5793.507551857971, 5800.1589769484035, 5835.183074369676, 8878.689245876363, 9245.815083698422, 9253.352265341362, 9254.074592540692, 9264.166225039779, 7027.648617569203, 9065.954670226607, 9185.908140918506, 9255.86116208244, 9324.020977797234, 5513.61404701667, 7210.315020073084, 7252.311030452099, 7254.383377412979, 7260.128784545888, 7833.060032796101, 7884.093329266978, 7888.3689304856625, 7891.210878912063, 7910.288074073361, 6955.895661565181, 7023.789286321418, 7090.539114957512, 7129.429645628441, 7143.1428228612895, 6931.514793964374, 6965.895511567229, 6990.685419156049, 7036.155661320674, 7050.541714788523, 6265.49876009909, 6342.524310676626, 6385.393387628168, 6402.455791257609, 6406.903896441464, 4016.7297192082424, 4067.064575147792, 4870.31815545831, 4975.253711943237, 5051.898962020812, 3617.35410388278, 4153.605407486284, 4976.427710813303, 5029.270755740324, 5123.180291153547], "value": 4258.686611650336, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.3461630599696, 357.6977454051326, 360.55831606282305, 369.112284954684, 371.52630396244786, 5200.8699782505455, 5253.737313134342, 5293.788248470061, 5306.151015837462, 5381.757820898059, 4661.925443177787, 5181.279828090254, 5253.763580638871, 5288.985278391318, 5318.388385335687, 3560.1099725068734, 3935.8425662973445, 4044.9797751011206, 4097.85657501986, 4101.589841015897, 4031.838726450953, 4059.249038927793, 4093.9590604093873, 4094.8362065517344, 4111.629953304202, 3529.205928666055, 3901.7463864848837, 4028.9597104028876, 4067.0645751477177, 4094.3858421236905, 3367.24237046665, 3906.726529142961, 3965.4250133730447, 4103.733257338264, 4104.281750693616, 2902.490704833475, 2914.9562756558666, 3430.262493563871, 3468.5490886184634, 3489.9127521812156, 4311.461067366579, 4523.5024147343765, 4573.725576465423, 5173.198154286054, 5199.922001169985, 4295.2912769393015, 4588.990422107131, 4629.356578414648, 4648.209804333272, 4650.488446270908, 4470.965710601701, 5130.461301563357, 5637.746301416446, 5639.407862174454, 5670.574706896958, 3856.2287542491613, 3861.5366156061173, 3866.574676785551, 3870.7290489665734, 3929.960700392988, 4787.712737235742, 4886.75566221691, 5437.861221146019, 5772.365039845656, 5974.8506287342725, 3454.1537323355938, 3687.2256826066828, 3816.8931010006786, 3832.616738326145, 3846.2307538492414, 3665.376885929398, 3895.7857317847793, 3937.291287568265, 3955.5253369595835, 3959.5842436544326, 3793.715471339661, 3871.7289789715014, 4577.336286238543, 4593.507110189202, 4604.355390245369, 5157.839486115594, 5470.316210473691, 5612.550995920316, 5643.492085712305, 5661.3206415229615, 1154.1113342726053, 1272.4401263444056, 1279.6161151654653, 1280.225463594512, 1291.4640424223842, 708.9613715906222, 805.8468890910708, 864.7665130414879, 880.8942926848819, 881.2949640287744, 4122.381642753611, 4401.801918913657, 4678.228092364721, 5120.974395128075, 5129.8717532062, 4637.837675681335, 5160.174372100446, 5348.598855085884, 5581.469760372758, 5586.664800112007, 4424.535423780457, 4606.56237657422, 4764.8332308369045, 4898.110115032148, 5139.58883289336, 2580.0711743772226, 2673.5136323219203, 2699.78509670649, 2768.335599456156, 2933.559966005116, 5238.92141617876, 5469.906018796257, 5830.941690583125, 5868.559858010666, 5883.529317654577, 4940.925886111711, 5335.132703553725, 5730.312362516524, 5737.770489180386, 5972.983643260212, 5563.638363506278, 6067.24159480065, 6106.908396374147, 6110.969445152746, 6114.838180745644, 4811.0, 5583.994880921473, 5679.914801278067, 5799.217105690846, 5859.619124756877, 4985.853253751585, 5472.181826359349, 5771.422857714195, 5818.359980402068, 5835.7373918174635, 4844.539768721966, 5729.799457018984, 5911.793087241926, 6082.391760823883, 6100.664463454375, 3722.7021838253418, 3809.2381523694817, 3857.787821669835, 3899.0229965435665, 3957.821898014539, 5137.974310128426, 5816.9127463088935, 5832.075415113088, 5834.095715164156, 5844.503217226584, 5192.935448233178, 5330.893382132258, 5525.834224973257, 5593.664380137205, 5646.764926305271, 5329.120695085423, 5619.325680918316, 5640.85897852557, 5659.801906933402, 5669.433056694297, 4873.390826146731, 5212.348456442945, 5379.569634429315, 5459.235707000944, 5568.805091821767, 5283.441384791573, 5504.394516603091, 5567.0, 5625.46558076982, 5630.18362337467, 5021.372328458942, 5034.874128146826, 5501.284832971688, 5518.392976772632, 5547.722613869371], "value": 4051.6799654901506, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.9935800641993, 691.5539477037311, 717.0033653222023, 732.1433922310894, 734.1190571314424, 12330.32183229922, 14191.716165676693, 15651.373945042213, 15691.0, 15940.760888586652, 14047.0, 14140.363683634261, 14391.568252952402, 14470.565883023526, 14492.492762753305, 876.3996662286338, 889.0664801957919, 892.6518657723493, 896.3232759266745, 897.3763234551989, 871.9433236839617, 893.4282059481905, 897.8742975983367, 900.158351940934, 911.0843602179833, 934.7476181431009, 936.9484678342689, 937.5452905340904, 937.6249500199906, 938.5870217104452, 932.5244125495979, 933.4865823796919, 936.6347124621403, 937.6577549194495, 937.9906200937971, 695.0720787748362, 695.9965200174017, 698.0157977252101, 698.2703075286338, 699.3356311504092, 14622.488212912604, 15135.0, 15372.0, 15414.691706165813, 15430.768538471932, 12838.844503994593, 13892.652683682887, 13898.374573144132, 14026.662168397366, 14221.360038798279, 10322.690319290434, 11489.655310340702, 11525.07605215864, 11551.0, 11750.706232344175, 3618.6743193112484, 3628.782273063598, 3653.598104208535, 3653.598104208535, 3693.759905606127, 97985.0, 99854.50072749589, 100054.0, 100269.9973000268, 100449.9910001796, 10648.0, 10676.145908327315, 10816.296940698829, 10985.670429887115, 11183.608573699881, 21863.12547498114, 21924.23265185711, 22311.0, 22342.0, 22354.32937011892, 7196.136463624294, 7239.0, 7332.926670733331, 7346.265373462611, 7488.363489103378, 4062.73592216505, 4164.6251837334785, 4169.666426685919, 4174.812133453941, 4207.86387675332, 3568.8572457101427, 3581.086822860154, 3611.530501034849, 3670.5134420559716, 3686.870959516458, 545.3973359437844, 561.6658088437373, 647.7959442775605, 659.1562799616498, 661.1504217081027, 613.982277108018, 627.6014730645982, 649.2760571962228, 675.3280485916545, 687.1410736579276, 9849.606015759433, 10075.59697612102, 10079.5464204111, 10151.543180556893, 10258.487075646337, 633.8845516680345, 691.4122995453824, 700.8668353012911, 705.693023534764, 726.5422783646269, 4491.88770280739, 4800.9106882129945, 4889.65772395936, 4894.926576101361, 4900.534449227318, 99235.50382248043, 102994.0, 103428.0, 104750.47624761828, 108183.45908270408, 87105.56447217724, 88837.0, 89794.0, 90770.36918523317, 90784.1843163133, 62103.378966210665, 62445.68777156085, 65264.0, 68531.97202041974, 71022.28977710157, 9842.261830362895, 10504.0, 10573.577056917637, 10601.575936962743, 10630.25588208849, 44855.32717009313, 47615.952644025645, 49557.752211238716, 50318.0, 51632.0, 58579.70710146422, 64521.354786451535, 67712.0, 68121.93451294802, 68578.65710671415, 12255.571055013388, 12353.752924941271, 12668.239905605693, 12757.617271481871, 12951.158174718614, 11369.54521819151, 14206.786898196742, 14383.928080359532, 15009.474668386554, 15039.473618423302, 12726.745465090831, 12982.610521684364, 13122.223943162406, 13303.733925321632, 13675.24786136772, 12868.356582171042, 13153.079284449997, 13154.934225328812, 13293.800592991307, 14027.368768405242, 12754.42605082792, 12786.872131278567, 13433.126846754929, 13541.526046588322, 13727.931360343135, 10181.949090254502, 10615.522301496298, 10721.892781072089, 10723.892761072602, 11326.716832079264, 8534.658613655385, 8913.420627659181, 9539.856902146612, 10378.792424151323, 10630.202734794766], "value": 7395.684953503445, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1625.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 180.0, 139.0, 121.0, 119.0, 119.0, 104.0, 173.0, 177.0, 126.0], "value": 140.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 149.0, 176.0, 107.0, 122.0, 139.0, 108.0, 160.0, 146.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [261.0, 276.0, 236.0, 202.0, 193.0, 202.0, 194.0, 204.0, 199.0, 195.0], "value": 216.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 66.0, 85.0, 78.0, 81.0, 57.0, 66.0, 53.0, 75.0, 83.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 90.0, 87.0, 104.0, 73.0, 86.0, 95.0, 103.0, 115.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 122.0, 136.0, 137.0, 93.0, 92.0, 94.0, 92.0, 93.0, 97.0], "value": 107.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 101.0, 99.0, 100.0, 100.0, 100.0, 100.0, 100.0, 100.0, 100.0], "value": 100.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 144.0, 100.0, 93.0, 78.0, 146.0, 144.0, 96.0, 96.0, 98.0], "value": 111.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 68.0, 67.0, 68.0, 66.0, 69.0, 68.0, 66.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 77.0, 80.0, 75.0, 70.0, 68.0, 71.0, 71.0, 69.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 88.0, 88.0, 91.0, 91.0, 91.0, 88.0, 89.0, 102.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 152.0, 182.0, 154.0, 146.0, 149.0, 151.0, 159.0, 193.0, 147.0], "value": 157.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 179.0, 176.0, 179.0, 207.0, 244.0, 233.0, 176.0, 177.0, 174.0], "value": 192.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 69.0, 66.0, 69.0, 70.0, 69.0, 69.0, 70.0, 69.0], "value": 68.9, "unit": "score"}]}]} 10:07:03 INFO - Return code: 0 10:07:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:07:03 ERROR - # TBPL SUCCESS # 10:07:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:07:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:07:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:07:03 INFO - 2017-02-24 10:07:03.871 screenresolution[1655:10687] starting screenresolution argv=screenresolution get 10:07:03 INFO - 2017-02-24 10:07:03.874 screenresolution[1655:10687] Display 0: 1600x1200x32@60 10:07:03 INFO - 2017-02-24 10:07:03.880 screenresolution[1656:10690] starting screenresolution argv=screenresolution list 10:07:03 INFO - Available Modes on Display 0 10:07:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:07:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:07:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:07:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:07:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:07:04 INFO - Intel Iris: 10:07:04 INFO - Chipset Model: Intel Iris 10:07:04 INFO - Type: GPU 10:07:04 INFO - Bus: Built-In 10:07:04 INFO - VRAM (Dynamic, Max): 1536 MB 10:07:04 INFO - Vendor: Intel (0x8086) 10:07:04 INFO - Device ID: 0x0a2e 10:07:04 INFO - Revision ID: 0x0009 10:07:04 INFO - Displays: 10:07:04 INFO - Display: 10:07:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:07:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:07:04 INFO - Display Serial Number: 1600x1200 60 10:07:04 INFO - Main Display: Yes 10:07:04 INFO - Mirror: Off 10:07:04 INFO - Online: Yes 10:07:04 INFO - Rotation: Supported 10:07:04 INFO - Return code: 0 10:07:04 INFO - Running post-action listener: _resource_record_post_action 10:07:04 INFO - [mozharness: 2017-02-24 18:07:04.114104Z] Finished run-tests step (success) 10:07:04 INFO - Running post-run listener: _resource_record_post_run 10:07:04 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 39848448; Write bytes: 515463168; Read time: 386; Write time: 1810 10:07:04 INFO - TinderboxPrint: CPU usage
54.6% 10:07:04 INFO - TinderboxPrint: I/O read bytes / time
39,848,448 / 386 10:07:04 INFO - TinderboxPrint: I/O write bytes / time
515,463,168 / 1,810 10:07:04 INFO - TinderboxPrint: CPU idle
2,181.5 (45.4%) 10:07:04 INFO - TinderboxPrint: CPU system
975.3 (20.3%) 10:07:04 INFO - TinderboxPrint: CPU user
1,652.9 (34.4%) 10:07:04 INFO - TinderboxPrint: Swap in / out
250,224,640 / 0 10:07:04 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 210438656; Write bytes: 205625344; Read time: 16694; Write time: 502 10:07:04 INFO - run-tests - Wall time: 1182s; CPU: 55.0%; Read bytes: 35207680; Write bytes: 299823104; Read time: 351; Write time: 1278 10:07:04 INFO - Running post-run listener: _upload_blobber_files 10:07:04 INFO - Blob upload gear active. 10:07:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:07:04 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 10:07:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:07:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:07:05 INFO - (blobuploader) - INFO - Open directory for files ... 10:07:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:07:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:07:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:06 INFO - (blobuploader) - INFO - Done attempting. 10:07:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:07:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:07:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:07 INFO - (blobuploader) - INFO - Done attempting. 10:07:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:07:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:07:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:08 INFO - (blobuploader) - INFO - Done attempting. 10:07:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:07:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:07:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:10 INFO - (blobuploader) - INFO - Done attempting. 10:07:10 INFO - (blobuploader) - INFO - Iteration through files over. 10:07:10 INFO - Return code: 0 10:07:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:07:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:07:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a51c3a61c7554d717fb752fe829d36f1aff6f97f893c37bfdc5a9f26454ca2296ab8312e44ad5debfc3061347d05ebeb45d6fbbce0356eba1ca2eb31c92a0f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e227ebde8e5db6df505d433574cc69bda1a7df94635de5f7da2f150cb112a5ecdfcbb953e2287f275be3612772b7ab4206dc76173f5edba2e9c645eb7940c4e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd1c9b34c19215c426a30b76b106101e8b918347887a867b0530c30a07f1c9566121286a44824ced751d56ca75c91590f6f9b89121d348e191c4671b1dbbcfe7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84b99ecc7cf03e1d3c070556d7bbbbded79611cd370f94b484af213eb126fd4d506123f38790630b38aead347b7102aef39f1c5406d6b9ce5db85cefe54baa4d"} 10:07:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:07:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:07:10 INFO - Contents: 10:07:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a51c3a61c7554d717fb752fe829d36f1aff6f97f893c37bfdc5a9f26454ca2296ab8312e44ad5debfc3061347d05ebeb45d6fbbce0356eba1ca2eb31c92a0f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e227ebde8e5db6df505d433574cc69bda1a7df94635de5f7da2f150cb112a5ecdfcbb953e2287f275be3612772b7ab4206dc76173f5edba2e9c645eb7940c4e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd1c9b34c19215c426a30b76b106101e8b918347887a867b0530c30a07f1c9566121286a44824ced751d56ca75c91590f6f9b89121d348e191c4671b1dbbcfe7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84b99ecc7cf03e1d3c070556d7bbbbded79611cd370f94b484af213eb126fd4d506123f38790630b38aead347b7102aef39f1c5406d6b9ce5db85cefe54baa4d"} 10:07:10 INFO - Running post-run listener: copy_logs_to_upload_dir 10:07:10 INFO - Copying logs to upload dir... 10:07:10 INFO - mkdir: /builds/slave/test/build/upload/logs 10:07:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.643489 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-02-24 10:07:11.150584) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-02-24 10:07:11.157773) ========= 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.ZSj10jtavz/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.gzivvfPfNC/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a51c3a61c7554d717fb752fe829d36f1aff6f97f893c37bfdc5a9f26454ca2296ab8312e44ad5debfc3061347d05ebeb45d6fbbce0356eba1ca2eb31c92a0f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e227ebde8e5db6df505d433574cc69bda1a7df94635de5f7da2f150cb112a5ecdfcbb953e2287f275be3612772b7ab4206dc76173f5edba2e9c645eb7940c4e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd1c9b34c19215c426a30b76b106101e8b918347887a867b0530c30a07f1c9566121286a44824ced751d56ca75c91590f6f9b89121d348e191c4671b1dbbcfe7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84b99ecc7cf03e1d3c070556d7bbbbded79611cd370f94b484af213eb126fd4d506123f38790630b38aead347b7102aef39f1c5406d6b9ce5db85cefe54baa4d"} build_url:https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009808 build_url: 'https://queue.taskcluster.net/v1/task/CJQzNI_HRDiWsFklq-sE2w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a51c3a61c7554d717fb752fe829d36f1aff6f97f893c37bfdc5a9f26454ca2296ab8312e44ad5debfc3061347d05ebeb45d6fbbce0356eba1ca2eb31c92a0f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e227ebde8e5db6df505d433574cc69bda1a7df94635de5f7da2f150cb112a5ecdfcbb953e2287f275be3612772b7ab4206dc76173f5edba2e9c645eb7940c4e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd1c9b34c19215c426a30b76b106101e8b918347887a867b0530c30a07f1c9566121286a44824ced751d56ca75c91590f6f9b89121d348e191c4671b1dbbcfe7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84b99ecc7cf03e1d3c070556d7bbbbded79611cd370f94b484af213eb126fd4d506123f38790630b38aead347b7102aef39f1c5406d6b9ce5db85cefe54baa4d"}' ========= master_lag: 1.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-02-24 10:07:12.182168) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 10:07:12.182549) ========= 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.ZSj10jtavz/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.gzivvfPfNC/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.004571 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 10:07:12.231547) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-24 10:07:12.231875) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-24 10:07:12.232233) ========= ========= Total master_lag: 1.32 =========