builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0022 starttime: 1490185302.87 results: success (0) buildid: 20170322033504 builduid: 0f06d623363d41958d8113fea01a955b revision: 7fc9362cb6a5cc5a53b323d2aa48adc70dab415c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:42.865573) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:42.865991) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:42.866276) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:42.895552) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:42.895927) ========= 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.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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-03-22 05:21: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% 161M=0s 2017-03-22 05:21:43 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149814 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:43.072967) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:43.073287) ========= 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.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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.027731 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:43.123275) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-22 05:21:43.123625) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7fc9362cb6a5cc5a53b323d2aa48adc70dab415c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7fc9362cb6a5cc5a53b323d2aa48adc70dab415c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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-03-22 05:21:43,236 truncating revision to first 12 chars 2017-03-22 05:21:43,236 Setting DEBUG logging. 2017-03-22 05:21:43,236 attempt 1/10 2017-03-22 05:21:43,236 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7fc9362cb6a5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-22 05:21:44,342 unpacking tar archive at: mozilla-aurora-7fc9362cb6a5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.392539 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-22 05:21:44.544572) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:44.545659) ========= script_repo_revision: 7fc9362cb6a5cc5a53b323d2aa48adc70dab415c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:44.546069) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:44.546334) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 05:21:44.568218) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-03-22 05:21:44.568586) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 05:21:44 INFO - MultiFileLogger online at 20170322 05:21:44 in /builds/slave/test 05:21:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:21:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:21:44 INFO - {'append_to_log': False, 05:21:44 INFO - 'base_work_dir': '/builds/slave/test', 05:21:44 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:21:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:21:44 INFO - 'branch': 'Mozilla-Aurora', 05:21:44 INFO - 'buildbot_json_path': 'buildprops.json', 05:21:44 INFO - 'config_files': ('talos/mac_config.py',), 05:21:44 INFO - 'default_actions': ('clobber', 05:21:44 INFO - 'read-buildbot-config', 05:21:44 INFO - 'download-and-extract', 05:21:44 INFO - 'populate-webroot', 05:21:44 INFO - 'create-virtualenv', 05:21:44 INFO - 'install', 05:21:44 INFO - 'run-tests'), 05:21:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:21:44 INFO - 'download_minidump_stackwalk': True, 05:21:44 INFO - 'download_symbols': 'ondemand', 05:21:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:21:44 INFO - 'tooltool.py': '/tools/tooltool.py', 05:21:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:21:44 INFO - '/tools/misc-python/virtualenv.py')}, 05:21:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:21:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:21:44 INFO - 'gecko_profile': False, 05:21:44 INFO - 'gecko_profile_interval': 0, 05:21:44 INFO - 'installer_path': 'installer.exe', 05:21:44 INFO - 'log_level': 'info', 05:21:44 INFO - 'log_name': 'talos', 05:21:44 INFO - 'log_to_console': True, 05:21:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:21:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:21:44 INFO - 'opt_config_files': (), 05:21:44 INFO - 'pip_index': False, 05:21:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:21:44 INFO - 'cmd': ('bash', 05:21:44 INFO - '-c', 05:21:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:21:44 INFO - 'enabled': True, 05:21:44 INFO - 'halt_on_failure': False, 05:21:44 INFO - 'name': 'check_screen_resolution'},), 05:21:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:21:44 INFO - 'cmd': ('bash', 05:21:44 INFO - '-c', 05:21:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:21:44 INFO - 'enabled': True, 05:21:44 INFO - 'halt_on_failure': False, 05:21:44 INFO - 'name': 'check_screen_resolution'},), 05:21:44 INFO - 'run_cmd_checks_enabled': True, 05:21:44 INFO - 'suite': 'dromaeojs-e10s', 05:21:44 INFO - 'system_bits': '32', 05:21:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:21:44 INFO - 'title': 't-yosemite-r7-0022', 05:21:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:21:44 INFO - 'use_talos_json': True, 05:21:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:21:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:21:44 INFO - 'work_dir': 'build'} 05:21:44 INFO - [mozharness: 2017-03-22 12:21:44.782348Z] Running clobber step. 05:21:44 INFO - Running pre-action listener: _resource_record_pre_action 05:21:44 INFO - Running main action method: clobber 05:21:44 INFO - rmtree: /builds/slave/test/build 05:21:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:21:47 INFO - Running post-action listener: _resource_record_post_action 05:21:47 INFO - [mozharness: 2017-03-22 12:21:47.211877Z] Finished clobber step (success) 05:21:47 INFO - [mozharness: 2017-03-22 12:21:47.212002Z] Running read-buildbot-config step. 05:21:47 INFO - Running pre-action listener: _resource_record_pre_action 05:21:47 INFO - Running main action method: read_buildbot_config 05:21:47 INFO - Using buildbot properties: 05:21:47 INFO - { 05:21:47 INFO - "project": "", 05:21:47 INFO - "product": "firefox", 05:21:47 INFO - "script_repo_revision": "production", 05:21:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 05:21:47 INFO - "repository": "", 05:21:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 05:21:47 INFO - "buildid": "20170322033504", 05:21:47 INFO - "pgo_build": "False", 05:21:47 INFO - "basedir": "/builds/slave/test", 05:21:47 INFO - "buildnumber": 25, 05:21:47 INFO - "slavename": "t-yosemite-r7-0022", 05:21:47 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:21:47 INFO - "platform": "yosemite_r7", 05:21:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:21:47 INFO - "branch": "mozilla-aurora", 05:21:47 INFO - "repo_path": "releases/mozilla-aurora", 05:21:47 INFO - "revision": "7fc9362cb6a5cc5a53b323d2aa48adc70dab415c", 05:21:47 INFO - "stage_platform": "macosx64", 05:21:47 INFO - "builduid": "0f06d623363d41958d8113fea01a955b", 05:21:47 INFO - "slavebuilddir": "test" 05:21:47 INFO - } 05:21:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 05:21:47 INFO - Running post-action listener: _resource_record_post_action 05:21:47 INFO - [mozharness: 2017-03-22 12:21:47.215469Z] Finished read-buildbot-config step (success) 05:21:47 INFO - [mozharness: 2017-03-22 12:21:47.215589Z] Running download-and-extract step. 05:21:47 INFO - Running pre-action listener: _resource_record_pre_action 05:21:47 INFO - Running main action method: download_and_extract 05:21:47 INFO - mkdir: /builds/slave/test/build/tests 05:21: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')]} 05:21:47 INFO - trying https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 05:21:47 INFO - Downloading https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 05:21:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 05:21:48 INFO - Downloaded 1199 bytes. 05:21:48 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 05:21:48 INFO - Using the following test package requirements: 05:21:48 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 05:21:48 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:21:48 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 05:21:48 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:21:48 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 05:21:48 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:21:48 INFO - u'jsshell-mac.zip'], 05:21:48 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:21:48 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 05:21:48 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 05:21:48 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:21:48 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 05:21:48 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:21:48 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 05:21:48 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:21:48 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 05:21:48 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:21:48 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 05:21:48 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:21:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 05:21:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:21:48 INFO - Fetch https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 05:21:49 INFO - Expected file size: 51782049 05:21:49 INFO - Obtained file size: 51782049 05:22:05 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 05:22:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 05:22:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:22:05 INFO - Fetch https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 05:22:06 INFO - Expected file size: 51782049 05:22:06 INFO - Obtained file size: 51782049 05:22:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 05:22:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:22:22 INFO - Fetch https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 05:22:23 INFO - Expected file size: 14404438 05:22:23 INFO - Obtained file size: 14404438 05:22:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:23 INFO - trying https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 05:22:23 INFO - Downloading https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 05:22:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 05:22:25 INFO - Downloaded 74763937 bytes. 05:22:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 05:22:25 INFO - mkdir: /builds/slave/test/properties 05:22:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:22:25 INFO - Writing to file /builds/slave/test/properties/build_url 05:22:25 INFO - Contents: 05:22:25 INFO - build_url:https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 05:22:26 INFO - Running post-action listener: _resource_record_post_action 05:22:26 INFO - Running post-action listener: set_extra_try_arguments 05:22:26 INFO - [mozharness: 2017-03-22 12:22:26.617273Z] Finished download-and-extract step (success) 05:22:26 INFO - [mozharness: 2017-03-22 12:22:26.617437Z] Running populate-webroot step. 05:22:26 INFO - Running pre-action listener: _resource_record_pre_action 05:22:26 INFO - Running main action method: populate_webroot 05:22:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:22:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:22:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:22:26 INFO - '--tppagecycles', 05:22:26 INFO - '7'], 05:22:26 INFO - 'tests': ['tsvgm']}}, 05:22:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:22:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:22:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:22:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:22:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:22:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:22:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:22:26 INFO - 'talos_options': ['--disable-e10s'], 05:22:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:22:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:22:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:22:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:22:26 INFO - 'talos_options': ['--disable-e10s'], 05:22:26 INFO - 'tests': ['damp', 'tps']}, 05:22:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:22:26 INFO - 'tests': ['damp', 'tps']}, 05:22:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:22:26 INFO - 'tests': ['dromaeo_dom']}, 05:22:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:22:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:22:26 INFO - 'tests': ['basic_compositor_video']}, 05:22:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:22:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:22:26 INFO - 'tests': ['a11yr', 05:22:26 INFO - 'ts_paint', 05:22:26 INFO - 'tpaint', 05:22:26 INFO - 'sessionrestore', 05:22:26 INFO - 'sessionrestore_no_auto_restore', 05:22:26 INFO - 'tabpaint']}, 05:22:26 INFO - 'other-e10s': {'tests': ['a11yr', 05:22:26 INFO - 'ts_paint', 05:22:26 INFO - 'tpaint', 05:22:26 INFO - 'sessionrestore', 05:22:26 INFO - 'sessionrestore_no_auto_restore', 05:22:26 INFO - 'tabpaint']}, 05:22:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:22:26 INFO - 'ts_paint', 05:22:26 INFO - 'tpaint', 05:22:26 INFO - 'sessionrestore', 05:22:26 INFO - 'sessionrestore_no_auto_restore', 05:22:26 INFO - 'tabpaint']}, 05:22:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:22:26 INFO - 'ts_paint', 05:22:26 INFO - 'tpaint', 05:22:26 INFO - 'sessionrestore', 05:22:26 INFO - 'sessionrestore_no_auto_restore', 05:22:26 INFO - 'tabpaint']}, 05:22:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:22:26 INFO - 'tests': ['a11yr', 05:22:26 INFO - 'ts_paint', 05:22:26 INFO - 'tpaint', 05:22:26 INFO - 'sessionrestore', 05:22:26 INFO - 'sessionrestore_no_auto_restore', 05:22:26 INFO - 'tabpaint']}, 05:22:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:22:26 INFO - 'tests': ['a11yr', 05:22:26 INFO - 'ts_paint', 05:22:26 INFO - 'tpaint', 05:22:26 INFO - 'sessionrestore', 05:22:26 INFO - 'sessionrestore_no_auto_restore', 05:22:26 INFO - 'tabpaint']}, 05:22:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:22:26 INFO - 'tests': ['tsvgx', 05:22:26 INFO - 'tsvgr_opacity', 05:22:26 INFO - 'tart', 05:22:26 INFO - 'tscrollx', 05:22:26 INFO - 'cart', 05:22:26 INFO - 'tsvg_static']}, 05:22:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:22:26 INFO - 'tsvgr_opacity', 05:22:26 INFO - 'tart', 05:22:26 INFO - 'tscrollx', 05:22:26 INFO - 'cart', 05:22:26 INFO - 'tsvg_static']}, 05:22:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:22:26 INFO - 'talos_options': ['--disable-e10s'], 05:22:26 INFO - 'tests': ['tp5o']}, 05:22:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:22:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:22:26 INFO - 'talos_options': ['--disable-e10s', 05:22:26 INFO - '--xperf_path', 05:22:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:22:26 INFO - 'tests': ['tp5n']}, 05:22:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:22:26 INFO - 'talos_options': ['--xperf_path', 05:22:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:22:26 INFO - 'tests': ['tp5n']}}, 05:22:26 INFO - 'talos.zip': {'path': '', 05:22:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:22:26 INFO - Running post-action listener: _resource_record_post_action 05:22:26 INFO - [mozharness: 2017-03-22 12:22:26.635938Z] Finished populate-webroot step (success) 05:22:26 INFO - [mozharness: 2017-03-22 12:22:26.636064Z] Running create-virtualenv step. 05:22:26 INFO - Running pre-action listener: _resource_record_pre_action 05:22:26 INFO - Running main action method: create_virtualenv 05:22:26 INFO - Creating virtualenv /builds/slave/test/build/venv 05:22:26 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 05:22:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:22:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:22:26 INFO - Using real prefix '/tools/python27' 05:22:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:22:27 INFO - Installing distribute.............................................................................................................................................................................................done. 05:22:30 INFO - Installing pip.................done. 05:22:30 INFO - Return code: 0 05:22:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:22:30 INFO - Reading from file tmpfile_stdout 05:22:30 INFO - Output received: 05:22:30 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) 05:22:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:30 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 0x1022bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b3ca2340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102098d30>, '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 0x101fbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10208dbe8>, '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.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 05:22:30 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 05:22:30 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 05:22:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPAQnPZb1z/Render', 05:22:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:30 INFO - 'HOME': '/Users/cltbld', 05:22:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:30 INFO - 'LOGNAME': 'cltbld', 05:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:30 INFO - 'MOZ_NO_REMOTE': '1', 05:22:30 INFO - 'NO_EM_RESTART': '1', 05:22:30 INFO - 'PAGER': '/bin/cat', 05:22:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:30 INFO - 'PWD': '/builds/slave/test', 05:22:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:30 INFO - 'SHELL': '/bin/bash', 05:22:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Hf7z0EpVt/Listeners', 05:22:30 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:22:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:30 INFO - 'USER': 'cltbld', 05:22:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:30 INFO - 'XPC_FLAGS': '0x0', 05:22:30 INFO - 'XPC_SERVICE_NAME': '0', 05:22:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:30 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 05:22:30 INFO - Cleaning up... 05:22:30 INFO - Return code: 0 05:22:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:30 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 0x1022bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b3ca2340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102098d30>, '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 0x101fbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10208dbe8>, '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.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 05:22:30 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 05:22:30 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 05:22:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPAQnPZb1z/Render', 05:22:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:30 INFO - 'HOME': '/Users/cltbld', 05:22:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:30 INFO - 'LOGNAME': 'cltbld', 05:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:30 INFO - 'MOZ_NO_REMOTE': '1', 05:22:30 INFO - 'NO_EM_RESTART': '1', 05:22:30 INFO - 'PAGER': '/bin/cat', 05:22:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:30 INFO - 'PWD': '/builds/slave/test', 05:22:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:30 INFO - 'SHELL': '/bin/bash', 05:22:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Hf7z0EpVt/Listeners', 05:22:30 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:22:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:30 INFO - 'USER': 'cltbld', 05:22:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:30 INFO - 'XPC_FLAGS': '0x0', 05:22:30 INFO - 'XPC_SERVICE_NAME': '0', 05:22:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:31 INFO - Downloading/unpacking psutil>=3.1.1 05:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:22:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:22:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:22:34 INFO - Installing collected packages: psutil 05:22:34 INFO - Running setup.py install for psutil 05:22:34 INFO - building 'psutil._psutil_osx' extension 05:22:34 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 05:22: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:22: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:22:35 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 05:22:35 INFO - building 'psutil._psutil_posix' extension 05:22:35 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 05:22:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:22:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:22:35 INFO - ^ 05:22:35 INFO - 1 warning generated. 05:22:35 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 05:22:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:22:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:22:35 INFO - Successfully installed psutil 05:22:35 INFO - Cleaning up... 05:22:35 INFO - Return code: 0 05:22:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:22:35 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')]} 05:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:35 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')]} 05:22:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:35 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 0x1022bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b3ca2340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102098d30>, '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 0x101fbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10208dbe8>, '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.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 05:22:35 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 05:22:35 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 05:22:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPAQnPZb1z/Render', 05:22:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:35 INFO - 'HOME': '/Users/cltbld', 05:22:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:35 INFO - 'LOGNAME': 'cltbld', 05:22:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:35 INFO - 'MOZ_NO_REMOTE': '1', 05:22:35 INFO - 'NO_EM_RESTART': '1', 05:22:35 INFO - 'PAGER': '/bin/cat', 05:22:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:35 INFO - 'PWD': '/builds/slave/test', 05:22:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:35 INFO - 'SHELL': '/bin/bash', 05:22:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Hf7z0EpVt/Listeners', 05:22:35 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:22:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:35 INFO - 'USER': 'cltbld', 05:22:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:35 INFO - 'XPC_FLAGS': '0x0', 05:22:35 INFO - 'XPC_SERVICE_NAME': '0', 05:22:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:22:36 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 05:22:36 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 05:22:36 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 05:22:36 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 05:22:36 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 05:22:36 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 05:22:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:22:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:22:38 INFO - Installing collected packages: mozsystemmonitor 05:22:38 INFO - Running setup.py install for mozsystemmonitor 05:22:39 INFO - Successfully installed mozsystemmonitor 05:22:39 INFO - Cleaning up... 05:22:39 INFO - Return code: 0 05:22:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:22:39 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')]} 05:22:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:39 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')]} 05:22:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:39 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 0x1022bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b3ca2340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102098d30>, '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 0x101fbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10208dbe8>, '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.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 05:22:39 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 05:22:39 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 05:22:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPAQnPZb1z/Render', 05:22:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:39 INFO - 'HOME': '/Users/cltbld', 05:22:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:39 INFO - 'LOGNAME': 'cltbld', 05:22:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:39 INFO - 'MOZ_NO_REMOTE': '1', 05:22:39 INFO - 'NO_EM_RESTART': '1', 05:22:39 INFO - 'PAGER': '/bin/cat', 05:22:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:39 INFO - 'PWD': '/builds/slave/test', 05:22:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:39 INFO - 'SHELL': '/bin/bash', 05:22:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Hf7z0EpVt/Listeners', 05:22:39 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:22:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:39 INFO - 'USER': 'cltbld', 05:22:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:39 INFO - 'XPC_FLAGS': '0x0', 05:22:39 INFO - 'XPC_SERVICE_NAME': '0', 05:22:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:40 INFO - Downloading/unpacking jsonschema==2.5.1 05:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:22:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:43 INFO - Downloading functools32-3.2.3-2.tar.gz 05:22:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:22:43 INFO - warning: no files found matching '*.txt' 05:22:43 INFO - no previously-included directories found matching 'build' 05:22:43 INFO - no previously-included directories found matching 'dist' 05:22:43 INFO - no previously-included directories found matching '.git*' 05:22:43 INFO - Installing collected packages: jsonschema, functools32 05:22:43 INFO - Running setup.py install for functools32 05:22:43 INFO - warning: no files found matching '*.txt' 05:22:43 INFO - no previously-included directories found matching 'build' 05:22:43 INFO - no previously-included directories found matching 'dist' 05:22:43 INFO - no previously-included directories found matching '.git*' 05:22:43 INFO - Successfully installed jsonschema functools32 05:22:43 INFO - Cleaning up... 05:22:43 INFO - Return code: 0 05:22:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:43 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 0x1022bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b3ca2340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102098d30>, '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 0x101fbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10208dbe8>, '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.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 05:22:43 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 05:22:43 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 05:22:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPAQnPZb1z/Render', 05:22:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:43 INFO - 'HOME': '/Users/cltbld', 05:22:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:43 INFO - 'LOGNAME': 'cltbld', 05:22:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:43 INFO - 'MOZ_NO_REMOTE': '1', 05:22:43 INFO - 'NO_EM_RESTART': '1', 05:22:43 INFO - 'PAGER': '/bin/cat', 05:22:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:43 INFO - 'PWD': '/builds/slave/test', 05:22:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:43 INFO - 'SHELL': '/bin/bash', 05:22:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Hf7z0EpVt/Listeners', 05:22:43 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:22:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:43 INFO - 'USER': 'cltbld', 05:22:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:43 INFO - 'XPC_FLAGS': '0x0', 05:22:43 INFO - 'XPC_SERVICE_NAME': '0', 05:22:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:22:44 INFO - Cleaning up... 05:22:44 INFO - Return code: 0 05:22:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1022bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b3ca2340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102098d30>, '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 0x101fbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10208dbe8>, '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.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 05:22:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:22:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:22:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPAQnPZb1z/Render', 05:22:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:44 INFO - 'HOME': '/Users/cltbld', 05:22:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:44 INFO - 'LOGNAME': 'cltbld', 05:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:44 INFO - 'MOZ_NO_REMOTE': '1', 05:22:44 INFO - 'NO_EM_RESTART': '1', 05:22:44 INFO - 'PAGER': '/bin/cat', 05:22:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:44 INFO - 'PWD': '/builds/slave/test', 05:22:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:44 INFO - 'SHELL': '/bin/bash', 05:22:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Hf7z0EpVt/Listeners', 05:22:44 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:22:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:44 INFO - 'USER': 'cltbld', 05:22:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:44 INFO - 'XPC_FLAGS': '0x0', 05:22:44 INFO - 'XPC_SERVICE_NAME': '0', 05:22:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:44 INFO - Downloading/unpacking blobuploader==1.2.4 05:22: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 05:22: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 05:22: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 05:22: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 05:22: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 05:22: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 05:22:46 INFO - Downloading blobuploader-1.2.4.tar.gz 05:22:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:22:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:22:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:22:48 INFO - Downloading docopt-0.6.1.tar.gz 05:22:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:22:48 INFO - Installing collected packages: blobuploader, requests, docopt 05:22:48 INFO - Running setup.py install for blobuploader 05:22:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:22:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:22:48 INFO - Running setup.py install for requests 05:22:48 INFO - Running setup.py install for docopt 05:22:49 INFO - Successfully installed blobuploader requests docopt 05:22:49 INFO - Cleaning up... 05:22:49 INFO - Return code: 0 05:22:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1022bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b3ca2340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102098d30>, '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 0x101fbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10208dbe8>, '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.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 05:22:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:22:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPAQnPZb1z/Render', 05:22:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:49 INFO - 'HOME': '/Users/cltbld', 05:22:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:49 INFO - 'LOGNAME': 'cltbld', 05:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:49 INFO - 'MOZ_NO_REMOTE': '1', 05:22:49 INFO - 'NO_EM_RESTART': '1', 05:22:49 INFO - 'PAGER': '/bin/cat', 05:22:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:49 INFO - 'PWD': '/builds/slave/test', 05:22:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:49 INFO - 'SHELL': '/bin/bash', 05:22:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Hf7z0EpVt/Listeners', 05:22:49 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:22:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:49 INFO - 'USER': 'cltbld', 05:22:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:49 INFO - 'XPC_FLAGS': '0x0', 05:22:49 INFO - 'XPC_SERVICE_NAME': '0', 05:22:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:22:49 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-3HWawW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:22:49 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Sx0dMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:22:49 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-1_gA3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:22:49 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-I5q_YQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:22:49 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-AOdSfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:22:49 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-EWnPn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:22:50 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-6nOVjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:22:50 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-6pZxGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:22:50 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-SMx2_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:22:50 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-jS_bFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:22:50 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-5fFJqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:22:50 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-u_pd99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:22:50 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-BfzYk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:22:50 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-ov7JQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:50 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-V8ENTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:22:50 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-lu0VSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:22:51 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-MmkFdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:22:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:22:51 INFO - Running setup.py install for manifestparser 05:22:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:22:51 INFO - Running setup.py install for mozcrash 05:22:51 INFO - Running setup.py install for mozdebug 05:22:51 INFO - Running setup.py install for mozdevice 05:22:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:22:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:22:51 INFO - Running setup.py install for mozfile 05:22:51 INFO - Running setup.py install for mozhttpd 05:22:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:22:52 INFO - Running setup.py install for mozinfo 05:22:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:22:52 INFO - Running setup.py install for mozInstall 05:22:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:22:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:22:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:22:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:22:52 INFO - Running setup.py install for mozleak 05:22:52 INFO - Running setup.py install for mozlog 05:22:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:22:52 INFO - Running setup.py install for moznetwork 05:22:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:22:52 INFO - Running setup.py install for mozprocess 05:22:52 INFO - Running setup.py install for mozprofile 05:22:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:22:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:22:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:22:53 INFO - Running setup.py install for mozrunner 05:22:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:22:53 INFO - Running setup.py install for mozscreenshot 05:22:53 INFO - Running setup.py install for moztest 05:22:53 INFO - Running setup.py install for mozversion 05:22:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:22:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:22:53 INFO - Cleaning up... 05:22:53 INFO - Return code: 0 05:22:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:53 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')]} 05:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:53 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')]} 05:22:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:53 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 0x1022bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b3ca2340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102098d30>, '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 0x101fbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10208dbe8>, '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.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 05:22:53 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 05:22:53 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 05:22:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPAQnPZb1z/Render', 05:22:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:53 INFO - 'HOME': '/Users/cltbld', 05:22:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:53 INFO - 'LOGNAME': 'cltbld', 05:22:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:53 INFO - 'MOZ_NO_REMOTE': '1', 05:22:53 INFO - 'NO_EM_RESTART': '1', 05:22:53 INFO - 'PAGER': '/bin/cat', 05:22:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:53 INFO - 'PWD': '/builds/slave/test', 05:22:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:53 INFO - 'SHELL': '/bin/bash', 05:22:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Hf7z0EpVt/Listeners', 05:22:53 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:22:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:53 INFO - 'USER': 'cltbld', 05:22:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:53 INFO - 'XPC_FLAGS': '0x0', 05:22:53 INFO - 'XPC_SERVICE_NAME': '0', 05:22:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:22:54 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-hPoBnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:22:54 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-6Zt9la-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:22:54 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-OPb4Ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:22:54 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-YAWlqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:22:54 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)) 05:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:22:54 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-n0msgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:22:54 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)) 05:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:22:54 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-J7EiZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:22:54 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)) 05:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:22:54 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-UCXrQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:22:54 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)) 05:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:22:54 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Zli1zG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:22:54 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)) 05:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:22:54 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-cQd0v3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:22:54 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)) 05:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:22:54 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-vnxkTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:22:55 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-BiK2qT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:22:55 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-sHRSnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:22:55 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)) 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:22:55 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-psDTHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:22:55 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-uv2qOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:22:55 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)) 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:55 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-707zll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:22:55 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)) 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:22:55 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-_7aYU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:22:55 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)) 05:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:22:55 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-WHouFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:22:55 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)) 05:22:55 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)) 05:22:55 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)) 05:22:55 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)) 05:22:55 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)) 05:22:55 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)) 05:22:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:22:55 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 05:22:55 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 05:22:55 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 05:22:55 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 05:22:55 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 05:22:55 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 05:22:58 INFO - Downloading blessings-1.6.tar.gz 05:22:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:22:58 INFO - Installing collected packages: blessings 05:22:58 INFO - Running setup.py install for blessings 05:22:58 INFO - Successfully installed blessings 05:22:58 INFO - Cleaning up... 05:22:58 INFO - Return code: 0 05:22:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:22:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:22:59 INFO - Reading from file tmpfile_stdout 05:22:59 INFO - Current package versions: 05:22:59 INFO - blessings == 1.6 05:22:59 INFO - blobuploader == 1.2.4 05:22:59 INFO - docopt == 0.6.1 05:22:59 INFO - functools32 == 3.2.3-2 05:22:59 INFO - jsonschema == 2.5.1 05:22:59 INFO - manifestparser == 1.1 05:22:59 INFO - mozInstall == 1.12 05:22:59 INFO - mozcrash == 1.0 05:22:59 INFO - mozdebug == 0.1 05:22:59 INFO - mozdevice == 0.49 05:22:59 INFO - mozfile == 1.2 05:22:59 INFO - mozhttpd == 0.7 05:22:59 INFO - mozinfo == 0.9 05:22:59 INFO - mozleak == 0.1 05:22:59 INFO - mozlog == 3.4 05:22:59 INFO - moznetwork == 0.27 05:22:59 INFO - mozprocess == 0.25 05:22:59 INFO - mozprofile == 0.28 05:22:59 INFO - mozrunner == 6.13 05:22:59 INFO - mozscreenshot == 0.1 05:22:59 INFO - mozsystemmonitor == 0.3 05:22:59 INFO - moztest == 0.8 05:22:59 INFO - mozversion == 1.4 05:22:59 INFO - psutil == 3.1.1 05:22:59 INFO - requests == 1.2.3 05:22:59 INFO - wsgiref == 0.1.2 05:22:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1022bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b3ca2340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102098d30>, '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 0x101fbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10208dbe8>, '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.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 05:22:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPAQnPZb1z/Render', 05:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:59 INFO - 'HOME': '/Users/cltbld', 05:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:59 INFO - 'LOGNAME': 'cltbld', 05:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:59 INFO - 'MOZ_NO_REMOTE': '1', 05:22:59 INFO - 'NO_EM_RESTART': '1', 05:22:59 INFO - 'PAGER': '/bin/cat', 05:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:59 INFO - 'PWD': '/builds/slave/test', 05:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:59 INFO - 'SHELL': '/bin/bash', 05:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Hf7z0EpVt/Listeners', 05:22:59 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:59 INFO - 'USER': 'cltbld', 05:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:59 INFO - 'XPC_FLAGS': '0x0', 05:22:59 INFO - 'XPC_SERVICE_NAME': '0', 05:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:22:59 INFO - Cleaning up... 05:22:59 INFO - Return code: 0 05:22:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:22:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1022bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4b3ca2340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102098d30>, '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 0x101fbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10208dbe8>, '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.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 05:22:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPAQnPZb1z/Render', 05:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:22:59 INFO - 'HOME': '/Users/cltbld', 05:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:22:59 INFO - 'LOGNAME': 'cltbld', 05:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:59 INFO - 'MOZ_NO_REMOTE': '1', 05:22:59 INFO - 'NO_EM_RESTART': '1', 05:22:59 INFO - 'PAGER': '/bin/cat', 05:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:59 INFO - 'PWD': '/builds/slave/test', 05:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:22:59 INFO - 'SHELL': '/bin/bash', 05:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Hf7z0EpVt/Listeners', 05:22:59 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:22:59 INFO - 'USER': 'cltbld', 05:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:59 INFO - 'XPC_FLAGS': '0x0', 05:22:59 INFO - 'XPC_SERVICE_NAME': '0', 05:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:22:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:22:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:22:59 INFO - Cleaning up... 05:22:59 INFO - Return code: 0 05:22:59 INFO - Running post-action listener: _resource_record_post_action 05:22:59 INFO - Running post-action listener: _start_resource_monitoring 05:22:59 INFO - Starting resource monitoring. 05:22:59 INFO - [mozharness: 2017-03-22 12:22:59.672944Z] Finished create-virtualenv step (success) 05:22:59 INFO - [mozharness: 2017-03-22 12:22:59.673959Z] Running install step. 05:22:59 INFO - Running pre-action listener: _resource_record_pre_action 05:22:59 INFO - Running main action method: install 05:22:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:22:59 INFO - Reading from file tmpfile_stdout 05:22:59 INFO - Detecting whether we're running mozinstall >=1.0... 05:22:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:23:00 INFO - Reading from file tmpfile_stdout 05:23:00 INFO - Output received: 05:23:00 INFO - Usage: mozinstall [options] installer 05:23:00 INFO - Options: 05:23:00 INFO - -h, --help show this help message and exit 05:23:00 INFO - -d DEST, --destination=DEST 05:23:00 INFO - Directory to install application into. [default: 05:23:00 INFO - "/builds/slave/test"] 05:23:00 INFO - --app=APP Application being installed. [default: firefox] 05:23:00 INFO - mkdir: /builds/slave/test/build/application 05:23:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:23:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 05:23:19 INFO - Reading from file tmpfile_stdout 05:23:19 INFO - Output received: 05:23:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:23:19 INFO - Running post-action listener: _resource_record_post_action 05:23:19 INFO - [mozharness: 2017-03-22 12:23:19.938172Z] Finished install step (success) 05:23:19 INFO - [mozharness: 2017-03-22 12:23:19.938306Z] Running run-tests step. 05:23:19 INFO - Running pre-action listener: _resource_record_pre_action 05:23:19 INFO - Running main action method: run_tests 05:23:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:23:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:23:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:23:19 INFO - 2017-03-22 05:23:19.952 screenresolution[1626:7614] starting screenresolution argv=screenresolution get 05:23:19 INFO - 2017-03-22 05:23:19.955 screenresolution[1626:7614] Display 0: 1600x1200x32@60 05:23:19 INFO - 2017-03-22 05:23:19.960 screenresolution[1627:7616] starting screenresolution argv=screenresolution list 05:23:19 INFO - Available Modes on Display 0 05:23:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:23:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:23:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:23:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:23:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:23:20 INFO - Intel Iris: 05:23:20 INFO - Chipset Model: Intel Iris 05:23:20 INFO - Type: GPU 05:23:20 INFO - Bus: Built-In 05:23:20 INFO - VRAM (Dynamic, Max): 1536 MB 05:23:20 INFO - Vendor: Intel (0x8086) 05:23:20 INFO - Device ID: 0x0a2e 05:23:20 INFO - Revision ID: 0x0009 05:23:20 INFO - Displays: 05:23:20 INFO - Display: 05:23:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:23:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:23:20 INFO - Display Serial Number: 1600x1200 60 05:23:20 INFO - Main Display: Yes 05:23:20 INFO - Mirror: Off 05:23:20 INFO - Online: Yes 05:23:20 INFO - Rotation: Supported 05:23:20 INFO - Return code: 0 05:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:23:20 INFO - Python 2.7.3 05:23:20 INFO - Return code: 0 05:23:20 INFO - grabbing minidump binary from tooltool 05:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:20 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 0x102098d30>, '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 0x101fbfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10208dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:23:20 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 05:23:20 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 05:23:20 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 05:23:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:23:20 INFO - Return code: 0 05:23:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:23:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:23:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:23:20 INFO - ENV: RUST_BACKTRACE is now 1 05:23:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:23:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--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 05:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0022 --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 05:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qPAQnPZb1z/Render', 05:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:20 INFO - 'HOME': '/Users/cltbld', 05:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:20 INFO - 'LOGNAME': 'cltbld', 05:23:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:23:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:20 INFO - 'MOZ_NO_REMOTE': '1', 05:23:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:23:20 INFO - 'NO_EM_RESTART': '1', 05:23:20 INFO - 'PAGER': '/bin/cat', 05:23:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:20 INFO - 'PWD': '/builds/slave/test', 05:23:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:20 INFO - 'RUST_BACKTRACE': '1', 05:23:20 INFO - 'SHELL': '/bin/bash', 05:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Hf7z0EpVt/Listeners', 05:23:20 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 05:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:20 INFO - 'USER': 'cltbld', 05:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:20 INFO - 'XPC_FLAGS': '0x0', 05:23:20 INFO - 'XPC_SERVICE_NAME': '0', 05:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--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 05:23:20 INFO - mozversion application_buildid: 20170322033504 05:23:20 INFO - mozversion application_changeset: 7fc9362cb6a5cc5a53b323d2aa48adc70dab415c 05:23:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:23:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:23:20 INFO - mozversion application_name: Firefox 05:23:20 INFO - mozversion application_remotingname: firefox-dev 05:23:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:23:20 INFO - mozversion application_vendor: Mozilla 05:23:20 INFO - mozversion application_version: 54.0a2 05:23:20 INFO - mozversion platform_buildid: 20170322033504 05:23:20 INFO - mozversion platform_changeset: 7fc9362cb6a5cc5a53b323d2aa48adc70dab415c 05:23:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:23:20 INFO - mozversion platform_version: 54.0a2 05:23:20 INFO - using testdate: 1490185400 05:23:20 INFO - actual date: 1490185400 05:23:20 INFO - starting webserver on 'localhost:49241' 05:23:20 INFO - SUITE-START | Running 2 tests 05:23:20 INFO - TEST-START | dromaeo_css 05:23:20 INFO - operating with platform_type : mac_ 05:23:20 INFO - Initialising browser for dromaeo_css test... 05:23:20 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpvJXr6P/profile http://localhost:49241/getInfo.html) 05:23:23 INFO - PROCESS | 1637 | 2017-03-22 05:23:23.447 plugin-container[1639:7800] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4953, name = 'com.apple.tsm.portname' 05:23:23 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:23 INFO - PROCESS | 1637 | 2017-03-22 05:23:23.452 plugin-container[1639:7800] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9603, name = 'com.apple.CFPasteboardClient' 05:23:23 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:23 INFO - PROCESS | 1637 | 2017-03-22 05:23:23.452 plugin-container[1639:7800] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:23:23 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 05:23:23 INFO - PROCESS | 1637 | colorDepth:24 05:23:23 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 05:23:23 INFO - PROCESS | 1637 | __metrics 05:23:23 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 05:23:24 INFO - TEST-INFO | 1637: exit 0 05:23:24 INFO - Browser initialized. 05:23:24 INFO - Running cycle 1/1 for dromaeo_css test... 05:23:24 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpvJXr6P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:23:27 INFO - PROCESS | 1640 | 2017-03-22 05:23:26.995 plugin-container[1641:7924] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 05:23:27 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:27 INFO - PROCESS | 1640 | 2017-03-22 05:23:26.996 plugin-container[1641:7924] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 05:23:27 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:27 INFO - PROCESS | 1640 | 2017-03-22 05:23:26.996 plugin-container[1641:7924] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:23:36 INFO - PROCESS | 1640 | RSS: Main: 220585984 05:23:36 INFO - PROCESS | 1640 | 05:26:40 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 05:26:41 INFO - PROCESS | 1640 | RSS: Main: 239226880 05:26:41 INFO - PROCESS | 1640 | 05:28:24 INFO - PROCESS | 1640 | ************************* 05:28:24 INFO - PROCESS | 1640 | A coding exception was thrown and uncaught in a Task. 05:28:24 INFO - PROCESS | 1640 | 05:28:24 ERROR - PROCESS | 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:28:24 INFO - PROCESS | 1640 | Full stack: 05:28:24 INFO - PROCESS | 1640 | ************************* 05:29:44 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 05:29:44 INFO - PROCESS | 1640 | RSS: Main: 248860672 05:29:44 INFO - PROCESS | 1640 | 05:32:46 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 05:32:47 INFO - PROCESS | 1640 | RSS: Main: 256131072 05:32:47 INFO - PROCESS | 1640 | 05:35:49 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 05:35:50 INFO - PROCESS | 1640 | RSS: Main: 252383232 05:35:50 INFO - PROCESS | 1640 | 05:38:53 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 05:38:53 INFO - PROCESS | 1640 | RSS: Main: 257372160 05:38:53 INFO - PROCESS | 1640 | 05:41:56 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 05:41:56 INFO - PROCESS | 1640 | RSS: Main: 253857792 05:41:56 INFO - PROCESS | 1640 | 05:41:56 INFO - PROCESS | 1640 | __start_tp_report 05:41:56 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 05:41:56 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 05:41:56 INFO - PROCESS | 1640 | |i|pagename|runs| 05:41:56 INFO - PROCESS | 1640 | |0;dojo.html;3460.1003739027847;3482.0076278260685;3486.8779592714254;3515.0685068456864;3515.753897227195;6022.187004754357;6084.3003054648725;6088.665123418215;6117.6329420234815;6137.140800287963;4883.316335713003;6093.329733729286;6101.755929762815;6115.082737589364;6149.846253843656;5431.456854314557;5438.102713052342;5498.642588231752;5527.640703354269;5625.6905870177125;5478.986387518302;5553.194786755918;5557.527610153142;5562.805301814437;5571.303587051619;4469.681443974036;5267.710275934822;5450.991566560159;5454.427285135043;5455.645383050109;4929.457759646436;5379.488948549882;5381.892362152774;5414.07960646687;5440.292761940962;5058.671186372911;5332.706701131437;5356.517913387814;5369.355677318707;5416.9729151353995;5834.854128646775;5976.223090998186;6042.395760423967;6083.361247069039;6093.847653808691;6217.316095229521;6324.177856878577;6340.682965851689;6375.808725738235;6389.712462939133;5575.661841158133;6432.646204458753;6509.3490650935;6531;6540.4440622547145;6218.129461875341;6222.284437289679;6236.563440559117;6240.032794916795;6250.718717657716;6243.219597550306;6341.302456729756;6558.114654521674;6604.306547812506;6646.435053020451;6325.335839736854;6345.33373995733;6348.873022539523;6377.936220637735;6378.968105159444;6508.544401891822;6524.151860258136;6525.608463492205;6531.673416329259;6542.345765423514;6246.437820596168;6260.154879091265;6266.749330026839;6358.364163583605;6427.132337134427;4892.730899800474;6333.20834895638;6368.777092801823;6377.202849643794;6379.3620637936765;5191.325127733446;5201.167813149954;5206.219067139884;5228.895422091537;5234.947650523446;4518.638508919278;4529.13946350199;4603.033362993742;4618.330342100377;4653.115907977473;6150.415710507495;6201.441870231611;6211.409916057967;6242.563020588515;6253.53098517613;6525.1517302750335;6533.738650454024;6543.378379053982;6565.606063636197;6570.113034740344;5961.165436838846;5992.700364981733;6118.265808103056;6216.222972128484;6244.594101383395;5668.206451096849;5669.461401166883;5690.5447564194765;5735.913961290584;5742.7415766290615;6662.500312476487;6724.831879203059;6732.999999999902;6737.629430381376;6745.730170793114;6455.838604034937;6504.902426463702;6512.73949042033;6516.446102081376;6528.869422611616;6345.809625711236;6417.839554011187;6658.334166583303;6731.865362692621;6759.932400675931;6372.458341041063;6439.195100612424;6441.420272175339;6456.483481321389;6461.483081353386;6417.679116044085;6484.675766211765;6487.351264873475;6503.804885853432;6516.609003459807;5368.060589396762;5700.087985922232;6132.2947860996555;6204.100405441095;6374.043893416025;6228.750849966132;6259.405356491126;6321.589096708699;6342.302346741947;6345.460635846005;5582.27430434041;5704.942950570441;6209.944309467311;6279.591826531261;6283.37166283368;6218.626882387071;6263.906041409291;6281.748730050746;6314.463270622048;6316.936830631818;6145.494353883327;6166.660833654013;6180.165677633642;6255.186825712628;6259.436650701458 05:41:56 INFO - PROCESS | 1640 | |1;ext.html;676.7428377216656;806.1938061938063;827.9627416766247;894.8747175395446;972.6206779356048;37854.810725946365;38852.22295554087;39901.80049099758;39942.80028599853;40183.598164018316;38786.8060659697;38804.223915521674;38980.41529377062;39063.41404878922;39157.21685566287;7862.567558784279;8008;10509.78980420391;10819.350838949687;10929.726756831064;10709.410982395964;10737.570497180104;10765.300255483407;10774.138068954464;10776.78446431071;10760.73098172544;10795.5681772729;10798.136149108092;10805.5677772889;10818.621348252813;10732.624358147466;10749.570017199303;10768.623098191565;10795.24433289674;10800.945995269973;10477.633282835064;10517.84223236652;10567.630132945387;10585.629502967437;10600.310979786289;33940.66059339424;33973;34068.82965585181;34223.80216692404;34245.657543424495;3633.6911362534215;3681.742278040538;3713.164537978933;3713.387291096966;3764.5670747863796;24991.875040624862;25026;25193.748062519506;26328.341791455176;26373;2689.8924043038255;3022.863971121305;3097.1018404662846;3256.0231930421014;3257.5439438478634;346488;351314;352230;355782;424979;42812.143757124686;42826;42969.785151074044;43041.923951900826;43082.13835723268;45672;45857.312140317925;45956.08087838224;46038;46083.53916460792;13528.255945922872;13588.660283492794;13628.727425451634;13643.38604762788;13751.106178098591;11804.232724872856;11876.10929180315;11880.762384752257;11905.285682859056;11919.821202681967;11805.704857378463;11839.230450020894;11873.465694043789;11908.345041022827;11985.520579176735;5597.412271711411;5615.438456154352;5640.689762063126;5641.661500309994;5642.7178641067785;1108.7283615514089;1136.8010598145293;1146.529922731676;1159.8633339327507;1195.9103067269816;26141.869290653423;26417.867910660323;26601.600975985377;26754.063607773634;26814;1110.2228440091806;1162.8372027916098;1213.696575856036;1245.1968480330136;1254.974900502003;10715.571377144826;13647.727045459034;13936.303184840717;13974.650633734238;14003.579892603238;412949;415286;415733;416153;416297;54441.45558544443;54635;54669;54709.632259193044;54732;1378.620879258212;1439.1077531930061;1439.3163247457373;1441.6323837421392;1442.1419255542935;20767;24886.626700599867;25530.361740956625;25885.6117158239;26017.699115043793;44131.55868441275;44200.55799441964;44292.55707442884;44316.778416107714;44368.11263774771;392181.0390948141;393491;394089;394300;394329;30646.540301895042;30687.693123068482;38689.41965870571;39762.40356394714;39800.606978755604;48098.5190148108;48145.03709925712;48161.79595510141;48961.02077958492;49796.50203497919;29466.85266573739;37594.436083458226;39996;40291.395629065;40595.7970210147;32857.50713739343;39162.60837391589;39352.803235983636;40485.79757101196;40663.39004914988;39510.20979580335;39739.205215894945;39934.60065399309;39938.2012359757;40085.79957100196;30435.54346684846;32937.50593741048;34824.82587587046;34857.65142348645;35084.64915350916;26369.340766480993;26399.472010560065;26628.201153965412;26743.19770406891;26746.03449727407 05:41:56 INFO - PROCESS | 1640 | |2;jquery.html;369.805851927738;378.8749712594844;380.17122672573794;408.96932730045256;453.8570350339643;13468.191908485473;18620.813791862078;19516.21935122595;21070.473238169052;21075.05162267698;21569.24507642233;21706.023228954695;21785.128594856185;21939.780602194012;22017.779822201774;5909.20225769522;6838.247792742794;6853.143357080365;6855.794326170223;6863.622500762456;6500.187476565429;6618.437432818217;6643.933560664404;6654.936969539434;6676.899846502306;6694.564853284545;6708.698108585126;6708.899366509506;6722.193336799591;6730;5928.407159284058;6147.862645410613;6649.069130321759;6699.531032827704;6707.798766037026;6283.403076707706;6500.414962653336;6509.576877502947;6514.794762968866;6549.836254093638;25596.616050759254;25666.358341041436;26141.346466338302;26358.73641263582;26386.076487323044;2901.376204116104;2916.6208392908784;2921.7516511096583;2922.901724172384;2961.422522608092;15205.847941520553;15569;15701.371945122295;16083.437079702271;16411.507654770376;2057.711920331155;2089.9791002089937;2097.590969760897;2124.2034237161065;2200;149437;166519.1674041622;171771.14114429473;173217.26782732137;175830;16578.50264492067;21533;21544.24595139194;21545.78454215469;21811.23660671869;29103.85448072746;30406.695933040388;33538.66461335404;33601.327973440886;33715.49426758601;10179.898201017895;10181;10297.970202979792;10388.688339349832;10397.792044159074;8567.314614830799;8607.870881936777;8616.956915215384;8619.310455163572;8759.649614015496;9499.335046546676;9518.428894266366;9596.856047159297;9664.855027174597;9718.319717619841;3923.0765389436074;4128.926479115392;4260.297050986553;4372.1692878353015;4402.173098164226;996.8654231678776;1007.5415685862915;1016.3089099412323;1017.9847302290472;1056.6090546497844;20489.97550122488;20934.953252337324;20950.895245523676;20992.685109723367;21145.04847281876;951.7192428233687;1003.7252689084821;1032.5198782566142;1033.214756784849;1034.1468288661883;7549;10536.315139515908;10909.236353455182;10972.615958441416;11016.06363459115;728964;757543;758116;767578;772324;169782.15108924377;171444;174006;179531.10234448744;181953;1303.3027330378202;1308.4242933109401;1350.567818298135;1355.5323413422354;1365.0035474103959;15201.695966080837;20265.78405295687;20741.58516829685;20843.89578052161;20873.373798786062;38316.65891693778;55942;67348.98976515258;68576;69383.3061669397;92255.53872230597;92520;92831;93341.53329233582;94052.58921116326;11631.709207269547;11687.88312116902;11869.406529673655;11890;12197.817032744693;13019.349032548145;13207.566032831724;13328.533501327407;13471.12437691547;13647.11293765902;10199.031092046243;10888.836667450152;11279.718007049561;11307.038901693448;11420.257683250562;11130.387828669178;11248;11249.381284029458;11304.039156671446;11361.602343917923;11391;11569.247998880383;11818.40907954616;11837.348945807718;11915.046796256105;11725;11868.940655296668;11896.940515297369;12113.001354776716;12137.453814578541;11152.442377881236;11279.266847654877;11345.602903898323;11532.308061516336;11631.825522616999 05:41:56 INFO - PROCESS | 1640 | |3;mootools.html;1423.380829339237;1513.7284680868077;1585.4688679292444;1600.359856057577;1604.8876578639492;10710.678679639605;11159.665210043691;11367.715807104827;11415.372154531497;11499.827502587468;10804.0816530595;10949.069329107035;10966.945165274163;10979;11005.559777608885;2358.6579945907874;2409.987950060256;2414.9396265093337;2415.698037745282;2418.22616762636;2397.1729753235195;2430.635404689293;2432.4648577312964;2441.7558244175616;2446.449548851503;2394.5330660521204;2413.81896357773;2419.661247425362;2422.188566830114;2424.75752424757;2359.0563774490342;2376.061455724988;2379.0602711928777;2387.2241521505407;2387.8328517003815;2326.732425771041;2349.5418393413293;2401.639754036891;2406.6991626046743;2408.759124087595;10999.670009899715;11070.335779853234;11078.723031924186;11083.556657733681;11105.888941110567;5882.647041177543;6010.338862725097;6036;6038.516918646542;6058.121572372025;3144.701253380948;3510.2102027043966;3663.1025398777206;3707.2029513654693;3756.1173124315765;1179.1156632525606;1181.8168183931505;1213.1265488848014;1254.3728135932033;1260.8360913081333;11828.881711182865;12325.322107284186;13734.725305493834;15646.9217653911;15772.921135394365;4639.582437580634;4678.532146785362;4825.914169311876;4829.830955916596;4833.202521583899;11761.235519691363;11793.410329483491;11814.99572536327;11831.704207394883;11842.940785296018;5819.447152520504;5928.822135335947;5942.3166335871065;5949.286085669661;5962.135490353874;5044.722540260321;5110.4634013428795;5147.433782283946;5167.457416971164;5217.452167522431;3444.50054742066;3909.843606255718;3914.197589494147;3924.9607503925167;4024.839006439768;969.8797888438864;1027.897210278981;1069.187417562658;1069.2889228663007;1074.065562960214;887.4143065576689;928.3872644054892;938.8216238914584;954.19370631816;1007.1841808135433;6132.78535251273;6473.158489396348;6505.934940650533;6591.209054913441;6759.526833121634;985.192142443204;992.5682328187262;994.5872462927948;997.4314640654757;1002.2883752535781;3510;4191.538930717618;4216.599423054805;4223.852165174204;4247.638950689165;13525.39135738894;13740.862591374158;13870.514531991534;13880.652983675489;13938.512152074629;13161.802572961413;13529;13753.518626848012;13767.036307458382;13807.447702091804;8303.709370172015;8748.95012598505;10318.742031449123;10333.638322658671;10382.896171038345;6106.175666284995;6995.195552511526;7075.893861591977;7079.362857342658;7116.573005619679;12079;12083.39583020828;12085.093617978511;12086;12181.451834667638;12510.311932843417;12674.302913339854;12969.54606588765;12985.610431687064;13146.868531314565;8979.910200898168;9170.082991700776;9269.72190834276;9511.334206605472;9537.570809313458;10384.191231259885;10548.261621686715;10552.841707374242;10660.200484963503;10694.251402401756;9280.535973201448;9296.628134874529;9311.67409140704;9329.440233586007;9435.858462122937;9012.639494420411;9036.864447033431;9059.773505662411;9069.365144439826;9133.178013978773;5788.884222315614;7446.218147094477;7759.573223472763;7781.949436826182;7787.532748035136;5685.34618518859;5765.971170144123;5853.2976042875125;5881.823545293648;5970.0447928333;5623.606347555632;5846.386129456346;5920.437558431942;5942.1383899335215;5957.731902064331 05:41:56 INFO - PROCESS | 1640 | |4;prototype.html;366.7616049567781;377.59597230962845;380.7734398033171;399.6782590015039;402.5793046266651;4887.755612219384;5244.947550524494;5381.5963802714805;5417.160340147283;5457.016378044025;5129.179331306992;5292.841214763554;5368.973155134219;5380.7309634518215;5402.109871218899;3713.5172427584375;3930;4072.552019277877;4446.622037126848;4454.599086082252;3479.599846017715;4212.747235165884;4421.425214722081;4426.845060422886;4445.888852778674;3963.7819919904396;4108.6507646849905;4423.447069116361;4463.616278953518;4493.910121797562;3573.4639804029343;4341.544137865541;4371.912561748779;4389.846355377546;4500.7074540154945;3871.380579107329;4079;4095.8361665533344;4108.979455102735;4110.677467231035;4694.230404061586;4888.804447822083;5265.6840589564745;5268.236105764643;5280.471952804727;4407.713498622579;4733.296013435161;4928.67963582366;5149.716765577892;5176.5858731301405;4886.804527818883;4990.4011518618;5646.830595082154;5695.572832037572;5765.942340576583;4032.7983600819844;4168.290221702687;4275.828966841323;4458.0415210729525;4582.839600613996;4994;5203.583713302926;5367.75845086972;5644.604877658566;6117.7152797912495;3907.7459965102175;4127.379114262465;4150.730202536825;4395.7142785718825;4575.519570445122;4284.635805956528;4622.959834037382;4743.501932297128;4764.76176191196;4787.904241915212;4838.298446725205;5070.391553013662;5088.796448142107;5096.770645320969;5111.616628752859;4652.975565882203;4922.75386230687;5646.830595082154;5713.4000929902595;5791.536677065825;1410.7672234081467;1545.984540154584;1595.755310857533;1603.8636715879047;1634.166575046711;1096.9195342587545;1114.2840026359477;1128.8250321200228;1211.1703483114095;1213.8239955206445;4972.5276098770555;5247.790088396498;5349.833317506416;5350.438203988603;5361;5035.874103147451;5821.592488525763;5849.385814489502;5899.7050147492455;5983.940160598426;4597.494275629678;4982.377202849644;5360.758765855546;5364.946350536445;5365.865853353697;2779.6803367612983;2882.798204125691;3068.0335694256682;3077.7999430036975;3100.3024319527926;5586.329640443091;5711.771529138788;5773.422657734277;5805.622634528742;5936.643801371932;4976.676516026447;5165.431802501722;5377.623566350396;6025.855087533355;6119.357467465941;5505.476979687003;5772.3650398456975;6006.168118723831;6074.54440916933;6162.352952939967;5417.349918009864;5640.88718225625;5708.230448560959;5752.827415177551;5956.851078723066;5023;5301.045811753921;5313.123334649894;5674.177244299636;6003.009503431885;5306.628536002597;5449.6730196188355;5468.792920035708;6106.236720409949;6345.524085693499;4725.881852953704;4730.45216087934;4769.046190761931;4775.185429536754;4804.918893248991;5702.857428564153;5973.759840059977;6016.909746353721;6037.879242415039;6056.3035250946705;5540.778368865362;5579.888402231852;5794.710264486843;5850.7074646266665;5886.764529418775;4806.375171227718;4933.876653083702;5423.322084739408;5610.747516361692;5781.710914454345;4747.928781068218;4950.504949505021;5174.7153906535505;5212.50481204285;5693.5160511357;5518.978988887069;5785.768569257351;5785.913211301918;5788.768449261982;5865.882682346414;4426.911461770682;4593.931091033704;5183.144781111226;5400.42927751411;5493.945060549343 05:41:56 INFO - PROCESS | 1640 | |5;yui.html;698.8881778915375;706.2972342519195;751.3200553499082;793.2781169136088;864.3387808326629;15448.382064717398;15632.14023228722;16287;16301.266443010063;16338.918305408486;13255.806977372034;15018.699626007474;15496.380144794222;15545.300461479232;15585.220738963035;666.7266420767462;688.7864761923795;913.8492148795457;952.8523078922743;955.8040601676639;924.8196601662678;949.6438835436711;960.1502670136944;962.4706411473696;965.4352203960691;991.7867658453431;992.5632721602472;993.1062044160242;993.6571882700493;993.8807343118835;986.1519093579516;987.9407235565889;988.5353883674661;989.7228775942748;991.1030517381781;726.5023458930649;734.7318228846486;736.9705211791522;737.5169264132002;738.8670039392907;15553.611159720984;15654.139022353766;15824.920875395665;16120.274587643584;16122.274497647633;14565.927170364186;14688.632784180374;14786.852131478656;15116;15192.088474691554;11738.823917641243;11818.645440636794;11985.760284794342;12012.519499220021;12086.033117350678;3681.9631803681887;3752.0894690758546;3756.079760458705;3813.122981714221;3953.9011524712114;108914;115221.42389288083;115623.42188289089;116115.41942290319;116343.4182829089;10481.842772358421;10754.623588174376;10903;11101.5559377624;11104.222704410775;22710;22935.311940641808;23150.884245579;23189.188378406674;23386.7661323388;7885.605719714106;7930.822605541994;7942.960285198537;8032.638531266107;8139.145389734111;4367.716098453654;4386.824527018947;4418.16054949565;4422.083544832894;4475.619572336323;3500.3524347995703;3529.982350088233;3595.4786555949763;3596.4245720684567;3612.7290453216115;565.5164834066895;565.5927732033012;568.2158621102851;576.1789450033768;576.1990832742491;687.1410736579276;699.692135460406;700.6776882633943;706.6254884910902;713.5362014690389;10057.396556206651;10201.081902628799;10224.233182511192;10276.845847312296;10451.895481045092;460.94007778988515;543.4592580382466;576.3026737647493;587.9353271140171;594.1325664529835;4841.709497430165;4968.478309777493;4973.1297023020825;4981;4982.327385802942;83210;106876.46561767296;108697.45651271693;108786;109041;91227;93157;94675.99999999862;94935.050649494;96094.07811843725;59586.404135958954;78986;79884.60634665033;80669.59665201754;81549.77675334875;7358.96320518394;9609.951950240484;9635.710928672152;9716.562754676197;9758.072983066597;54184;54288.3713488596;54519.27403629882;55245;56384.71807640936;67905;68131.65934170298;69323.65338173478;69731.95402068872;70964;9640.614375424904;9753.9024609753;10561.207909406672;11844.52621895149;11860.110491713329;13026;13172;13232.13991090576;13250.138740981805;13410.262435566137;10698.518566664363;11755.837984085885;11866.3961168137;12139.21095128849;12157.574484892986;11780.941095294469;12094;12153.878461215274;12263.877361226274;12551.246925184518;11818.231814932002;11863.288202707865;11881.702957426134;11984.76030479403;12324.445399957203;10202.18382529381;10473.581056757645;10584.36493810343;10892.945535272273;11093.667189984313;10280;10287.742806429898;10288.639897603547;10299.3820370778;10340.896591033994 05:41:56 INFO - PROCESS | 1640 | __end_tp_report 05:41:56 INFO - PROCESS | 1640 | __start_cc_report 05:41:56 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,423 05:41:56 INFO - PROCESS | 1640 | __end_cc_report 05:41:56 INFO - PROCESS | 1640 | __startTimestamp1490186516591__endTimestamp 05:41:56 INFO - PROCESS | 1640 | 05:41:56 INFO - PROCESS | 1640 | ------- Summary: start ------- 05:41:56 INFO - PROCESS | 1640 | Number of tests: 6 05:41:56 INFO - PROCESS | 1640 | 05:41:56 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5963.65 Median:6222.28 stddev:684.49 (11.0%) stddev-sans-first:657.81 05:41:56 INFO - PROCESS | 1640 | Values: 3460.1 3482.0 3486.9 3515.1 3515.8 6022.2 6084.3 6088.7 6117.6 6137.1 4883.3 6093.3 6101.8 6115.1 6149.8 5431.5 5438.1 5498.6 5527.6 5625.7 5479.0 5553.2 5557.5 5562.8 5571.3 4469.7 5267.7 5451.0 5454.4 5455.6 4929.5 5379.5 5381.9 5414.1 5440.3 5058.7 5332.7 5356.5 5369.4 5417.0 5834.9 5976.2 6042.4 6083.4 6093.8 6217.3 6324.2 6340.7 6375.8 6389.7 5575.7 6432.6 6509.3 6531.0 6540.4 6218.1 6222.3 6236.6 6240.0 6250.7 6243.2 6341.3 6558.1 6604.3 6646.4 6325.3 6345.3 6348.9 6377.9 6379.0 6508.5 6524.2 6525.6 6531.7 6542.3 6246.4 6260.2 6266.7 6358.4 6427.1 4892.7 6333.2 6368.8 6377.2 6379.4 5191.3 5201.2 5206.2 5228.9 5234.9 4518.6 4529.1 4603.0 4618.3 4653.1 6150.4 6201.4 6211.4 6242.6 6253.5 6525.2 6533.7 6543.4 6565.6 6570.1 5961.2 5992.7 6118.3 6216.2 6244.6 5668.2 5669.5 5690.5 5735.9 5742.7 6662.5 6724.8 6733.0 6737.6 6745.7 6455.8 6504.9 6512.7 6516.4 6528.9 6345.8 6417.8 6658.3 6731.9 6759.9 6372.5 6439.2 6441.4 6456.5 6461.5 6417.7 6484.7 6487.4 6503.8 6516.6 5368.1 5700.1 6132.3 6204.1 6374.0 6228.8 6259.4 6321.6 6342.3 6345.5 5582.3 5704.9 6209.9 6279.6 6283.4 6218.6 6263.9 6281.7 6314.5 6316.9 6145.5 6166.7 6180.2 6255.2 6259.4 05:41:56 INFO - PROCESS | 1640 | 05:41:56 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:53690.57 Median:34020.91 stddev:103701.54 (304.8%) stddev-sans-first:103916.06 05:41:56 INFO - PROCESS | 1640 | Values: 676.7 806.2 828.0 894.9 972.6 37854.8 38852.2 39901.8 39942.8 40183.6 38786.8 38804.2 38980.4 39063.4 39157.2 7862.6 8008.0 10509.8 10819.4 10929.7 10709.4 10737.6 10765.3 10774.1 10776.8 10760.7 10795.6 10798.1 10805.6 10818.6 10732.6 10749.6 10768.6 10795.2 10800.9 10477.6 10517.8 10567.6 10585.6 10600.3 33940.7 33973.0 34068.8 34223.8 34245.7 3633.7 3681.7 3713.2 3713.4 3764.6 24991.9 25026.0 25193.7 26328.3 26373.0 2689.9 3022.9 3097.1 3256.0 3257.5 346488.0 351314.0 352230.0 355782.0 424979.0 42812.1 42826.0 42969.8 43041.9 43082.1 45672.0 45857.3 45956.1 46038.0 46083.5 13528.3 13588.7 13628.7 13643.4 13751.1 11804.2 11876.1 11880.8 11905.3 11919.8 11805.7 11839.2 11873.5 11908.3 11985.5 5597.4 5615.4 5640.7 5641.7 5642.7 1108.7 1136.8 1146.5 1159.9 1195.9 26141.9 26417.9 26601.6 26754.1 26814.0 1110.2 1162.8 1213.7 1245.2 1255.0 10715.6 13647.7 13936.3 13974.7 14003.6 412949.0 415286.0 415733.0 416153.0 416297.0 54441.5 54635.0 54669.0 54709.6 54732.0 1378.6 1439.1 1439.3 1441.6 1442.1 20767.0 24886.6 25530.4 25885.6 26017.7 44131.6 44200.6 44292.6 44316.8 44368.1 392181.0 393491.0 394089.0 394300.0 394329.0 30646.5 30687.7 38689.4 39762.4 39800.6 48098.5 48145.0 48161.8 48961.0 49796.5 29466.9 37594.4 39996.0 40291.4 40595.8 32857.5 39162.6 39352.8 40485.8 40663.4 39510.2 39739.2 39934.6 39938.2 40085.8 30435.5 32937.5 34824.8 34857.7 35084.6 26369.3 26399.5 26628.2 26743.2 26746.0 05:41:56 INFO - PROCESS | 1640 | 05:41:56 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:44311.20 Median:11634.63 stddev:127211.74 (1093.4%) stddev-sans-first:127525.82 05:41:56 INFO - PROCESS | 1640 | Values: 369.8 378.9 380.2 409.0 453.9 13468.2 18620.8 19516.2 21070.5 21075.1 21569.2 21706.0 21785.1 21939.8 22017.8 5909.2 6838.2 6853.1 6855.8 6863.6 6500.2 6618.4 6643.9 6654.9 6676.9 6694.6 6708.7 6708.9 6722.2 6730.0 5928.4 6147.9 6649.1 6699.5 6707.8 6283.4 6500.4 6509.6 6514.8 6549.8 25596.6 25666.4 26141.3 26358.7 26386.1 2901.4 2916.6 2921.8 2922.9 2961.4 15205.8 15569.0 15701.4 16083.4 16411.5 2057.7 2090.0 2097.6 2124.2 2200.0 149437.0 166519.2 171771.1 173217.3 175830.0 16578.5 21533.0 21544.2 21545.8 21811.2 29103.9 30406.7 33538.7 33601.3 33715.5 10179.9 10181.0 10298.0 10388.7 10397.8 8567.3 8607.9 8617.0 8619.3 8759.6 9499.3 9518.4 9596.9 9664.9 9718.3 3923.1 4128.9 4260.3 4372.2 4402.2 996.9 1007.5 1016.3 1018.0 1056.6 20490.0 20935.0 20950.9 20992.7 21145.0 951.7 1003.7 1032.5 1033.2 1034.1 7549.0 10536.3 10909.2 10972.6 11016.1 728964.0 757543.0 758116.0 767578.0 772324.0 169782.2 171444.0 174006.0 179531.1 181953.0 1303.3 1308.4 1350.6 1355.5 1365.0 15201.7 20265.8 20741.6 20843.9 20873.4 38316.7 55942.0 67349.0 68576.0 69383.3 92255.5 92520.0 92831.0 93341.5 94052.6 11631.7 11687.9 11869.4 11890.0 12197.8 13019.3 13207.6 13328.5 13471.1 13647.1 10199.0 10888.8 11279.7 11307.0 11420.3 11130.4 11248.0 11249.4 11304.0 11361.6 11391.0 11569.2 11818.4 11837.3 11915.0 11725.0 11868.9 11896.9 12113.0 12137.5 11152.4 11279.3 11345.6 11532.3 11631.8 05:41:56 INFO - PROCESS | 1640 | 05:41:56 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:6737.69 Median:2431.55 stddev:4189.38 (172.3%) stddev-sans-first:4182.10 05:41:56 INFO - PROCESS | 1640 | Values: 1423.4 1513.7 1585.5 1600.4 1604.9 10710.7 11159.7 11367.7 11415.4 11499.8 10804.1 10949.1 10966.9 10979.0 11005.6 2358.7 2410.0 2414.9 2415.7 2418.2 2397.2 2430.6 2432.5 2441.8 2446.4 2394.5 2413.8 2419.7 2422.2 2424.8 2359.1 2376.1 2379.1 2387.2 2387.8 2326.7 2349.5 2401.6 2406.7 2408.8 10999.7 11070.3 11078.7 11083.6 11105.9 5882.6 6010.3 6036.0 6038.5 6058.1 3144.7 3510.2 3663.1 3707.2 3756.1 1179.1 1181.8 1213.1 1254.4 1260.8 11828.9 12325.3 13734.7 15646.9 15772.9 4639.6 4678.5 4825.9 4829.8 4833.2 11761.2 11793.4 11815.0 11831.7 11842.9 5819.4 5928.8 5942.3 5949.3 5962.1 5044.7 5110.5 5147.4 5167.5 5217.5 3444.5 3909.8 3914.2 3925.0 4024.8 969.9 1027.9 1069.2 1069.3 1074.1 887.4 928.4 938.8 954.2 1007.2 6132.8 6473.2 6505.9 6591.2 6759.5 985.2 992.6 994.6 997.4 1002.3 3510.0 4191.5 4216.6 4223.9 4247.6 13525.4 13740.9 13870.5 13880.7 13938.5 13161.8 13529.0 13753.5 13767.0 13807.4 8303.7 8749.0 10318.7 10333.6 10382.9 6106.2 6995.2 7075.9 7079.4 7116.6 12079.0 12083.4 12085.1 12086.0 12181.5 12510.3 12674.3 12969.5 12985.6 13146.9 8979.9 9170.1 9269.7 9511.3 9537.6 10384.2 10548.3 10552.8 10660.2 10694.3 9280.5 9296.6 9311.7 9329.4 9435.9 9012.6 9036.9 9059.8 9069.4 9133.2 5788.9 7446.2 7759.6 7781.9 7787.5 5685.3 5766.0 5853.3 5881.8 5970.0 5623.6 5846.4 5920.4 5942.1 5957.7 05:41:56 INFO - PROCESS | 1640 | 05:41:56 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4717.83 Median:5092.78 stddev:1323.62 (26.0%) stddev-sans-first:1286.42 05:41:56 INFO - PROCESS | 1640 | Values: 366.8 377.6 380.8 399.7 402.6 4887.8 5244.9 5381.6 5417.2 5457.0 5129.2 5292.8 5369.0 5380.7 5402.1 3713.5 3930.0 4072.6 4446.6 4454.6 3479.6 4212.7 4421.4 4426.8 4445.9 3963.8 4108.7 4423.4 4463.6 4493.9 3573.5 4341.5 4371.9 4389.8 4500.7 3871.4 4079.0 4095.8 4109.0 4110.7 4694.2 4888.8 5265.7 5268.2 5280.5 4407.7 4733.3 4928.7 5149.7 5176.6 4886.8 4990.4 5646.8 5695.6 5765.9 4032.8 4168.3 4275.8 4458.0 4582.8 4994.0 5203.6 5367.8 5644.6 6117.7 3907.7 4127.4 4150.7 4395.7 4575.5 4284.6 4623.0 4743.5 4764.8 4787.9 4838.3 5070.4 5088.8 5096.8 5111.6 4653.0 4922.8 5646.8 5713.4 5791.5 1410.8 1546.0 1595.8 1603.9 1634.2 1096.9 1114.3 1128.8 1211.2 1213.8 4972.5 5247.8 5349.8 5350.4 5361.0 5035.9 5821.6 5849.4 5899.7 5983.9 4597.5 4982.4 5360.8 5364.9 5365.9 2779.7 2882.8 3068.0 3077.8 3100.3 5586.3 5711.8 5773.4 5805.6 5936.6 4976.7 5165.4 5377.6 6025.9 6119.4 5505.5 5772.4 6006.2 6074.5 6162.4 5417.3 5640.9 5708.2 5752.8 5956.9 5023.0 5301.0 5313.1 5674.2 6003.0 5306.6 5449.7 5468.8 6106.2 6345.5 4725.9 4730.5 4769.0 4775.2 4804.9 5702.9 5973.8 6016.9 6037.9 6056.3 5540.8 5579.9 5794.7 5850.7 5886.8 4806.4 4933.9 5423.3 5610.7 5781.7 4747.9 4950.5 5174.7 5212.5 5693.5 5519.0 5785.8 5785.9 5788.8 5865.9 4426.9 4593.9 5183.1 5400.4 5493.9 05:41:56 INFO - PROCESS | 1640 | 05:41:56 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20879.35 Median:3883.51 stddev:30691.78 (790.3%) stddev-sans-first:30740.47 05:41:56 INFO - PROCESS | 1640 | Values: 698.9 706.3 751.3 793.3 864.3 15448.4 15632.1 16287.0 16301.3 16338.9 13255.8 15018.7 15496.4 15545.3 15585.2 666.7 688.8 913.8 952.9 955.8 924.8 949.6 960.2 962.5 965.4 991.8 992.6 993.1 993.7 993.9 986.2 987.9 988.5 989.7 991.1 726.5 734.7 737.0 737.5 738.9 15553.6 15654.1 15824.9 16120.3 16122.3 14565.9 14688.6 14786.9 15116.0 15192.1 11738.8 11818.6 11985.8 12012.5 12086.0 3682.0 3752.1 3756.1 3813.1 3953.9 108914.0 115221.4 115623.4 116115.4 116343.4 10481.8 10754.6 10903.0 11101.6 11104.2 22710.0 22935.3 23150.9 23189.2 23386.8 7885.6 7930.8 7943.0 8032.6 8139.1 4367.7 4386.8 4418.2 4422.1 4475.6 3500.4 3530.0 3595.5 3596.4 3612.7 565.5 565.6 568.2 576.2 576.2 687.1 699.7 700.7 706.6 713.5 10057.4 10201.1 10224.2 10276.8 10451.9 460.9 543.5 576.3 587.9 594.1 4841.7 4968.5 4973.1 4981.0 4982.3 83210.0 106876.5 108697.5 108786.0 109041.0 91227.0 93157.0 94676.0 94935.1 96094.1 59586.4 78986.0 79884.6 80669.6 81549.8 7359.0 9610.0 9635.7 9716.6 9758.1 54184.0 54288.4 54519.3 55245.0 56384.7 67905.0 68131.7 69323.7 69732.0 70964.0 9640.6 9753.9 10561.2 11844.5 11860.1 13026.0 13172.0 13232.1 13250.1 13410.3 10698.5 11755.8 11866.4 12139.2 12157.6 11780.9 12094.0 12153.9 12263.9 12551.2 11818.2 11863.3 11881.7 11984.8 12324.4 10202.2 10473.6 10584.4 10892.9 11093.7 10280.0 10287.7 10288.6 10299.4 10340.9 05:41:56 INFO - PROCESS | 1640 | -------- Summary: end -------- 05:41:56 INFO - PROCESS | 1640 | 05:41:57 INFO - TEST-INFO | 1640: exit 0 05:41:57 INFO - TEST-OK | dromaeo_css | took 1116699ms 05:41:57 INFO - TEST-START | kraken 05:41:57 INFO - operating with platform_type : mac_ 05:41:57 INFO - Initialising browser for kraken test... 05:41:57 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpNg7p7b/profile http://localhost:49241/getInfo.html) 05:41:59 INFO - PROCESS | 1653 | 2017-03-22 05:41:59.749 plugin-container[1654:10322] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 05:41:59 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:41:59 INFO - PROCESS | 1653 | 2017-03-22 05:41:59.752 plugin-container[1654:10322] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 05:41:59 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:41:59 INFO - PROCESS | 1653 | 2017-03-22 05:41:59.752 plugin-container[1654:10322] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:41:59 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 05:41:59 INFO - PROCESS | 1653 | colorDepth:24 05:41:59 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 05:41:59 INFO - PROCESS | 1653 | __metrics 05:41:59 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 05:42:00 INFO - TEST-INFO | 1653: exit 0 05:42:00 INFO - Browser initialized. 05:42:00 INFO - Running cycle 1/1 for kraken test... 05:42:00 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpNg7p7b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:42:02 INFO - PROCESS | 1655 | 2017-03-22 05:42:02.987 plugin-container[1656:10447] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 05:42:02 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:02 INFO - PROCESS | 1655 | 2017-03-22 05:42:02.988 plugin-container[1656:10447] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 05:42:02 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:02 INFO - PROCESS | 1655 | 2017-03-22 05:42:02.988 plugin-container[1656:10447] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:42:12 INFO - PROCESS | 1655 | RSS: Main: 220823552 05:42:12 INFO - PROCESS | 1655 | 05:42:15 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 05:42:16 INFO - PROCESS | 1655 | RSS: Main: 235671552 05:42:16 INFO - PROCESS | 1655 | 05:42:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 05:42:19 INFO - PROCESS | 1655 | RSS: Main: 243838976 05:42:19 INFO - PROCESS | 1655 | 05:42:23 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 05:42:24 INFO - PROCESS | 1655 | RSS: Main: 245399552 05:42:24 INFO - PROCESS | 1655 | 05:42:26 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 05:42:26 INFO - PROCESS | 1655 | RSS: Main: 247119872 05:42:26 INFO - PROCESS | 1655 | 05:42:28 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:42:29 INFO - PROCESS | 1655 | RSS: Main: 248840192 05:42:29 INFO - PROCESS | 1655 | 05:42:32 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 05:42:32 INFO - PROCESS | 1655 | RSS: Main: 252649472 05:42:32 INFO - PROCESS | 1655 | 05:42:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 05:42:36 INFO - PROCESS | 1655 | RSS: Main: 252485632 05:42:36 INFO - PROCESS | 1655 | 05:42:40 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 05:42:40 INFO - PROCESS | 1655 | RSS: Main: 252166144 05:42:40 INFO - PROCESS | 1655 | 05:42:42 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:42:43 INFO - PROCESS | 1655 | RSS: Main: 251772928 05:42:43 INFO - PROCESS | 1655 | 05:42:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:42:45 INFO - PROCESS | 1655 | RSS: Main: 251809792 05:42:45 INFO - PROCESS | 1655 | 05:42:47 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:42:48 INFO - PROCESS | 1655 | RSS: Main: 251789312 05:42:48 INFO - PROCESS | 1655 | 05:42:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:42:52 INFO - PROCESS | 1655 | RSS: Main: 252223488 05:42:52 INFO - PROCESS | 1655 | 05:42:55 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:42:55 INFO - PROCESS | 1655 | RSS: Main: 251973632 05:42:55 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 05:42:57 INFO - PROCESS | 1655 | RSS: Main: 254038016 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | __start_tp_report 05:42:57 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 05:42:57 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 05:42:57 INFO - PROCESS | 1655 | |i|pagename|runs| 05:42:57 INFO - PROCESS | 1655 | |0;ai-astar;107;121;120;119;118;121;119;119;119;119 05:42:57 INFO - PROCESS | 1655 | |1;audio-beat-detection;146;110;112;144;113;150;145;142;130;111 05:42:57 INFO - PROCESS | 1655 | |2;audio-dft;213;280;252;199;198;224;218;222;268;278 05:42:57 INFO - PROCESS | 1655 | |3;audio-fft;84;64;77;102;61;62;61;62;65;64 05:42:57 INFO - PROCESS | 1655 | |4;audio-oscillator;74;70;86;71;73;71;81;72;71;85 05:42:57 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;87;88;89;94;88;89;89;90;85;138 05:42:57 INFO - PROCESS | 1655 | |6;imaging-darkroom;120;97;158;113;102;153;156;96;97;102 05:42:57 INFO - PROCESS | 1655 | |7;imaging-desaturate;79;149;142;101;84;146;139;90;100;124 05:42:57 INFO - PROCESS | 1655 | |8;json-parse-financial;63;64;66;65;69;69;68;67;69;83 05:42:57 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;58;63;56;66;65;73;52;58;58;57 05:42:57 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;85;83;85;86;75;92;102;102;87;89 05:42:57 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;145;150;147;137;139;145;142;160;204;165 05:42:57 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;195;237;193;169;169;165;167;164;177;179 05:42:57 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;66;66;65;77;85;84;64;66;64;67 05:42:57 INFO - PROCESS | 1655 | __end_tp_report 05:42:57 INFO - PROCESS | 1655 | __start_cc_report 05:42:57 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1008 05:42:57 INFO - PROCESS | 1655 | __end_cc_report 05:42:57 INFO - PROCESS | 1655 | __startTimestamp1490186577967__endTimestamp 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | ------- Summary: start ------- 05:42:57 INFO - PROCESS | 1655 | Number of tests: 14 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:118.20 Median:119.00 stddev:4.05 (3.4%) stddev-sans-first:1.01 05:42:57 INFO - PROCESS | 1655 | Values: 107.0 121.0 120.0 119.0 118.0 121.0 119.0 119.0 119.0 119.0 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:130.30 Median:143.00 stddev:16.98 (11.9%) stddev-sans-first:17.03 05:42:57 INFO - PROCESS | 1655 | Values: 146.0 110.0 112.0 144.0 113.0 150.0 145.0 142.0 130.0 111.0 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:235.20 Median:238.00 stddev:31.59 (13.3%) stddev-sans-first:32.47 05:42:57 INFO - PROCESS | 1655 | Values: 213.0 280.0 252.0 199.0 198.0 224.0 218.0 222.0 268.0 278.0 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:70.20 Median:64.00 stddev:13.56 (21.2%) stddev-sans-first:13.44 05:42:57 INFO - PROCESS | 1655 | Values: 84.0 64.0 77.0 102.0 61.0 62.0 61.0 62.0 65.0 64.0 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:75.40 Median:73.50 stddev:6.17 (8.4%) stddev-sans-first:6.52 05:42:57 INFO - PROCESS | 1655 | Values: 74.0 70.0 86.0 71.0 73.0 71.0 81.0 72.0 71.0 85.0 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:93.70 Median:89.00 stddev:15.73 (17.7%) stddev-sans-first:16.50 05:42:57 INFO - PROCESS | 1655 | Values: 87.0 88.0 89.0 94.0 88.0 89.0 89.0 90.0 85.0 138.0 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:119.40 Median:157.00 stddev:26.15 (16.7%) stddev-sans-first:27.74 05:42:57 INFO - PROCESS | 1655 | Values: 120.0 97.0 158.0 113.0 102.0 153.0 156.0 96.0 97.0 102.0 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:115.40 Median:147.50 stddev:27.50 (18.6%) stddev-sans-first:25.82 05:42:57 INFO - PROCESS | 1655 | Values: 79.0 149.0 142.0 101.0 84.0 146.0 139.0 90.0 100.0 124.0 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:68.30 Median:68.50 stddev:5.60 (8.2%) stddev-sans-first:5.60 05:42:57 INFO - PROCESS | 1655 | Values: 63.0 64.0 66.0 65.0 69.0 69.0 68.0 67.0 69.0 83.0 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:60.50 stddev:6.11 (10.1%) stddev-sans-first:6.41 05:42:57 INFO - PROCESS | 1655 | Values: 58.0 63.0 56.0 66.0 65.0 73.0 52.0 58.0 58.0 57.0 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:85.50 stddev:8.32 (9.7%) stddev-sans-first:8.72 05:42:57 INFO - PROCESS | 1655 | Values: 85.0 83.0 85.0 86.0 75.0 92.0 102.0 102.0 87.0 89.0 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:153.40 Median:148.50 stddev:19.83 (13.4%) stddev-sans-first:20.80 05:42:57 INFO - PROCESS | 1655 | Values: 145.0 150.0 147.0 137.0 139.0 145.0 142.0 160.0 204.0 165.0 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.50 Median:178.00 stddev:22.42 (12.6%) stddev-sans-first:23.24 05:42:57 INFO - PROCESS | 1655 | Values: 195.0 237.0 193.0 169.0 169.0 165.0 167.0 164.0 177.0 179.0 05:42:57 INFO - PROCESS | 1655 | 05:42:57 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:66.50 stddev:8.32 (12.5%) stddev-sans-first:8.67 05:42:57 INFO - PROCESS | 1655 | Values: 66.0 66.0 65.0 77.0 85.0 84.0 64.0 66.0 64.0 67.0 05:42:57 INFO - PROCESS | 1655 | -------- Summary: end -------- 05:42:57 INFO - PROCESS | 1655 | 05:42:58 INFO - TEST-INFO | 1655: exit 0 05:42:58 INFO - TEST-OK | kraken | took 61699ms 05:42:58 INFO - SUITE-END | took 1178s 05:42:59 INFO - Completed test suite (00:19:38) 05:42:59 INFO - javascript benchmark 05:42:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7755.155034969576, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3460.1003739027847, 3482.0076278260685, 3486.8779592714254, 3515.0685068456864, 3515.753897227195, 6022.187004754357, 6084.3003054648725, 6088.665123418215, 6117.6329420234815, 6137.140800287963, 4883.316335713003, 6093.329733729286, 6101.755929762815, 6115.082737589364, 6149.846253843656, 5431.456854314557, 5438.102713052342, 5498.642588231752, 5527.640703354269, 5625.6905870177125, 5478.986387518302, 5553.194786755918, 5557.527610153142, 5562.805301814437, 5571.303587051619, 4469.681443974036, 5267.710275934822, 5450.991566560159, 5454.427285135043, 5455.645383050109, 4929.457759646436, 5379.488948549882, 5381.892362152774, 5414.07960646687, 5440.292761940962, 5058.671186372911, 5332.706701131437, 5356.517913387814, 5369.355677318707, 5416.9729151353995, 5834.854128646775, 5976.223090998186, 6042.395760423967, 6083.361247069039, 6093.847653808691, 6217.316095229521, 6324.177856878577, 6340.682965851689, 6375.808725738235, 6389.712462939133, 5575.661841158133, 6432.646204458753, 6509.3490650935, 6531.0, 6540.4440622547145, 6218.129461875341, 6222.284437289679, 6236.563440559117, 6240.032794916795, 6250.718717657716, 6243.219597550306, 6341.302456729756, 6558.114654521674, 6604.306547812506, 6646.435053020451, 6325.335839736854, 6345.33373995733, 6348.873022539523, 6377.936220637735, 6378.968105159444, 6508.544401891822, 6524.151860258136, 6525.608463492205, 6531.673416329259, 6542.345765423514, 6246.437820596168, 6260.154879091265, 6266.749330026839, 6358.364163583605, 6427.132337134427, 4892.730899800474, 6333.20834895638, 6368.777092801823, 6377.202849643794, 6379.3620637936765, 5191.325127733446, 5201.167813149954, 5206.219067139884, 5228.895422091537, 5234.947650523446, 4518.638508919278, 4529.13946350199, 4603.033362993742, 4618.330342100377, 4653.115907977473, 6150.415710507495, 6201.441870231611, 6211.409916057967, 6242.563020588515, 6253.53098517613, 6525.1517302750335, 6533.738650454024, 6543.378379053982, 6565.606063636197, 6570.113034740344, 5961.165436838846, 5992.700364981733, 6118.265808103056, 6216.222972128484, 6244.594101383395, 5668.206451096849, 5669.461401166883, 5690.5447564194765, 5735.913961290584, 5742.7415766290615, 6662.500312476487, 6724.831879203059, 6732.999999999902, 6737.629430381376, 6745.730170793114, 6455.838604034937, 6504.902426463702, 6512.73949042033, 6516.446102081376, 6528.869422611616, 6345.809625711236, 6417.839554011187, 6658.334166583303, 6731.865362692621, 6759.932400675931, 6372.458341041063, 6439.195100612424, 6441.420272175339, 6456.483481321389, 6461.483081353386, 6417.679116044085, 6484.675766211765, 6487.351264873475, 6503.804885853432, 6516.609003459807, 5368.060589396762, 5700.087985922232, 6132.2947860996555, 6204.100405441095, 6374.043893416025, 6228.750849966132, 6259.405356491126, 6321.589096708699, 6342.302346741947, 6345.460635846005, 5582.27430434041, 5704.942950570441, 6209.944309467311, 6279.591826531261, 6283.37166283368, 6218.626882387071, 6263.906041409291, 6281.748730050746, 6314.463270622048, 6316.936830631818, 6145.494353883327, 6166.660833654013, 6180.165677633642, 6255.186825712628, 6259.436650701458], "value": 5920.785995964781, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.7428377216656, 806.1938061938063, 827.9627416766247, 894.8747175395446, 972.6206779356048, 37854.810725946365, 38852.22295554087, 39901.80049099758, 39942.80028599853, 40183.598164018316, 38786.8060659697, 38804.223915521674, 38980.41529377062, 39063.41404878922, 39157.21685566287, 7862.567558784279, 8008.0, 10509.78980420391, 10819.350838949687, 10929.726756831064, 10709.410982395964, 10737.570497180104, 10765.300255483407, 10774.138068954464, 10776.78446431071, 10760.73098172544, 10795.5681772729, 10798.136149108092, 10805.5677772889, 10818.621348252813, 10732.624358147466, 10749.570017199303, 10768.623098191565, 10795.24433289674, 10800.945995269973, 10477.633282835064, 10517.84223236652, 10567.630132945387, 10585.629502967437, 10600.310979786289, 33940.66059339424, 33973.0, 34068.82965585181, 34223.80216692404, 34245.657543424495, 3633.6911362534215, 3681.742278040538, 3713.164537978933, 3713.387291096966, 3764.5670747863796, 24991.875040624862, 25026.0, 25193.748062519506, 26328.341791455176, 26373.0, 2689.8924043038255, 3022.863971121305, 3097.1018404662846, 3256.0231930421014, 3257.5439438478634, 346488.0, 351314.0, 352230.0, 355782.0, 424979.0, 42812.143757124686, 42826.0, 42969.785151074044, 43041.923951900826, 43082.13835723268, 45672.0, 45857.312140317925, 45956.08087838224, 46038.0, 46083.53916460792, 13528.255945922872, 13588.660283492794, 13628.727425451634, 13643.38604762788, 13751.106178098591, 11804.232724872856, 11876.10929180315, 11880.762384752257, 11905.285682859056, 11919.821202681967, 11805.704857378463, 11839.230450020894, 11873.465694043789, 11908.345041022827, 11985.520579176735, 5597.412271711411, 5615.438456154352, 5640.689762063126, 5641.661500309994, 5642.7178641067785, 1108.7283615514089, 1136.8010598145293, 1146.529922731676, 1159.8633339327507, 1195.9103067269816, 26141.869290653423, 26417.867910660323, 26601.600975985377, 26754.063607773634, 26814.0, 1110.2228440091806, 1162.8372027916098, 1213.696575856036, 1245.1968480330136, 1254.974900502003, 10715.571377144826, 13647.727045459034, 13936.303184840717, 13974.650633734238, 14003.579892603238, 412949.0, 415286.0, 415733.0, 416153.0, 416297.0, 54441.45558544443, 54635.0, 54669.0, 54709.632259193044, 54732.0, 1378.620879258212, 1439.1077531930061, 1439.3163247457373, 1441.6323837421392, 1442.1419255542935, 20767.0, 24886.626700599867, 25530.361740956625, 25885.6117158239, 26017.699115043793, 44131.55868441275, 44200.55799441964, 44292.55707442884, 44316.778416107714, 44368.11263774771, 392181.0390948141, 393491.0, 394089.0, 394300.0, 394329.0, 30646.540301895042, 30687.693123068482, 38689.41965870571, 39762.40356394714, 39800.606978755604, 48098.5190148108, 48145.03709925712, 48161.79595510141, 48961.02077958492, 49796.50203497919, 29466.85266573739, 37594.436083458226, 39996.0, 40291.395629065, 40595.7970210147, 32857.50713739343, 39162.60837391589, 39352.803235983636, 40485.79757101196, 40663.39004914988, 39510.20979580335, 39739.205215894945, 39934.60065399309, 39938.2012359757, 40085.79957100196, 30435.54346684846, 32937.50593741048, 34824.82587587046, 34857.65142348645, 35084.64915350916, 26369.340766480993, 26399.472010560065, 26628.201153965412, 26743.19770406891, 26746.03449727407], "value": 18816.824163040754, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.805851927738, 378.8749712594844, 380.17122672573794, 408.96932730045256, 453.8570350339643, 13468.191908485473, 18620.813791862078, 19516.21935122595, 21070.473238169052, 21075.05162267698, 21569.24507642233, 21706.023228954695, 21785.128594856185, 21939.780602194012, 22017.779822201774, 5909.20225769522, 6838.247792742794, 6853.143357080365, 6855.794326170223, 6863.622500762456, 6500.187476565429, 6618.437432818217, 6643.933560664404, 6654.936969539434, 6676.899846502306, 6694.564853284545, 6708.698108585126, 6708.899366509506, 6722.193336799591, 6730.0, 5928.407159284058, 6147.862645410613, 6649.069130321759, 6699.531032827704, 6707.798766037026, 6283.403076707706, 6500.414962653336, 6509.576877502947, 6514.794762968866, 6549.836254093638, 25596.616050759254, 25666.358341041436, 26141.346466338302, 26358.73641263582, 26386.076487323044, 2901.376204116104, 2916.6208392908784, 2921.7516511096583, 2922.901724172384, 2961.422522608092, 15205.847941520553, 15569.0, 15701.371945122295, 16083.437079702271, 16411.507654770376, 2057.711920331155, 2089.9791002089937, 2097.590969760897, 2124.2034237161065, 2200.0, 149437.0, 166519.1674041622, 171771.14114429473, 173217.26782732137, 175830.0, 16578.50264492067, 21533.0, 21544.24595139194, 21545.78454215469, 21811.23660671869, 29103.85448072746, 30406.695933040388, 33538.66461335404, 33601.327973440886, 33715.49426758601, 10179.898201017895, 10181.0, 10297.970202979792, 10388.688339349832, 10397.792044159074, 8567.314614830799, 8607.870881936777, 8616.956915215384, 8619.310455163572, 8759.649614015496, 9499.335046546676, 9518.428894266366, 9596.856047159297, 9664.855027174597, 9718.319717619841, 3923.0765389436074, 4128.926479115392, 4260.297050986553, 4372.1692878353015, 4402.173098164226, 996.8654231678776, 1007.5415685862915, 1016.3089099412323, 1017.9847302290472, 1056.6090546497844, 20489.97550122488, 20934.953252337324, 20950.895245523676, 20992.685109723367, 21145.04847281876, 951.7192428233687, 1003.7252689084821, 1032.5198782566142, 1033.214756784849, 1034.1468288661883, 7549.0, 10536.315139515908, 10909.236353455182, 10972.615958441416, 11016.06363459115, 728964.0, 757543.0, 758116.0, 767578.0, 772324.0, 169782.15108924377, 171444.0, 174006.0, 179531.10234448744, 181953.0, 1303.3027330378202, 1308.4242933109401, 1350.567818298135, 1355.5323413422354, 1365.0035474103959, 15201.695966080837, 20265.78405295687, 20741.58516829685, 20843.89578052161, 20873.373798786062, 38316.65891693778, 55942.0, 67348.98976515258, 68576.0, 69383.3061669397, 92255.53872230597, 92520.0, 92831.0, 93341.53329233582, 94052.58921116326, 11631.709207269547, 11687.88312116902, 11869.406529673655, 11890.0, 12197.817032744693, 13019.349032548145, 13207.566032831724, 13328.533501327407, 13471.12437691547, 13647.11293765902, 10199.031092046243, 10888.836667450152, 11279.718007049561, 11307.038901693448, 11420.257683250562, 11130.387828669178, 11248.0, 11249.381284029458, 11304.039156671446, 11361.602343917923, 11391.0, 11569.247998880383, 11818.40907954616, 11837.348945807718, 11915.046796256105, 11725.0, 11868.940655296668, 11896.940515297369, 12113.001354776716, 12137.453814578541, 11152.442377881236, 11279.266847654877, 11345.602903898323, 11532.308061516336, 11631.825522616999], "value": 11683.39438635786, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.380829339237, 1513.7284680868077, 1585.4688679292444, 1600.359856057577, 1604.8876578639492, 10710.678679639605, 11159.665210043691, 11367.715807104827, 11415.372154531497, 11499.827502587468, 10804.0816530595, 10949.069329107035, 10966.945165274163, 10979.0, 11005.559777608885, 2358.6579945907874, 2409.987950060256, 2414.9396265093337, 2415.698037745282, 2418.22616762636, 2397.1729753235195, 2430.635404689293, 2432.4648577312964, 2441.7558244175616, 2446.449548851503, 2394.5330660521204, 2413.81896357773, 2419.661247425362, 2422.188566830114, 2424.75752424757, 2359.0563774490342, 2376.061455724988, 2379.0602711928777, 2387.2241521505407, 2387.8328517003815, 2326.732425771041, 2349.5418393413293, 2401.639754036891, 2406.6991626046743, 2408.759124087595, 10999.670009899715, 11070.335779853234, 11078.723031924186, 11083.556657733681, 11105.888941110567, 5882.647041177543, 6010.338862725097, 6036.0, 6038.516918646542, 6058.121572372025, 3144.701253380948, 3510.2102027043966, 3663.1025398777206, 3707.2029513654693, 3756.1173124315765, 1179.1156632525606, 1181.8168183931505, 1213.1265488848014, 1254.3728135932033, 1260.8360913081333, 11828.881711182865, 12325.322107284186, 13734.725305493834, 15646.9217653911, 15772.921135394365, 4639.582437580634, 4678.532146785362, 4825.914169311876, 4829.830955916596, 4833.202521583899, 11761.235519691363, 11793.410329483491, 11814.99572536327, 11831.704207394883, 11842.940785296018, 5819.447152520504, 5928.822135335947, 5942.3166335871065, 5949.286085669661, 5962.135490353874, 5044.722540260321, 5110.4634013428795, 5147.433782283946, 5167.457416971164, 5217.452167522431, 3444.50054742066, 3909.843606255718, 3914.197589494147, 3924.9607503925167, 4024.839006439768, 969.8797888438864, 1027.897210278981, 1069.187417562658, 1069.2889228663007, 1074.065562960214, 887.4143065576689, 928.3872644054892, 938.8216238914584, 954.19370631816, 1007.1841808135433, 6132.78535251273, 6473.158489396348, 6505.934940650533, 6591.209054913441, 6759.526833121634, 985.192142443204, 992.5682328187262, 994.5872462927948, 997.4314640654757, 1002.2883752535781, 3510.0, 4191.538930717618, 4216.599423054805, 4223.852165174204, 4247.638950689165, 13525.39135738894, 13740.862591374158, 13870.514531991534, 13880.652983675489, 13938.512152074629, 13161.802572961413, 13529.0, 13753.518626848012, 13767.036307458382, 13807.447702091804, 8303.709370172015, 8748.95012598505, 10318.742031449123, 10333.638322658671, 10382.896171038345, 6106.175666284995, 6995.195552511526, 7075.893861591977, 7079.362857342658, 7116.573005619679, 12079.0, 12083.39583020828, 12085.093617978511, 12086.0, 12181.451834667638, 12510.311932843417, 12674.302913339854, 12969.54606588765, 12985.610431687064, 13146.868531314565, 8979.910200898168, 9170.082991700776, 9269.72190834276, 9511.334206605472, 9537.570809313458, 10384.191231259885, 10548.261621686715, 10552.841707374242, 10660.200484963503, 10694.251402401756, 9280.535973201448, 9296.628134874529, 9311.67409140704, 9329.440233586007, 9435.858462122937, 9012.639494420411, 9036.864447033431, 9059.773505662411, 9069.365144439826, 9133.178013978773, 5788.884222315614, 7446.218147094477, 7759.573223472763, 7781.949436826182, 7787.532748035136, 5685.34618518859, 5765.971170144123, 5853.2976042875125, 5881.823545293648, 5970.0447928333, 5623.606347555632, 5846.386129456346, 5920.437558431942, 5942.1383899335215, 5957.731902064331], "value": 5147.017991957596, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.7616049567781, 377.59597230962845, 380.7734398033171, 399.6782590015039, 402.5793046266651, 4887.755612219384, 5244.947550524494, 5381.5963802714805, 5417.160340147283, 5457.016378044025, 5129.179331306992, 5292.841214763554, 5368.973155134219, 5380.7309634518215, 5402.109871218899, 3713.5172427584375, 3930.0, 4072.552019277877, 4446.622037126848, 4454.599086082252, 3479.599846017715, 4212.747235165884, 4421.425214722081, 4426.845060422886, 4445.888852778674, 3963.7819919904396, 4108.6507646849905, 4423.447069116361, 4463.616278953518, 4493.910121797562, 3573.4639804029343, 4341.544137865541, 4371.912561748779, 4389.846355377546, 4500.7074540154945, 3871.380579107329, 4079.0, 4095.8361665533344, 4108.979455102735, 4110.677467231035, 4694.230404061586, 4888.804447822083, 5265.6840589564745, 5268.236105764643, 5280.471952804727, 4407.713498622579, 4733.296013435161, 4928.67963582366, 5149.716765577892, 5176.5858731301405, 4886.804527818883, 4990.4011518618, 5646.830595082154, 5695.572832037572, 5765.942340576583, 4032.7983600819844, 4168.290221702687, 4275.828966841323, 4458.0415210729525, 4582.839600613996, 4994.0, 5203.583713302926, 5367.75845086972, 5644.604877658566, 6117.7152797912495, 3907.7459965102175, 4127.379114262465, 4150.730202536825, 4395.7142785718825, 4575.519570445122, 4284.635805956528, 4622.959834037382, 4743.501932297128, 4764.76176191196, 4787.904241915212, 4838.298446725205, 5070.391553013662, 5088.796448142107, 5096.770645320969, 5111.616628752859, 4652.975565882203, 4922.75386230687, 5646.830595082154, 5713.4000929902595, 5791.536677065825, 1410.7672234081467, 1545.984540154584, 1595.755310857533, 1603.8636715879047, 1634.166575046711, 1096.9195342587545, 1114.2840026359477, 1128.8250321200228, 1211.1703483114095, 1213.8239955206445, 4972.5276098770555, 5247.790088396498, 5349.833317506416, 5350.438203988603, 5361.0, 5035.874103147451, 5821.592488525763, 5849.385814489502, 5899.7050147492455, 5983.940160598426, 4597.494275629678, 4982.377202849644, 5360.758765855546, 5364.946350536445, 5365.865853353697, 2779.6803367612983, 2882.798204125691, 3068.0335694256682, 3077.7999430036975, 3100.3024319527926, 5586.329640443091, 5711.771529138788, 5773.422657734277, 5805.622634528742, 5936.643801371932, 4976.676516026447, 5165.431802501722, 5377.623566350396, 6025.855087533355, 6119.357467465941, 5505.476979687003, 5772.3650398456975, 6006.168118723831, 6074.54440916933, 6162.352952939967, 5417.349918009864, 5640.88718225625, 5708.230448560959, 5752.827415177551, 5956.851078723066, 5023.0, 5301.045811753921, 5313.123334649894, 5674.177244299636, 6003.009503431885, 5306.628536002597, 5449.6730196188355, 5468.792920035708, 6106.236720409949, 6345.524085693499, 4725.881852953704, 4730.45216087934, 4769.046190761931, 4775.185429536754, 4804.918893248991, 5702.857428564153, 5973.759840059977, 6016.909746353721, 6037.879242415039, 6056.3035250946705, 5540.778368865362, 5579.888402231852, 5794.710264486843, 5850.7074646266665, 5886.764529418775, 4806.375171227718, 4933.876653083702, 5423.322084739408, 5610.747516361692, 5781.710914454345, 4747.928781068218, 4950.504949505021, 5174.7153906535505, 5212.50481204285, 5693.5160511357, 5518.978988887069, 5785.768569257351, 5785.913211301918, 5788.768449261982, 5865.882682346414, 4426.911461770682, 4593.931091033704, 5183.144781111226, 5400.42927751411, 5493.945060549343], "value": 4330.155825929774, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.8881778915375, 706.2972342519195, 751.3200553499082, 793.2781169136088, 864.3387808326629, 15448.382064717398, 15632.14023228722, 16287.0, 16301.266443010063, 16338.918305408486, 13255.806977372034, 15018.699626007474, 15496.380144794222, 15545.300461479232, 15585.220738963035, 666.7266420767462, 688.7864761923795, 913.8492148795457, 952.8523078922743, 955.8040601676639, 924.8196601662678, 949.6438835436711, 960.1502670136944, 962.4706411473696, 965.4352203960691, 991.7867658453431, 992.5632721602472, 993.1062044160242, 993.6571882700493, 993.8807343118835, 986.1519093579516, 987.9407235565889, 988.5353883674661, 989.7228775942748, 991.1030517381781, 726.5023458930649, 734.7318228846486, 736.9705211791522, 737.5169264132002, 738.8670039392907, 15553.611159720984, 15654.139022353766, 15824.920875395665, 16120.274587643584, 16122.274497647633, 14565.927170364186, 14688.632784180374, 14786.852131478656, 15116.0, 15192.088474691554, 11738.823917641243, 11818.645440636794, 11985.760284794342, 12012.519499220021, 12086.033117350678, 3681.9631803681887, 3752.0894690758546, 3756.079760458705, 3813.122981714221, 3953.9011524712114, 108914.0, 115221.42389288083, 115623.42188289089, 116115.41942290319, 116343.4182829089, 10481.842772358421, 10754.623588174376, 10903.0, 11101.5559377624, 11104.222704410775, 22710.0, 22935.311940641808, 23150.884245579, 23189.188378406674, 23386.7661323388, 7885.605719714106, 7930.822605541994, 7942.960285198537, 8032.638531266107, 8139.145389734111, 4367.716098453654, 4386.824527018947, 4418.16054949565, 4422.083544832894, 4475.619572336323, 3500.3524347995703, 3529.982350088233, 3595.4786555949763, 3596.4245720684567, 3612.7290453216115, 565.5164834066895, 565.5927732033012, 568.2158621102851, 576.1789450033768, 576.1990832742491, 687.1410736579276, 699.692135460406, 700.6776882633943, 706.6254884910902, 713.5362014690389, 10057.396556206651, 10201.081902628799, 10224.233182511192, 10276.845847312296, 10451.895481045092, 460.94007778988515, 543.4592580382466, 576.3026737647493, 587.9353271140171, 594.1325664529835, 4841.709497430165, 4968.478309777493, 4973.1297023020825, 4981.0, 4982.327385802942, 83210.0, 106876.46561767296, 108697.45651271693, 108786.0, 109041.0, 91227.0, 93157.0, 94675.99999999862, 94935.050649494, 96094.07811843725, 59586.404135958954, 78986.0, 79884.60634665033, 80669.59665201754, 81549.77675334875, 7358.96320518394, 9609.951950240484, 9635.710928672152, 9716.562754676197, 9758.072983066597, 54184.0, 54288.3713488596, 54519.27403629882, 55245.0, 56384.71807640936, 67905.0, 68131.65934170298, 69323.65338173478, 69731.95402068872, 70964.0, 9640.614375424904, 9753.9024609753, 10561.207909406672, 11844.52621895149, 11860.110491713329, 13026.0, 13172.0, 13232.13991090576, 13250.138740981805, 13410.262435566137, 10698.518566664363, 11755.837984085885, 11866.3961168137, 12139.21095128849, 12157.574484892986, 11780.941095294469, 12094.0, 12153.878461215274, 12263.877361226274, 12551.246925184518, 11818.231814932002, 11863.288202707865, 11881.702957426134, 11984.76030479403, 12324.445399957203, 10202.18382529381, 10473.581056757645, 10584.36493810343, 10892.945535272273, 11093.667189984313, 10280.0, 10287.742806429898, 10288.639897603547, 10299.3820370778, 10340.896591033994], "value": 7498.057808619733, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1580.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 121.0, 120.0, 119.0, 118.0, 121.0, 119.0, 119.0, 119.0, 119.0], "value": 118.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 110.0, 112.0, 144.0, 113.0, 150.0, 145.0, 142.0, 130.0, 111.0], "value": 130.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [213.0, 280.0, 252.0, 199.0, 198.0, 224.0, 218.0, 222.0, 268.0, 278.0], "value": 235.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 64.0, 77.0, 102.0, 61.0, 62.0, 61.0, 62.0, 65.0, 64.0], "value": 70.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 70.0, 86.0, 71.0, 73.0, 71.0, 81.0, 72.0, 71.0, 85.0], "value": 75.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 89.0, 94.0, 88.0, 89.0, 89.0, 90.0, 85.0, 138.0], "value": 93.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 97.0, 158.0, 113.0, 102.0, 153.0, 156.0, 96.0, 97.0, 102.0], "value": 119.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 149.0, 142.0, 101.0, 84.0, 146.0, 139.0, 90.0, 100.0, 124.0], "value": 115.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 66.0, 65.0, 69.0, 69.0, 68.0, 67.0, 69.0, 83.0], "value": 68.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 63.0, 56.0, 66.0, 65.0, 73.0, 52.0, 58.0, 58.0, 57.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 85.0, 86.0, 75.0, 92.0, 102.0, 102.0, 87.0, 89.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 150.0, 147.0, 137.0, 139.0, 145.0, 142.0, 160.0, 204.0, 165.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 237.0, 193.0, 169.0, 169.0, 165.0, 167.0, 164.0, 177.0, 179.0], "value": 181.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 77.0, 85.0, 84.0, 64.0, 66.0, 64.0, 67.0], "value": 70.4, "unit": "score"}]}]} 05:42:59 INFO - Return code: 0 05:42:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:42:59 ERROR - # TBPL SUCCESS # 05:42:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:42:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:42:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:42:59 INFO - 2017-03-22 05:42:59.305 screenresolution[1660:10834] starting screenresolution argv=screenresolution get 05:42:59 INFO - 2017-03-22 05:42:59.307 screenresolution[1660:10834] Display 0: 1600x1200x32@60 05:42:59 INFO - 2017-03-22 05:42:59.314 screenresolution[1661:10837] starting screenresolution argv=screenresolution list 05:42:59 INFO - Available Modes on Display 0 05:42:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:42:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:42:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:42:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:42:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:42:59 INFO - Intel Iris: 05:42:59 INFO - Chipset Model: Intel Iris 05:42:59 INFO - Type: GPU 05:42:59 INFO - Bus: Built-In 05:42:59 INFO - VRAM (Dynamic, Max): 1536 MB 05:42:59 INFO - Vendor: Intel (0x8086) 05:42:59 INFO - Device ID: 0x0a2e 05:42:59 INFO - Revision ID: 0x0009 05:42:59 INFO - Displays: 05:42:59 INFO - Display: 05:42:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:42:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:42:59 INFO - Display Serial Number: 1600x1200 60 05:42:59 INFO - Main Display: Yes 05:42:59 INFO - Mirror: Off 05:42:59 INFO - Online: Yes 05:42:59 INFO - Rotation: Supported 05:42:59 INFO - Return code: 0 05:42:59 INFO - Running post-action listener: _resource_record_post_action 05:42:59 INFO - [mozharness: 2017-03-22 12:42:59.554959Z] Finished run-tests step (success) 05:42:59 INFO - Running post-run listener: _resource_record_post_run 05:42:59 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 38754816; Write bytes: 513584640; Read time: 386; Write time: 4666 05:42:59 INFO - TinderboxPrint: CPU usage
54.5% 05:42:59 INFO - TinderboxPrint: I/O read bytes / time
38,754,816 / 386 05:42:59 INFO - TinderboxPrint: I/O write bytes / time
513,584,640 / 4,666 05:42:59 INFO - TinderboxPrint: CPU idle
2,184.1 (45.5%) 05:42:59 INFO - TinderboxPrint: CPU system
964.4 (20.1%) 05:42:59 INFO - TinderboxPrint: CPU user
1,649.2 (34.4%) 05:42:59 INFO - TinderboxPrint: Swap in / out
247,898,112 / 0 05:42:59 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206578176; Write bytes: 202745856; Read time: 15698; Write time: 433 05:42:59 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 35260928; Write bytes: 299660800; Read time: 356; Write time: 4196 05:43:00 INFO - Running post-run listener: _upload_blobber_files 05:43:00 INFO - Blob upload gear active. 05:43:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:43:00 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 05:43:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:43:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:43:00 INFO - (blobuploader) - INFO - Open directory for files ... 05:43:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:43:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:43:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:01 INFO - (blobuploader) - INFO - Done attempting. 05:43:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:43:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:43:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:02 INFO - (blobuploader) - INFO - Done attempting. 05:43:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:43:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:43:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:03 INFO - (blobuploader) - INFO - Done attempting. 05:43:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:43:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:43:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:05 INFO - (blobuploader) - INFO - Done attempting. 05:43:05 INFO - (blobuploader) - INFO - Iteration through files over. 05:43:05 INFO - Return code: 0 05:43:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:43:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:43:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a63c2b5a38766c9c3be072f617e8919945bb2e49d568f674add0c13a523f26a55099af08f6a237432825b8b1f695c1019615e14f7e491377bca937a8686ebad6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/921e9433e1888ea40e3d04fd25c78fc5b1c5a7c7a1531c98196c7c45264db5610de687ecb8daab62bb50aaa40a9be50c1e4396cfe65b7132d109249966f7d5a0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb73e39b27ed3cef4e0a0c81817cdd06be654dd836d42f1153a4c1a0fa4a5d3b01c4b474a1db70d9588bb1ac2baae7109e221e7e65d5136cffaa883420158d88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76ccd98d4617af751a4c9496255a176e26e564404cb266721eca9b7b4830f30f4ec9567b99c8a1859e0e9a21d85fc00b161b9762490967a471f06d0e47c46bab"} 05:43:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:43:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:43:05 INFO - Contents: 05:43:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a63c2b5a38766c9c3be072f617e8919945bb2e49d568f674add0c13a523f26a55099af08f6a237432825b8b1f695c1019615e14f7e491377bca937a8686ebad6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/921e9433e1888ea40e3d04fd25c78fc5b1c5a7c7a1531c98196c7c45264db5610de687ecb8daab62bb50aaa40a9be50c1e4396cfe65b7132d109249966f7d5a0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb73e39b27ed3cef4e0a0c81817cdd06be654dd836d42f1153a4c1a0fa4a5d3b01c4b474a1db70d9588bb1ac2baae7109e221e7e65d5136cffaa883420158d88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76ccd98d4617af751a4c9496255a176e26e564404cb266721eca9b7b4830f30f4ec9567b99c8a1859e0e9a21d85fc00b161b9762490967a471f06d0e47c46bab"} 05:43:05 INFO - Running post-run listener: copy_logs_to_upload_dir 05:43:05 INFO - Copying logs to upload dir... 05:43:05 INFO - mkdir: /builds/slave/test/build/upload/logs 05:43:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.072941 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-03-22 05:43:05.682337) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 05:43:05.686311) ========= 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.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a63c2b5a38766c9c3be072f617e8919945bb2e49d568f674add0c13a523f26a55099af08f6a237432825b8b1f695c1019615e14f7e491377bca937a8686ebad6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/921e9433e1888ea40e3d04fd25c78fc5b1c5a7c7a1531c98196c7c45264db5610de687ecb8daab62bb50aaa40a9be50c1e4396cfe65b7132d109249966f7d5a0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb73e39b27ed3cef4e0a0c81817cdd06be654dd836d42f1153a4c1a0fa4a5d3b01c4b474a1db70d9588bb1ac2baae7109e221e7e65d5136cffaa883420158d88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76ccd98d4617af751a4c9496255a176e26e564404cb266721eca9b7b4830f30f4ec9567b99c8a1859e0e9a21d85fc00b161b9762490967a471f06d0e47c46bab"} build_url:https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011137 build_url: 'https://queue.taskcluster.net/v1/task/cKADXOK3RTG5nDbR4cuonw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a63c2b5a38766c9c3be072f617e8919945bb2e49d568f674add0c13a523f26a55099af08f6a237432825b8b1f695c1019615e14f7e491377bca937a8686ebad6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/921e9433e1888ea40e3d04fd25c78fc5b1c5a7c7a1531c98196c7c45264db5610de687ecb8daab62bb50aaa40a9be50c1e4396cfe65b7132d109249966f7d5a0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb73e39b27ed3cef4e0a0c81817cdd06be654dd836d42f1153a4c1a0fa4a5d3b01c4b474a1db70d9588bb1ac2baae7109e221e7e65d5136cffaa883420158d88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76ccd98d4617af751a4c9496255a176e26e564404cb266721eca9b7b4830f30f4ec9567b99c8a1859e0e9a21d85fc00b161b9762490967a471f06d0e47c46bab"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 05:43:05.720320) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 05:43:05.720646) ========= 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.qPAQnPZb1z/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.4Hf7z0EpVt/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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.004983 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 05:43:05.769631) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 05:43:05.769957) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 05:43:05.770302) ========= ========= Total master_lag: 0.19 =========