builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0134 starttime: 1492110070.02 results: success (0) buildid: 20170413104325 builduid: 0cb5da75d9f744449625bf7d5f9b3af3 revision: 8dd662ed33876fc7d86882fd19bc9f56d96ffa41 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 12:01:10.024182) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 12:01:10.024635) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 12:01:10.024911) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 12:01:10.056410) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 12:01:10.056805) ========= 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.XfSlDEihSK/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.htlV4eeGK2/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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-04-13 12:01:10-- 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% 204M=0s 2017-04-13 12:01:10 (204 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137969 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 12:01:10.222504) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 12:01:10.222878) ========= 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.XfSlDEihSK/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.htlV4eeGK2/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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.029544 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 12:01:10.274692) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-13 12:01:10.275038) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8dd662ed33876fc7d86882fd19bc9f56d96ffa41 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8dd662ed33876fc7d86882fd19bc9f56d96ffa41 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XfSlDEihSK/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.htlV4eeGK2/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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-04-13 12:01:10,380 truncating revision to first 12 chars 2017-04-13 12:01:10,380 Setting DEBUG logging. 2017-04-13 12:01:10,380 attempt 1/10 2017-04-13 12:01:10,380 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8dd662ed3387?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-13 12:01:11,197 unpacking tar archive at: mozilla-central-8dd662ed3387/testing/mozharness/ program finished with exit code 0 elapsedTime=1.316259 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-13 12:01:11.617467) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 12:01:11.617899) ========= script_repo_revision: 8dd662ed33876fc7d86882fd19bc9f56d96ffa41 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 12:01:11.618453) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 12:01:11.618876) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 12:01:11.640506) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-04-13 12:01:11.641337) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XfSlDEihSK/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.htlV4eeGK2/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 12:01:11 INFO - MultiFileLogger online at 20170413 12:01:11 in /builds/slave/test 12:01:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:01:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:01:11 INFO - {'append_to_log': False, 12:01:11 INFO - 'base_work_dir': '/builds/slave/test', 12:01:11 INFO - 'blob_upload_branch': 'Firefox', 12:01:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:01:11 INFO - 'branch': 'Firefox', 12:01:11 INFO - 'buildbot_json_path': 'buildprops.json', 12:01:11 INFO - 'config_files': ('talos/mac_config.py',), 12:01:11 INFO - 'default_actions': ('clobber', 12:01:11 INFO - 'read-buildbot-config', 12:01:11 INFO - 'download-and-extract', 12:01:11 INFO - 'populate-webroot', 12:01:11 INFO - 'create-virtualenv', 12:01:11 INFO - 'install', 12:01:11 INFO - 'run-tests'), 12:01:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:01:11 INFO - 'download_minidump_stackwalk': True, 12:01:11 INFO - 'download_symbols': 'ondemand', 12:01:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:01:11 INFO - 'tooltool.py': '/tools/tooltool.py', 12:01:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:01:11 INFO - '/tools/misc-python/virtualenv.py')}, 12:01:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:01:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:01:11 INFO - 'gecko_profile': False, 12:01:11 INFO - 'gecko_profile_interval': 0, 12:01:11 INFO - 'installer_path': 'installer.exe', 12:01:11 INFO - 'log_level': 'info', 12:01:11 INFO - 'log_name': 'talos', 12:01:11 INFO - 'log_to_console': True, 12:01:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:01:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:01:11 INFO - 'opt_config_files': (), 12:01:11 INFO - 'pip_index': False, 12:01:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:01:11 INFO - 'cmd': ('bash', 12:01:11 INFO - '-c', 12:01:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:01:11 INFO - 'enabled': True, 12:01:11 INFO - 'halt_on_failure': False, 12:01:11 INFO - 'name': 'check_screen_resolution'},), 12:01:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:01:11 INFO - 'cmd': ('bash', 12:01:11 INFO - '-c', 12:01:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:01:11 INFO - 'enabled': True, 12:01:11 INFO - 'halt_on_failure': False, 12:01:11 INFO - 'name': 'check_screen_resolution'},), 12:01:11 INFO - 'run_cmd_checks_enabled': True, 12:01:11 INFO - 'suite': 'dromaeojs-e10s', 12:01:11 INFO - 'system_bits': '32', 12:01:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:01:11 INFO - 'title': 't-yosemite-r7-0134', 12:01:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:01:11 INFO - 'use_talos_json': True, 12:01:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:01:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:01:11 INFO - 'work_dir': 'build'} 12:01:11 INFO - [mozharness: 2017-04-13 19:01:11.860279Z] Running clobber step. 12:01:11 INFO - Running pre-action listener: _resource_record_pre_action 12:01:11 INFO - Running main action method: clobber 12:01:11 INFO - rmtree: /builds/slave/test/build 12:01:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:01:14 INFO - Running post-action listener: _resource_record_post_action 12:01:14 INFO - [mozharness: 2017-04-13 19:01:14.377260Z] Finished clobber step (success) 12:01:14 INFO - [mozharness: 2017-04-13 19:01:14.377436Z] Running read-buildbot-config step. 12:01:14 INFO - Running pre-action listener: _resource_record_pre_action 12:01:14 INFO - Running main action method: read_buildbot_config 12:01:14 INFO - Using buildbot properties: 12:01:14 INFO - { 12:01:14 INFO - "project": "", 12:01:14 INFO - "product": "firefox", 12:01:14 INFO - "script_repo_revision": "production", 12:01:14 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:01:14 INFO - "repository": "", 12:01:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:01:14 INFO - "buildid": "20170413104325", 12:01:14 INFO - "pgo_build": "False", 12:01:14 INFO - "basedir": "/builds/slave/test", 12:01:14 INFO - "buildnumber": 46, 12:01:14 INFO - "slavename": "t-yosemite-r7-0134", 12:01:14 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:01:14 INFO - "platform": "yosemite_r7", 12:01:14 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:01:14 INFO - "branch": "mozilla-central", 12:01:14 INFO - "repo_path": "mozilla-central", 12:01:14 INFO - "revision": "8dd662ed33876fc7d86882fd19bc9f56d96ffa41", 12:01:14 INFO - "stage_platform": "macosx64", 12:01:14 INFO - "builduid": "0cb5da75d9f744449625bf7d5f9b3af3", 12:01:14 INFO - "slavebuilddir": "test" 12:01:14 INFO - } 12:01:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 12:01:14 INFO - Running post-action listener: _resource_record_post_action 12:01:14 INFO - [mozharness: 2017-04-13 19:01:14.380760Z] Finished read-buildbot-config step (success) 12:01:14 INFO - [mozharness: 2017-04-13 19:01:14.380899Z] Running download-and-extract step. 12:01:14 INFO - Running pre-action listener: _resource_record_pre_action 12:01:14 INFO - Running main action method: download_and_extract 12:01:14 INFO - mkdir: /builds/slave/test/build/tests 12:01:14 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')]} 12:01:14 INFO - trying https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 12:01:14 INFO - Downloading https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:01:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 12:01:15 INFO - Downloaded 1325 bytes. 12:01:15 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:01:15 INFO - Using the following test package requirements: 12:01:15 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:15 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 12:01:15 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:01:15 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:15 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 12:01:15 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:15 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 12:01:15 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:15 INFO - u'jsshell-mac.zip'], 12:01:15 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:15 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 12:01:15 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:01:15 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:15 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 12:01:15 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:15 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 12:01:15 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:15 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 12:01:15 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:15 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 12:01:15 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:01:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:01:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:01:15 INFO - Fetch https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:01:17 INFO - Expected file size: 52595537 12:01:17 INFO - Obtained file size: 52595537 12:01:33 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:01:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:01:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:01:33 INFO - Fetch https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:01:35 INFO - Expected file size: 52595537 12:01:35 INFO - Obtained file size: 52595537 12:01:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 12:01:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:01:50 INFO - Fetch https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 12:01:52 INFO - Expected file size: 14407739 12:01:52 INFO - Obtained file size: 14407739 12:01:52 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')]} 12:01:52 INFO - trying https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:01:52 INFO - Downloading https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 12:01:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 12:01:54 INFO - Downloaded 71083538 bytes. 12:01:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:01:54 INFO - mkdir: /builds/slave/test/properties 12:01:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:01:54 INFO - Writing to file /builds/slave/test/properties/build_url 12:01:54 INFO - Contents: 12:01:54 INFO - build_url:https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:01:55 INFO - Running post-action listener: _resource_record_post_action 12:01:55 INFO - Running post-action listener: set_extra_try_arguments 12:01:55 INFO - [mozharness: 2017-04-13 19:01:55.102591Z] Finished download-and-extract step (success) 12:01:55 INFO - [mozharness: 2017-04-13 19:01:55.102752Z] Running populate-webroot step. 12:01:55 INFO - Running pre-action listener: _resource_record_pre_action 12:01:55 INFO - Running main action method: populate_webroot 12:01:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:01:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:01:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:01:55 INFO - '--tppagecycles', 12:01:55 INFO - '7'], 12:01:55 INFO - 'tests': ['tsvgm']}}, 12:01:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:01:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:01:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:01:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:01:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:01:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:01:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:01:55 INFO - 'talos_options': ['--disable-e10s'], 12:01:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:01:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:01:55 INFO - 'talos_options': ['--disable-e10s'], 12:01:55 INFO - 'tests': ['damp', 'tps']}, 12:01:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:01:55 INFO - 'tests': ['damp', 'tps']}, 12:01:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:01:55 INFO - 'tests': ['dromaeo_dom']}, 12:01:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:01:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:01:55 INFO - 'tests': ['basic_compositor_video']}, 12:01:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:01:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:01:55 INFO - 'tests': ['a11yr', 12:01:55 INFO - 'ts_paint', 12:01:55 INFO - 'tpaint', 12:01:55 INFO - 'sessionrestore', 12:01:55 INFO - 'sessionrestore_no_auto_restore', 12:01:55 INFO - 'tabpaint']}, 12:01:55 INFO - 'other-e10s': {'tests': ['a11yr', 12:01:55 INFO - 'ts_paint', 12:01:55 INFO - 'tpaint', 12:01:55 INFO - 'sessionrestore', 12:01:55 INFO - 'sessionrestore_no_auto_restore', 12:01:55 INFO - 'tabpaint']}, 12:01:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:01:55 INFO - 'ts_paint', 12:01:55 INFO - 'tpaint', 12:01:55 INFO - 'sessionrestore', 12:01:55 INFO - 'sessionrestore_no_auto_restore', 12:01:55 INFO - 'tabpaint']}, 12:01:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:01:55 INFO - 'ts_paint', 12:01:55 INFO - 'tpaint', 12:01:55 INFO - 'sessionrestore', 12:01:55 INFO - 'sessionrestore_no_auto_restore', 12:01:55 INFO - 'tabpaint']}, 12:01:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:01:55 INFO - 'tests': ['a11yr', 12:01:55 INFO - 'ts_paint', 12:01:55 INFO - 'tpaint', 12:01:55 INFO - 'sessionrestore', 12:01:55 INFO - 'sessionrestore_no_auto_restore', 12:01:55 INFO - 'tabpaint']}, 12:01:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:01:55 INFO - 'tests': ['a11yr', 12:01:55 INFO - 'ts_paint', 12:01:55 INFO - 'tpaint', 12:01:55 INFO - 'sessionrestore', 12:01:55 INFO - 'sessionrestore_no_auto_restore', 12:01:55 INFO - 'tabpaint']}, 12:01:55 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:01:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:01:55 INFO - 'bloom_basic_ref']}, 12:01:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:01:55 INFO - 'tests': ['tsvgx', 12:01:55 INFO - 'tsvgr_opacity', 12:01:55 INFO - 'tart', 12:01:55 INFO - 'tscrollx', 12:01:55 INFO - 'cart', 12:01:55 INFO - 'tsvg_static']}, 12:01:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:01:55 INFO - 'tsvgr_opacity', 12:01:55 INFO - 'tart', 12:01:55 INFO - 'tscrollx', 12:01:55 INFO - 'cart', 12:01:55 INFO - 'tsvg_static']}, 12:01:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:01:55 INFO - 'talos_options': ['--disable-e10s'], 12:01:55 INFO - 'tests': ['tp5o']}, 12:01:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:01:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:01:55 INFO - 'talos_options': ['--disable-e10s', 12:01:55 INFO - '--xperf_path', 12:01:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:55 INFO - 'tests': ['tp5n']}, 12:01:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:01:55 INFO - 'talos_options': ['--xperf_path', 12:01:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:55 INFO - 'tests': ['tp5n']}}, 12:01:55 INFO - 'talos.zip': {'path': '', 12:01:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:01:55 INFO - Running post-action listener: _resource_record_post_action 12:01:55 INFO - [mozharness: 2017-04-13 19:01:55.122177Z] Finished populate-webroot step (success) 12:01:55 INFO - [mozharness: 2017-04-13 19:01:55.122303Z] Running create-virtualenv step. 12:01:55 INFO - Running pre-action listener: _resource_record_pre_action 12:01:55 INFO - Running main action method: create_virtualenv 12:01:55 INFO - Creating virtualenv /builds/slave/test/build/venv 12:01:55 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 12:01:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:01:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:01:55 INFO - Using real prefix '/tools/python27' 12:01:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:01:55 INFO - Installing distribute.............................................................................................................................................................................................done. 12:01:58 INFO - Installing pip.................done. 12:01:58 INFO - Return code: 0 12:01:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:01:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:01:59 INFO - Reading from file tmpfile_stdout 12:01:59 INFO - Output received: 12:01:59 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) 12:01:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:01: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')]} 12:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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')]} 12:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108932b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108935768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf52d94b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108710d30>, '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 0x108637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108705be8>, '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.XfSlDEihSK/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.htlV4eeGK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 12:01: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', 'pip>=1.5'] in /builds/slave/test/build 12:01: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 pip>=1.5 12:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XfSlDEihSK/Render', 12:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:59 INFO - 'HOME': '/Users/cltbld', 12:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:59 INFO - 'LOGNAME': 'cltbld', 12:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:59 INFO - 'MOZ_NO_REMOTE': '1', 12:01:59 INFO - 'NO_EM_RESTART': '1', 12:01:59 INFO - 'PAGER': '/bin/cat', 12:01:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:59 INFO - 'PWD': '/builds/slave/test', 12:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:59 INFO - 'SHELL': '/bin/bash', 12:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htlV4eeGK2/Listeners', 12:01:59 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 12:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:59 INFO - 'USER': 'cltbld', 12:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:59 INFO - 'XPC_FLAGS': '0x0', 12:01:59 INFO - 'XPC_SERVICE_NAME': '0', 12:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:59 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 12:01:59 INFO - Cleaning up... 12:01:59 INFO - Return code: 0 12:01:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:01: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')]} 12:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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')]} 12:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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', '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 0x108932b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108935768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf52d94b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108710d30>, '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 0x108637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108705be8>, '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.XfSlDEihSK/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.htlV4eeGK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 12:01: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', 'psutil>=3.1.1'] in /builds/slave/test/build 12:01: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 psutil>=3.1.1 12:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XfSlDEihSK/Render', 12:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:59 INFO - 'HOME': '/Users/cltbld', 12:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:59 INFO - 'LOGNAME': 'cltbld', 12:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:59 INFO - 'MOZ_NO_REMOTE': '1', 12:01:59 INFO - 'NO_EM_RESTART': '1', 12:01:59 INFO - 'PAGER': '/bin/cat', 12:01:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:59 INFO - 'PWD': '/builds/slave/test', 12:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:59 INFO - 'SHELL': '/bin/bash', 12:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htlV4eeGK2/Listeners', 12:01:59 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 12:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:59 INFO - 'USER': 'cltbld', 12:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:59 INFO - 'XPC_FLAGS': '0x0', 12:01:59 INFO - 'XPC_SERVICE_NAME': '0', 12:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:59 INFO - Downloading/unpacking psutil>=3.1.1 12:01:59 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 12:01:59 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 12:01:59 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 12:01:59 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 12:01:59 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 12:01:59 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 12:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:02:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:02:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:02:03 INFO - Installing collected packages: psutil 12:02:03 INFO - Running setup.py install for psutil 12:02:03 INFO - building 'psutil._psutil_osx' extension 12:02:03 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 12:02:03 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 12:02:03 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 12:02:03 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 12:02:04 INFO - building 'psutil._psutil_posix' extension 12:02:04 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 12:02:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:02:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:02:04 INFO - ^ 12:02:04 INFO - 1 warning generated. 12:02:04 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 12:02:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:02:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:02:04 INFO - Successfully installed psutil 12:02:04 INFO - Cleaning up... 12:02:04 INFO - Return code: 0 12:02:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:02:04 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')]} 12:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:04 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')]} 12:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:04 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 0x108932b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108935768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf52d94b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108710d30>, '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 0x108637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108705be8>, '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.XfSlDEihSK/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.htlV4eeGK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 12:02:04 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 12:02:04 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 12:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XfSlDEihSK/Render', 12:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:04 INFO - 'HOME': '/Users/cltbld', 12:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:04 INFO - 'LOGNAME': 'cltbld', 12:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:04 INFO - 'MOZ_NO_REMOTE': '1', 12:02:04 INFO - 'NO_EM_RESTART': '1', 12:02:04 INFO - 'PAGER': '/bin/cat', 12:02:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:04 INFO - 'PWD': '/builds/slave/test', 12:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:04 INFO - 'SHELL': '/bin/bash', 12:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htlV4eeGK2/Listeners', 12:02:04 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 12:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:04 INFO - 'USER': 'cltbld', 12:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:04 INFO - 'XPC_FLAGS': '0x0', 12:02:04 INFO - 'XPC_SERVICE_NAME': '0', 12:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:02:07 INFO - Installing collected packages: mozsystemmonitor 12:02:07 INFO - Running setup.py install for mozsystemmonitor 12:02:07 INFO - Successfully installed mozsystemmonitor 12:02:07 INFO - Cleaning up... 12:02:08 INFO - Return code: 0 12:02:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:02:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:08 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 0x108932b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108935768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf52d94b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108710d30>, '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 0x108637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108705be8>, '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.XfSlDEihSK/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.htlV4eeGK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 12:02:08 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 12:02:08 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 12:02:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XfSlDEihSK/Render', 12:02:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:08 INFO - 'HOME': '/Users/cltbld', 12:02:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:08 INFO - 'LOGNAME': 'cltbld', 12:02:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:08 INFO - 'MOZ_NO_REMOTE': '1', 12:02:08 INFO - 'NO_EM_RESTART': '1', 12:02:08 INFO - 'PAGER': '/bin/cat', 12:02:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:08 INFO - 'PWD': '/builds/slave/test', 12:02:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:08 INFO - 'SHELL': '/bin/bash', 12:02:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htlV4eeGK2/Listeners', 12:02:08 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 12:02:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:08 INFO - 'USER': 'cltbld', 12:02:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:08 INFO - 'XPC_FLAGS': '0x0', 12:02:08 INFO - 'XPC_SERVICE_NAME': '0', 12:02:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:08 INFO - Downloading/unpacking jsonschema==2.5.1 12:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:02:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:11 INFO - Downloading functools32-3.2.3-2.tar.gz 12:02:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:02:11 INFO - warning: no files found matching '*.txt' 12:02:11 INFO - no previously-included directories found matching 'build' 12:02:11 INFO - no previously-included directories found matching 'dist' 12:02:11 INFO - no previously-included directories found matching '.git*' 12:02:11 INFO - Installing collected packages: jsonschema, functools32 12:02:12 INFO - Running setup.py install for functools32 12:02:12 INFO - warning: no files found matching '*.txt' 12:02:12 INFO - no previously-included directories found matching 'build' 12:02:12 INFO - no previously-included directories found matching 'dist' 12:02:12 INFO - no previously-included directories found matching '.git*' 12:02:12 INFO - Successfully installed jsonschema functools32 12:02:12 INFO - Cleaning up... 12:02:12 INFO - Return code: 0 12:02:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:02:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:12 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 0x108932b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108935768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf52d94b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108710d30>, '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 0x108637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108705be8>, '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.XfSlDEihSK/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.htlV4eeGK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 12:02:12 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 12:02:12 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 12:02:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XfSlDEihSK/Render', 12:02:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:12 INFO - 'HOME': '/Users/cltbld', 12:02:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:12 INFO - 'LOGNAME': 'cltbld', 12:02:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:12 INFO - 'MOZ_NO_REMOTE': '1', 12:02:12 INFO - 'NO_EM_RESTART': '1', 12:02:12 INFO - 'PAGER': '/bin/cat', 12:02:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:12 INFO - 'PWD': '/builds/slave/test', 12:02:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:12 INFO - 'SHELL': '/bin/bash', 12:02:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htlV4eeGK2/Listeners', 12:02:12 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 12:02:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:12 INFO - 'USER': 'cltbld', 12:02:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:12 INFO - 'XPC_FLAGS': '0x0', 12:02:12 INFO - 'XPC_SERVICE_NAME': '0', 12:02:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:02:12 INFO - Cleaning up... 12:02:12 INFO - Return code: 0 12:02:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:02:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:12 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 0x108932b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108935768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf52d94b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108710d30>, '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 0x108637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108705be8>, '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.XfSlDEihSK/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.htlV4eeGK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 12:02:12 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 12:02:12 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 12:02:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XfSlDEihSK/Render', 12:02:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:12 INFO - 'HOME': '/Users/cltbld', 12:02:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:12 INFO - 'LOGNAME': 'cltbld', 12:02:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:12 INFO - 'MOZ_NO_REMOTE': '1', 12:02:12 INFO - 'NO_EM_RESTART': '1', 12:02:12 INFO - 'PAGER': '/bin/cat', 12:02:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:12 INFO - 'PWD': '/builds/slave/test', 12:02:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:12 INFO - 'SHELL': '/bin/bash', 12:02:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htlV4eeGK2/Listeners', 12:02:12 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 12:02:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:12 INFO - 'USER': 'cltbld', 12:02:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:12 INFO - 'XPC_FLAGS': '0x0', 12:02:12 INFO - 'XPC_SERVICE_NAME': '0', 12:02:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:12 INFO - Downloading/unpacking blobuploader==1.2.4 12:02:12 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 12:02:12 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 12:02:12 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 12:02:12 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 12:02:12 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 12:02:12 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 12:02:15 INFO - Downloading blobuploader-1.2.4.tar.gz 12:02:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:02:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:02:15 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 12:02:15 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 12:02:15 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 12:02:15 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 12:02:15 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 12:02:15 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 12:02:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:02:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:16 INFO - Downloading docopt-0.6.1.tar.gz 12:02:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:02:16 INFO - Installing collected packages: blobuploader, requests, docopt 12:02:16 INFO - Running setup.py install for blobuploader 12:02:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:02:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:02:16 INFO - Running setup.py install for requests 12:02:17 INFO - Running setup.py install for docopt 12:02:17 INFO - Successfully installed blobuploader requests docopt 12:02:17 INFO - Cleaning up... 12:02:17 INFO - Return code: 0 12:02:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:17 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 0x108932b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108935768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf52d94b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108710d30>, '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 0x108637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108705be8>, '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.XfSlDEihSK/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.htlV4eeGK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 12:02:17 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 12:02:17 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 12:02:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XfSlDEihSK/Render', 12:02:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:17 INFO - 'HOME': '/Users/cltbld', 12:02:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:17 INFO - 'LOGNAME': 'cltbld', 12:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:17 INFO - 'MOZ_NO_REMOTE': '1', 12:02:17 INFO - 'NO_EM_RESTART': '1', 12:02:17 INFO - 'PAGER': '/bin/cat', 12:02:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:17 INFO - 'PWD': '/builds/slave/test', 12:02:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:17 INFO - 'SHELL': '/bin/bash', 12:02:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htlV4eeGK2/Listeners', 12:02:17 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 12:02:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:17 INFO - 'USER': 'cltbld', 12:02:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:17 INFO - 'XPC_FLAGS': '0x0', 12:02:17 INFO - 'XPC_SERVICE_NAME': '0', 12:02:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:02:17 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-uDjLpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:02:18 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-7lfFM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:02:18 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-PWTC7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:02:18 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Y4fDdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:02:18 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-tCPytM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:02:18 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-s6cjZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:02:18 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-boF_ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:02:18 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-AC2Ze_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:02:18 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-BRHImt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:02:18 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-HZcwmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:02:18 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-pcXWEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:02:19 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-ntyJOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:02:19 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-ijucmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:02:19 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-CybKxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:19 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-dCbK0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:02:19 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-13bki2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:02:19 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-xt4PxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:02:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:02:19 INFO - Running setup.py install for manifestparser 12:02:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:02:19 INFO - Running setup.py install for mozcrash 12:02:19 INFO - Running setup.py install for mozdebug 12:02:20 INFO - Running setup.py install for mozdevice 12:02:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:02:20 INFO - Running setup.py install for mozfile 12:02:20 INFO - Running setup.py install for mozhttpd 12:02:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:02:20 INFO - Running setup.py install for mozinfo 12:02:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:02:20 INFO - Running setup.py install for mozInstall 12:02:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:02:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:02:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:02:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:02:20 INFO - Running setup.py install for mozleak 12:02:20 INFO - Running setup.py install for mozlog 12:02:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:02:21 INFO - Running setup.py install for moznetwork 12:02:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:02:21 INFO - Running setup.py install for mozprocess 12:02:21 INFO - Running setup.py install for mozprofile 12:02:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:02:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:02:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:02:21 INFO - Running setup.py install for mozrunner 12:02:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:02:21 INFO - Running setup.py install for mozscreenshot 12:02:21 INFO - Running setup.py install for moztest 12:02:22 INFO - Running setup.py install for mozversion 12:02:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:02:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:02:22 INFO - Cleaning up... 12:02:22 INFO - Return code: 0 12:02:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:22 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 0x108932b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108935768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf52d94b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108710d30>, '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 0x108637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108705be8>, '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.XfSlDEihSK/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.htlV4eeGK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 12:02:22 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 12:02:22 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 12:02:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XfSlDEihSK/Render', 12:02:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:22 INFO - 'HOME': '/Users/cltbld', 12:02:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:22 INFO - 'LOGNAME': 'cltbld', 12:02:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:22 INFO - 'MOZ_NO_REMOTE': '1', 12:02:22 INFO - 'NO_EM_RESTART': '1', 12:02:22 INFO - 'PAGER': '/bin/cat', 12:02:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:22 INFO - 'PWD': '/builds/slave/test', 12:02:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:22 INFO - 'SHELL': '/bin/bash', 12:02:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htlV4eeGK2/Listeners', 12:02:22 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 12:02:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:22 INFO - 'USER': 'cltbld', 12:02:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:22 INFO - 'XPC_FLAGS': '0x0', 12:02:22 INFO - 'XPC_SERVICE_NAME': '0', 12:02:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:02:22 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-JcllhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:02:22 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)) 12:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:02:22 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-WemyC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:02:22 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)) 12:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:02:22 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-XfXHnY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:02:22 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)) 12:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:02:22 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-KebkxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:02:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 12:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:02:22 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-anSJXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:02:22 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)) 12:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:02:23 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Thned7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:02:23 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)) 12:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:02:23 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-qYwbML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:02:23 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)) 12:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:02:23 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-1l9hfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:02:23 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)) 12:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:02:23 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-pTRery-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:02:23 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)) 12:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:02:23 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-jmLWwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:02:23 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)) 12:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:02:23 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Pubsrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:02:23 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)) 12:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:02:23 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-IeXfTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:02:23 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)) 12:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:02:23 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-zjAJrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:02:23 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)) 12:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:02:23 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-p1h_QU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:02:23 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)) 12:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:23 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-KH4sdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:02:24 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)) 12:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:02:24 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-wzA9KM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:02:24 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)) 12:02:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:02:24 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-tw9PiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:02:24 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)) 12:02:24 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)) 12:02:24 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)) 12:02:24 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)) 12:02:24 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:02:24 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:02:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:26 INFO - Downloading blessings-1.6.tar.gz 12:02:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:02:26 INFO - Installing collected packages: blessings 12:02:26 INFO - Running setup.py install for blessings 12:02:27 INFO - Successfully installed blessings 12:02:27 INFO - Cleaning up... 12:02:27 INFO - Return code: 0 12:02:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:02:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:02:27 INFO - Reading from file tmpfile_stdout 12:02:27 INFO - Current package versions: 12:02:27 INFO - blessings == 1.6 12:02:27 INFO - blobuploader == 1.2.4 12:02:27 INFO - docopt == 0.6.1 12:02:27 INFO - functools32 == 3.2.3-2 12:02:27 INFO - jsonschema == 2.5.1 12:02:27 INFO - manifestparser == 1.1 12:02:27 INFO - mozInstall == 1.12 12:02:27 INFO - mozcrash == 1.0 12:02:27 INFO - mozdebug == 0.1 12:02:27 INFO - mozdevice == 0.50 12:02:27 INFO - mozfile == 1.2 12:02:27 INFO - mozhttpd == 0.7 12:02:27 INFO - mozinfo == 0.9 12:02:27 INFO - mozleak == 0.1 12:02:27 INFO - mozlog == 3.4 12:02:27 INFO - moznetwork == 0.27 12:02:27 INFO - mozprocess == 0.25 12:02:27 INFO - mozprofile == 0.28 12:02:27 INFO - mozrunner == 6.13 12:02:27 INFO - mozscreenshot == 0.1 12:02:27 INFO - mozsystemmonitor == 0.3 12:02:27 INFO - moztest == 0.8 12:02:27 INFO - mozversion == 1.4 12:02:27 INFO - psutil == 3.1.1 12:02:27 INFO - requests == 1.2.3 12:02:27 INFO - wsgiref == 0.1.2 12:02:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:27 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 0x108932b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108935768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf52d94b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108710d30>, '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 0x108637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108705be8>, '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.XfSlDEihSK/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.htlV4eeGK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 12:02:27 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 12:02:27 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 12:02:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XfSlDEihSK/Render', 12:02:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:27 INFO - 'HOME': '/Users/cltbld', 12:02:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:27 INFO - 'LOGNAME': 'cltbld', 12:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:27 INFO - 'MOZ_NO_REMOTE': '1', 12:02:27 INFO - 'NO_EM_RESTART': '1', 12:02:27 INFO - 'PAGER': '/bin/cat', 12:02:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:27 INFO - 'PWD': '/builds/slave/test', 12:02:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:27 INFO - 'SHELL': '/bin/bash', 12:02:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htlV4eeGK2/Listeners', 12:02:27 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 12:02:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:27 INFO - 'USER': 'cltbld', 12:02:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:27 INFO - 'XPC_FLAGS': '0x0', 12:02:27 INFO - 'XPC_SERVICE_NAME': '0', 12:02:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:27 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)) 12:02:27 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)) 12:02:27 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)) 12:02:27 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)) 12:02:27 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)) 12:02:27 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)) 12:02:27 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)) 12:02:27 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)) 12:02:27 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)) 12:02:27 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)) 12:02:27 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)) 12:02:27 INFO - Cleaning up... 12:02:27 INFO - Return code: 0 12:02:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:02:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108932b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108935768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf52d94b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108710d30>, '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 0x108637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108705be8>, '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.XfSlDEihSK/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.htlV4eeGK2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 12:02:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:02:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XfSlDEihSK/Render', 12:02:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:27 INFO - 'HOME': '/Users/cltbld', 12:02:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:27 INFO - 'LOGNAME': 'cltbld', 12:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:27 INFO - 'MOZ_NO_REMOTE': '1', 12:02:27 INFO - 'NO_EM_RESTART': '1', 12:02:27 INFO - 'PAGER': '/bin/cat', 12:02:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:27 INFO - 'PWD': '/builds/slave/test', 12:02:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:27 INFO - 'SHELL': '/bin/bash', 12:02:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htlV4eeGK2/Listeners', 12:02:27 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 12:02:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:27 INFO - 'USER': 'cltbld', 12:02:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:27 INFO - 'XPC_FLAGS': '0x0', 12:02:27 INFO - 'XPC_SERVICE_NAME': '0', 12:02:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:02:27 INFO - Cleaning up... 12:02:27 INFO - Return code: 0 12:02:27 INFO - Running post-action listener: _resource_record_post_action 12:02:27 INFO - Running post-action listener: _start_resource_monitoring 12:02:27 INFO - Starting resource monitoring. 12:02:28 INFO - [mozharness: 2017-04-13 19:02:28.001494Z] Finished create-virtualenv step (success) 12:02:28 INFO - [mozharness: 2017-04-13 19:02:28.002463Z] Running install step. 12:02:28 INFO - Running pre-action listener: _resource_record_pre_action 12:02:28 INFO - Running main action method: install 12:02:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:02:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:02:28 INFO - Reading from file tmpfile_stdout 12:02:28 INFO - Detecting whether we're running mozinstall >=1.0... 12:02:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:02:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:02:28 INFO - Reading from file tmpfile_stdout 12:02:28 INFO - Output received: 12:02:28 INFO - Usage: mozinstall [options] installer 12:02:28 INFO - Options: 12:02:28 INFO - -h, --help show this help message and exit 12:02:28 INFO - -d DEST, --destination=DEST 12:02:28 INFO - Directory to install application into. [default: 12:02:28 INFO - "/builds/slave/test"] 12:02:28 INFO - --app=APP Application being installed. [default: firefox] 12:02:28 INFO - mkdir: /builds/slave/test/build/application 12:02:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:02:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:02:47 INFO - Reading from file tmpfile_stdout 12:02:47 INFO - Output received: 12:02:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:02:47 INFO - Running post-action listener: _resource_record_post_action 12:02:47 INFO - [mozharness: 2017-04-13 19:02:47.962296Z] Finished install step (success) 12:02:47 INFO - [mozharness: 2017-04-13 19:02:47.962457Z] Running run-tests step. 12:02:47 INFO - Running pre-action listener: _resource_record_pre_action 12:02:47 INFO - Running main action method: run_tests 12:02:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:02:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:02:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:02:47 INFO - 2017-04-13 12:02:47.977 screenresolution[1622:7253] starting screenresolution argv=screenresolution get 12:02:47 INFO - 2017-04-13 12:02:47.979 screenresolution[1622:7253] Display 0: 1600x1200x32@60 12:02:47 INFO - 2017-04-13 12:02:47.985 screenresolution[1623:7256] starting screenresolution argv=screenresolution list 12:02:47 INFO - Available Modes on Display 0 12:02:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:02:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:02:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:02:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:02:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:02:48 INFO - Intel Iris: 12:02:48 INFO - Chipset Model: Intel Iris 12:02:48 INFO - Type: GPU 12:02:48 INFO - Bus: Built-In 12:02:48 INFO - VRAM (Dynamic, Max): 1536 MB 12:02:48 INFO - Vendor: Intel (0x8086) 12:02:48 INFO - Device ID: 0x0a2e 12:02:48 INFO - Revision ID: 0x0009 12:02:48 INFO - Displays: 12:02:48 INFO - Display: 12:02:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:02:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:02:48 INFO - Display Serial Number: 1600x1200 60 12:02:48 INFO - Main Display: Yes 12:02:48 INFO - Mirror: Off 12:02:48 INFO - Online: Yes 12:02:48 INFO - Rotation: Supported 12:02:48 INFO - Return code: 0 12:02:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:02:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:02:48 INFO - Python 2.7.3 12:02:48 INFO - Return code: 0 12:02:48 INFO - grabbing minidump binary from tooltool 12:02:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:48 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 0x108710d30>, '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 0x108637db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108705be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:02:48 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 12:02:48 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 12:02:48 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 12:02:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:02:48 INFO - Return code: 0 12:02:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:02:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:02:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:02:48 INFO - ENV: RUST_BACKTRACE is now 1 12:02:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:02:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:02:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--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 12:02:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0134 --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 12:02:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XfSlDEihSK/Render', 12:02:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:48 INFO - 'HOME': '/Users/cltbld', 12:02:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:48 INFO - 'LOGNAME': 'cltbld', 12:02:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:02:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:02:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:48 INFO - 'MOZ_NO_REMOTE': '1', 12:02:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:02:48 INFO - 'NO_EM_RESTART': '1', 12:02:48 INFO - 'PAGER': '/bin/cat', 12:02:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:48 INFO - 'PWD': '/builds/slave/test', 12:02:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:02:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:48 INFO - 'RUST_BACKTRACE': '1', 12:02:48 INFO - 'SHELL': '/bin/bash', 12:02:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htlV4eeGK2/Listeners', 12:02:48 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 12:02:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:48 INFO - 'USER': 'cltbld', 12:02:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:48 INFO - 'XPC_FLAGS': '0x0', 12:02:48 INFO - 'XPC_SERVICE_NAME': '0', 12:02:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--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 12:02:48 INFO - mozversion application_buildid: 20170413104325 12:02:48 INFO - mozversion application_changeset: 8dd662ed33876fc7d86882fd19bc9f56d96ffa41 12:02:48 INFO - mozversion application_display_name: Nightly 12:02:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:02:48 INFO - mozversion application_name: Firefox 12:02:48 INFO - mozversion application_remotingname: firefox 12:02:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:02:48 INFO - mozversion application_vendor: Mozilla 12:02:48 INFO - mozversion application_version: 55.0a1 12:02:48 INFO - mozversion platform_buildid: 20170413104325 12:02:48 INFO - mozversion platform_changeset: 8dd662ed33876fc7d86882fd19bc9f56d96ffa41 12:02:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:02:48 INFO - mozversion platform_version: 55.0a1 12:02:48 INFO - using testdate: 1492110168 12:02:48 INFO - actual date: 1492110168 12:02:48 INFO - starting webserver on 'localhost:49236' 12:02:48 INFO - SUITE-START | Running 2 tests 12:02:48 INFO - TEST-START | dromaeo_css 12:02:48 INFO - operating with platform_type : mac_ 12:02:48 INFO - Initialising browser for dromaeo_css test... 12:02:48 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpupbmWj/profile http://localhost:49236/getInfo.html) 12:02:51 INFO - PID 1633 | 2017-04-13 12:02:51.502 plugin-container[1635:7445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e43, name = 'com.apple.tsm.portname' 12:02:51 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:02:51 INFO - PID 1633 | 2017-04-13 12:02:51.505 plugin-container[1635:7445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c17, name = 'com.apple.CFPasteboardClient' 12:02:51 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:02:51 INFO - PID 1633 | 2017-04-13 12:02:51.505 plugin-container[1635:7445] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:02:51 INFO - PID 1633 | __metrics Screen width/height:1600/1200 12:02:51 INFO - PID 1633 | colorDepth:24 12:02:51 INFO - PID 1633 | Browser inner width/height: 1024/689 12:02:51 INFO - PID 1633 | __metrics 12:02:51 ERROR - PID 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 12:02:52 INFO - TEST-INFO | 1633: exit 0 12:02:52 INFO - Browser initialized. 12:02:52 INFO - Running cycle 1/1 for dromaeo_css test... 12:02:52 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpupbmWj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:02:55 INFO - PID 1636 | 2017-04-13 12:02:55.050 plugin-container[1637:7585] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 12:02:55 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:02:55 INFO - PID 1636 | 2017-04-13 12:02:55.050 plugin-container[1637:7585] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 12:02:55 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:02:55 INFO - PID 1636 | 2017-04-13 12:02:55.050 plugin-container[1637:7585] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:03:04 INFO - PID 1636 | RSS: Main: 234766336 12:03:04 INFO - PID 1636 | 12:06:08 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 12:06:10 INFO - PID 1636 | RSS: Main: 253370368 12:06:10 INFO - PID 1636 | 12:07:53 INFO - PID 1636 | ************************* 12:07:53 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 12:07:53 INFO - PID 1636 | 12:07:53 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:07:53 INFO - PID 1636 | Full stack: 12:07:53 INFO - PID 1636 | ************************* 12:07:53 INFO - PID 1636 | ************************* 12:07:53 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 12:07:53 INFO - PID 1636 | 12:07:53 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:07:53 INFO - PID 1636 | Full stack: 12:07:53 INFO - PID 1636 | ************************* 12:07:53 INFO - PID 1636 | ************************* 12:07:53 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 12:07:53 INFO - PID 1636 | 12:07:53 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:07:53 INFO - PID 1636 | Full stack: 12:07:53 INFO - PID 1636 | ************************* 12:07:53 INFO - PID 1636 | ************************* 12:07:53 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 12:07:53 INFO - PID 1636 | 12:07:53 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:07:53 INFO - PID 1636 | Full stack: 12:07:53 INFO - PID 1636 | ************************* 12:09:12 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 12:09:12 INFO - PID 1636 | RSS: Main: 261246976 12:09:12 INFO - PID 1636 | 12:12:14 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 12:12:15 INFO - PID 1636 | RSS: Main: 273633280 12:12:15 INFO - PID 1636 | 12:15:17 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 12:15:17 INFO - PID 1636 | RSS: Main: 266354688 12:15:17 INFO - PID 1636 | 12:18:20 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 12:18:21 INFO - PID 1636 | RSS: Main: 276746240 12:18:21 INFO - PID 1636 | 12:21:22 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 12:21:23 INFO - PID 1636 | RSS: Main: 270434304 12:21:23 INFO - PID 1636 | 12:21:23 INFO - PID 1636 | __start_tp_report 12:21:23 INFO - PID 1636 | _x_x_mozilla_page_load 12:21:23 INFO - PID 1636 | _x_x_mozilla_page_load_details 12:21:23 INFO - PID 1636 | |i|pagename|runs| 12:21:23 INFO - PID 1636 | |0;dojo.html;3495.4756786482026;3501.2997400519885;3524.2246705724733;3557.5019497270387;3568.80371579563;6068.6055406398555;6099.975683468232;6106.6030708004;6132;6148.415900489452;6128.2646082470055;6190.473809726179;6190.535709821759;6231.968840155804;6299.77950771723;4838.63710221733;5422.91865622016;5426.131818908975;5440.401555828856;5455.509004189622;5130.127878260686;5444.455554444564;5519.862003449905;5523.199136125279;5523.608731058818;5355.143177091685;5411.350637923474;5462.754176062087;5468.371137319219;5492.450754924515;5431.809886653988;5432.972835135799;5434.0218760623075;5473.945260547383;5517.641353312022;4529.796159172845;5374.274472946141;5377.838664840061;5400.21696853954;5404.864878378072;4752.7861246244;6033.788817391414;6118.908216376758;6170.413810687977;6257.374262573751;6572.342765723437;6620.6358650274215;6628.5028622853;6645.9366501359555;6651.102101216322;5015.3730783652045;6726.327367263283;6728.697208625624;6732.158480189976;6738.124043874314;4837.612990960714;5865.941340586625;6168.753249869955;6351.110844481776;6357.904631430486;6851.760188393382;6898.344657257552;6901.413379862667;6924.065251191126;6926.030355750199;6313;6321.6207027577575;6412.941864592383;6465.353464653497;6475.222973243147;6671.432928201158;6773.966130169318;6774.661266936633;6786.253512113663;6787.660616969132;6374.075759014917;6484.027395890655;6634.568753031038;6661.233958094785;6688.598684078971;6074.51403887688;6146.801373732169;6152.046432802923;6555.868882622321;6563.934360656428;5366.731663416892;5376.086065368819;5392.0294347017125;5392.191171324254;5400.189971504227;4775.95224047762;4781.306710526954;4804.615630749601;4810;4835.250536166829;5504.944950550524;6102.298235702951;6186.226721659793;6293.339199383999;6373.6175829450385;6666.799996000128;6747.089142965735;6747.190337159573;6752.729890804313;6761.290064543152;6313.747450102036;6316.589421687576;6331.841703957346;6332.936670633235;6334.936650633527;5749.482546570829;5883.499902508334;5951.761929522857;5966.075258334965;5997.460228579449;6650.168728908983;6762.053312536347;6843.828904277234;6910.06714093611;6917.066196063467;6717.731290748316;6722.798316050526;6737.56205846638;6743.426808721314;6761.188657361148;6775.695093720893;6810.080639113657;6851.48613853953;6872.209695885037;6888.483363747639;6395.840103997437;6485.643289619116;6553.868922621426;6592.637404942582;6619.801405957829;6753.797386078426;6767.763128290486;6773.187217533928;6782.423494003065;6787.253402125663;6142.590862071594;6351.396617321347;6376.458001069961;6462.386073323027;6554.279029306677;5474.370447398441;6453.644105065434;6502.349765023459;6505.186851643544;6516.869662606816;6083.057126145454;6290.308066112632;6328.24061112651;6335.239771227482;6346.174997250328;5472.6990015547935;6497.317781632861;6513.413792758674;6551.475881929339;6558.540902136995;6321.778737744157;6335.303116657072;6346.492280617447;6352.777652782131;6366.363363663597 12:21:23 INFO - PID 1636 | |1;ext.html;746.8282295072133;800.7317548621212;823.2714048067459;1275.2985857778226;1279.2324605236865;32774.50838237425;37303;38342.23315533688;38386.61613383865;38453;38273.61726382736;38516;38656.42015369765;38738.22523549527;38762.612373876254;7952.681892724284;8109.270165685087;8508.48949063055;8733.912660873406;8770.210681038705;7299.89050164248;7415.66629501671;8588.312934965219;8679.17547832955;8727.301815854717;7282.834746440544;8686.478811271312;8695.478271303711;8736.694215702451;8737.781555461133;8686.305095592368;8709.60806763694;8751.693690720795;8758.255548278405;8785.6046477908;8630.913690863137;8638.308935285162;8638.827223455495;8642.265407440374;8679;37677.811610942044;37847.243055138744;37988.43017354742;38012.23975520502;38175.809120954495;3582.5880023797336;3583.498310236569;3618.0231337538717;3619.9683090319395;3623.0398944279787;23739.525209495885;30417.69582304193;30476;30692;30757.769689212404;3122.2350524121507;3153.984230078835;3156.889508867179;3173.730232930204;3176.6029246344206;221854;226677;250886.49113508995;261840;265504.67247663834;47222;47360.28959565609;47426.76286618614;47436.7628161857;47455;49164.7541762296;49170.508294916595;49235.50764492309;49294;49316;14358.425662973574;14413.567592972227;14414.351354189086;14557.636059098608;14586.708265834624;11947.76104477923;11967.521299148111;12006.099542534344;12011.759764804656;12052.216605920587;12101.608315043706;12862.871371286354;13404.597862064154;13427.731445371039;13475.528356507475;5926.111083337448;5960.314563825215;5966.373530779292;5988.221531200916;6136.110264011693;960.4381436859376;1026.1534234256767;1054.1724746074449;1055.6041484443333;1082.978340433187;23085.307440776804;24176;24263.39341516441;24428.389290267885;24527.87736061308;1129.3449799116493;1129.717570607348;1134.2117228526095;1165.5570883064381;1182.9645110646695;12316.876831231573;12370.505179792708;12432.627021189379;12453.875461245272;12470.251784892935;307040;308427.45786270924;309544;309770;312250;51626.22560661593;51733.96532069337;52112.957740845726;52114;52206.47793522016;1493.768465887789;1498.7976623156171;1535.2016951185321;1536.1934984133195;1537.477257732354;23611.45828125795;23647.64528532108;23748.643770343806;23808.4047898797;23926;31302;37073.6292637081;37353.439698404;37359.69241076658;37494.43758343573;255093;255829;256267;256736;256995;33767.83116084404;34217.31565368629;35007.824960875034;35130.82434587811;35160.82419587886;36465;44434.555654443044;45123.323150152115;45529;45539.31691024571;24708.505829882943;29986.850065750405;30850.228744280674;30883;31253.84373078211;30623.693763062976;30918.69081309158;30992.53511197379;31054.534181986837;31723;36575;36880;37235.81382093072;37325;37449.81275093607;30394.240143996576;30969.0709278722;31360.529592055682;31387.84306078455;31694.207644809063;21510.784892151503;21768.89115554412;22761;24155.912983916118;24343 12:21:23 INFO - PID 1636 | |2;jquery.html;432.12279073480806;435.22529896783715;439.3651174053492;451.3906226594098;460.3141319434042;19739;21311.786882131175;22013.779862201372;22114.668279975773;22232.444188895286;21444.678329825067;21837.563248735016;22129.66805497919;22499.21252756154;22805.859707014664;5482.643628164176;6059;6133.233345831771;6238.095476155955;6252.34350393209;5977;6234.594751341171;6247.718852651642;6271.968640156792;6281.403266689657;6061.242344706911;6221.7822376216845;6228.03465462854;6249.218847644044;6250.656213908233;6251.562390632657;6264.59280146789;6273.215848018997;6274.623522588637;6287.748490060415;5418.593605479605;5530.059889818741;5547.140193270049;5558.027345214612;5576.302962129734;26618.86690566535;27096.32259193516;27124.728752712417;27580;27905;1908.4370110817342;2153.4401055725466;2212.4358288636454;2226.6882636430914;2230.063373383167;13593.524226652118;17698.646027079514;18143.909280453645;18727.344542940933;18869.056547172586;2171.695962565242;2179.607670619288;2222.577710235066;2224.73303203615;2227.888605569731;121662.78337216603;152079;153602;154609.2269538645;156983.215083925;9726.513674316257;10326.586936522604;10436.843447348296;12551.686207844694;12696.17474864131;16303.91848040776;16541.421050263423;16562.83437165637;16581.668366632843;16643.750343744854;7560.886586701204;8821.294296456268;9090.590923408463;9108.726738197865;9123.406978546373;8684.43551169172;8743.901463648679;8773;8930.106989301017;8933.330000250007;6655.670515319696;7847.450678452567;7929.1484223524185;7947.880781788278;7951.204879512003;2231.28598848367;2242.4302987908204;2244.281829814476;2252.290528483555;2382.0819496117465;817.0195765081926;943.5753910740194;972.2790660530962;997.0976266478848;1027.9383237005804;18979;19649.705254421195;20193.192272308945;20752.48118797012;20980.160793568088;974.6929717139078;992.8957459466652;1024.139722632977;1025.0774303127246;1038.4236748604596;9090.772730681785;9939.503024848873;10707.732306692395;10719.303245289031;10792.244542881923;646699;648002.7489902909;648688;657580;663713;120419;147947;150315.74526382113;151335;152799;1000.8040391731956;1102.3551222534747;1232.3776492871223;1264.9557265495664;1271.9236845789283;17003.40488082911;19896.67558425164;21840.907954602524;22095.796109564344;22206.88896555507;62947.37052629415;63046.16292266951;63458;63668;64473.67763161154;84456.57771711102;84703.7533244022;85289.57355213433;86480.56759716413;89602;8208.507489550646;8663.653453861774;8689.782755431164;8921.15249051339;8936.46381217129;13645.317734113452;13796;13847.446102155802;13911.165330080228;14008.159510429407;9477.289203309918;11793.05655547537;11969;12079.195053558102;12154.75690486203;10364.429956352473;10799.514021869192;11099.056580190774;11187.496562654538;11505.367204803817;9732.756681082747;11662.008729258108;11678.06575473977;12186.87813121893;12246.75506489883;8659;8795.516246606498;8838.734837954871;8869.379143459895;9216.677416290398;12061.396930153282;12108.818367724314;12133.21134126279;12276.877231227574;12361.443735032126 12:21:23 INFO - PID 1636 | |3;mootools.html;1388.201783974215;1417.3621870158424;1428.3215472650486;1437.080268628078;1456.082667919211;10222.802097549025;11233.887661123386;11413.888430640327;11818.349990750516;11837.704057398569;11556.53773849045;11574.26386042094;11598.65204043878;11729.47217375218;11761.823572646395;2489.178571071542;2509.6737424134926;2538;2542.6313184588225;2549.4646124313917;2451.191106934716;2473.492933948536;2499.987500062497;2510.748925107484;2518.26970178648;2470.4441500662388;2490.4645501217233;2506.0852788732072;2512.560301947161;2513.45960618467;2054.7739748627637;2426.927192184246;2488.390344365624;2499.562576549106;2501.0871032073164;1948.4446932624276;2287.3531057638475;2290.633498640226;2491.750824917512;2518.0305582350743;11613.30320180792;11616.186866919319;11668.241564298294;11693.70765730855;11738.413079346084;5828.9417105828825;6421.197350331208;6435;6682.532222744409;6701;3482.791032538056;3996.72022958393;4269.103488267437;4320.546342634009;4328.891777705551;1236.7341021680293;1237.2761934268465;1268.6638040919163;1280.5518068675983;1282.0833104330354;15057.54827355181;15581.22093895312;15989.920050399789;16111.355545778155;16236.594085147966;5390.838274851761;5562.888742225133;5723.942760572424;5736.024875771129;5797.246357973437;12018.098642601839;12066.215695979734;12072.154949153475;12113.515459381526;12135.635930922086;6515;6518;6533.085368048477;6535.738570457223;6543.770968016097;6023.945809483323;6145.877082458326;6150;6184.81445556634;6190.009598464224;4307.547707490731;4412.823487060546;4415.933760993587;4435.379046933432;4491.079328737601;1283.9293838838953;1297.2864924291603;1300.4213125159401;1308.2608326295804;1319.6041187643668;790.1584812175015;793.7499687598388;816.6570040582969;823.4030328012143;843.2874221283006;6151.507879369639;6847.588807303116;6851.48613853963;6974.163100427981;6974.58152510851;586.5981802465325;616.0851136062943;937.6249500199974;959.7024922274118;972.3533849989677;4122.196171746481;4213.431186789744;4247.978760106242;4283.036316828751;4387.605115539617;14720.411183552538;15042.721368683784;15292.464763733216;15326.846731532765;15343.846561534241;14643.12141271527;15054.548363549111;15230.771538426932;15258.237088145548;15400.537983860502;10724.410157441416;10727.731806704738;10934.289271197347;11010.559577616967;11026.283291586022;6469.77355792545;6939.097917270722;7089.574625522485;7098.787036388917;7195.46034047438;10826.945865270623;12537.811932820832;12707.491700331882;12902.032347573782;13012.804807928078;13661.79507307411;13730.862691373359;13798.103123296954;13864.445422183;13917.58247252584;8425.95787021061;9326.813463730552;9498.240140788583;9801.705948821547;9806.166475849634;11167.218294719292;11192.272502287326;11307.773844522899;11309.943450283025;11310.547578096784;9093.22707569864;9257.722268331692;9280.860787088333;9302.976672566148;9323.533823308942;9185.816283674423;9191.540422978691;9252.537373131181;9256.166944974984;9277.582508786985;8219.013718353835;8377.287930525974;8389.790255243668;8399.412041157304;8404.663813447394;6006.429389208193;6039.63762174271;6090.360512146338;6168.136460895388;6181.103739957771;5206.271122042993;6034.849128771816;6136.20229370179;6204.007358822567;6236.781712640217 12:21:23 INFO - PID 1636 | |4;prototype.html;352.2936512292852;401.51015760771884;401.8633664554051;411.56579808302246;413.7517489506296;5587.005291216779;6255.468285195752;6277.874442511147;6285.905711414327;6299.842503937404;4772.761361931907;5780.277465316835;6021.2473440819895;6040.335563088068;6100.969495152529;3499.597546282185;4000;4472.7539985300955;4496.505384407713;4502.122086193193;3618.3898165316314;3980.621840925122;4357.825686972517;4446.354419120634;4448.28827387618;3640.6541378568995;4385.035292235703;4394.47266328041;4412.779361031951;4419.977900110495;4427.358033085201;4428.490723566799;4441.933370999438;4448.243798554254;4450.4436945381985;3593.3711600469815;3729.160938788778;3780.206156707095;3796.0889386547306;3823.9426408603895;5815.505682017034;5860.296764388258;5873.765049398019;5885.970570147165;5909.965755992684;4056.6349028587274;5276.419593844674;5290.867728306785;5304.522592966613;5329.653572517774;6244.687765611747;6418.646974416405;6480.805575832733;6491;6493;4310.80601372939;4312.78436078198;4334.219840428722;4343.090129613167;4360.7819609019425;5936.643801371932;6638.966805165992;6830.931690683129;6870.793876183722;6934.895976560356;4385.6052955233545;4409.5149533551285;4415.2935530315;4421.513633500313;4433.556644335541;4495.9325610115875;4497.280435130363;4511.436070491189;4528.5245049270015;4540.818367265339;4078.1435898461064;5232.372115346183;5319.1223448131395;5324.440933701983;5331.920021199685;6497.9675101625135;6545.312742162099;6557.540972132;6576.901346479806;6599.439047680905;1402.6283034995733;1407.8873690104972;1422.9573112806424;1528.7553991361551;1540.6995635851126;893.4817805672694;960.9087136722001;983.465793362357;1004.4073996342194;1032.6850310655323;6155.984262596621;6170.413810687977;6217.569958909477;6243.188385509855;6302.558820882494;5984.760609575568;6435.292117867031;6752.932470675329;6783.626900520519;6819.624920629413;4660.4174478190225;5962.314333851663;6116;6121.265448146251;6182.629042257479;2802.4395120975887;3219.1791093271536;3468.5317482140094;3499.65003499648;3552.5967242938896;5396.761942834268;5406.243125962368;5418.020088951062;5669.695969926857;6983.615901125487;6862.455947411792;6953.513254072166;6962.721491140399;6973.721051157897;7011.964940175369;6597.921654678857;6746.494012949049;6822.3859852613505;6941.201761797357;6969.721211151497;6799.150106236721;6805.829854253683;6814.420774234246;6815.556988795713;6820.181578210647;6414.407931558342;6618.635975021228;6687.398134167948;6689.46484281266;6700.028495867974;4718.740469274086;6694.163229596301;6937.410320122644;6949.027136200835;6961.965190174017;3533.5583052118486;4282.293421585404;4357.542458041987;4443.155800397914;4453.821847126208;6764;6795.830104247433;6796.898046529207;6804.76183333581;6831.419329357061;5463.7979644478155;6563.704633291418;6568.438309059567;6568.770093046721;6582.637954912334;5512.310961129859;6243;6550.901736474052;6556.868862622626;6561.639109848813;6359.950608149788;6425.646589437439;6461.61230326182;6465.77369792055;6505.31694172124;6000.609960352737;6394.911190097648;6471.7734879279;6488.221413430419;6563.737450501926;5141.38303403594;6144.508439324933;6170.012797952306;6355.80724649995;6420.2937676856445 12:21:23 INFO - PID 1636 | |5;yui.html;710.5701050864227;834.3575446905886;850.2730165708319;877.1404024056425;885.3846576629242;16323.755143672839;16743;16788.664226715457;18531.629367412643;19253.51866203342;17927;17966;18126.3655772048;18221.7266740999;18554.257829686798;974.8050389922026;985.7979114281555;996.7308826616809;1001.4792307999836;1001.4942454060725;848.4145939301893;988.1748739802274;1011.9747006324827;1012.1447376966452;1026.6150193677372;949.8480243161096;1055.1189756553288;1067.8665166854144;1078.245228340161;1080.346390433788;1043.4626167523732;1061.8354155105972;1062.4315990944885;1067.9946600266987;1070.3738313086853;797.5693125712108;798.9800254993613;800.427694198645;804.9154838741965;809.5021561739546;16432.917835410866;16715;16725.498235052968;16885.24016419264;17088.57278568033;16756.581085472837;16936.153192340334;17235.3967611133;17377.47867563975;17429.47711568655;13939.721205575934;14013.369398377097;14129;14334.569962901129;14463.927680361636;3127.5152351385573;3598.298331825295;3618.8371523281517;3622.6739593436455;3624.039629498185;104777.47611262047;108236.4588177062;108958;109237;110196.9999999992;13199.802002969964;13453.26007069601;13566.253856038013;13566.660833479242;13571.796423053465;23683.407914801923;24234.515309693707;25121.62317565238;25158;25554.361140971254;8849.60176792046;9064.818703625891;9152.771180720401;9157.725268241964;9170.266378689688;4791.520847915181;4796.016816552598;4815.518448155157;4917.139500587383;4926.630502712311;4307.8492252771;4381.16757816014;4400.229959757094;4436.733795972252;4442.400275962768;958.7153214692346;961.7018724195522;967.617790972576;971.6647756523988;972.5915115651302;655.7442597387023;674.4907594765921;679.6839469646638;684.5618803965466;691.2120182991393;11159.497822598003;11664.300141991338;11796.823047654292;11856.288622682667;12215.511379544718;639.8720255948829;667.5694177255643;689.3313485918751;690.9550879192936;696.7352406085655;5071.340725705634;5409.134538473825;5495.395506494282;5542.11326187808;5617.354004289477;86014.70977935336;89323.10676893148;89933;90443;91024;85718;87782.56108719416;88127;89372.5531372352;89681;61621;75886;76067.23932760712;77953.66139015839;78563.42873142505;9569.760755981157;10440.843387349349;10772.730681733019;11090.44547772592;11195.04842088399;36003.63996360003;36123.63876361308;36214;36290.4556431659;36635;43473.56526434695;46348.30477542907;47688;52838;60010.39989600223;13579.185248885098;16213.675726485168;16747.33010679608;17134.314627414762;17364.739528906823;18309.359172429435;18334.174962126468;18934.053297335355;19015.42953711391;19090.045497725336;17036;17279.481615551555;17363.7395439066;17514.82485175132;17618.559536011704;16015.91992040079;17182.742258866376;17283.308667653153;17351.82648173502;17590.29638814484;16269.59326016859;16271.918640406722;16488.34046638169;16527.421540246032;16704.331826726793;11406.429678515875;11573.305601663928;11806.940965295118;11915;12035.277883327028;10157.39055656663;12735.363231838186;13293.335333233106;13411.597652070453;13791.517296894963 12:21:23 INFO - PID 1636 | __end_tp_report 12:21:23 INFO - PID 1636 | __start_cc_report 12:21:23 INFO - PID 1636 | _x_x_mozilla_cycle_collect,425 12:21:23 INFO - PID 1636 | __end_cc_report 12:21:23 INFO - PID 1636 | __startTimestamp1492111283224__endTimestamp 12:21:23 INFO - PID 1636 | 12:21:23 INFO - PID 1636 | ------- Summary: start ------- 12:21:23 INFO - PID 1636 | Number of tests: 6 12:21:23 INFO - PID 1636 | 12:21:23 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6090.26 Median:6331.84 stddev:742.94 (11.7%) stddev-sans-first:716.79 12:21:23 INFO - PID 1636 | Values: 3495.5 3501.3 3524.2 3557.5 3568.8 6068.6 6100.0 6106.6 6132.0 6148.4 6128.3 6190.5 6190.5 6232.0 6299.8 4838.6 5422.9 5426.1 5440.4 5455.5 5130.1 5444.5 5519.9 5523.2 5523.6 5355.1 5411.4 5462.8 5468.4 5492.5 5431.8 5433.0 5434.0 5473.9 5517.6 4529.8 5374.3 5377.8 5400.2 5404.9 4752.8 6033.8 6118.9 6170.4 6257.4 6572.3 6620.6 6628.5 6645.9 6651.1 5015.4 6726.3 6728.7 6732.2 6738.1 4837.6 5865.9 6168.8 6351.1 6357.9 6851.8 6898.3 6901.4 6924.1 6926.0 6313.0 6321.6 6412.9 6465.4 6475.2 6671.4 6774.0 6774.7 6786.3 6787.7 6374.1 6484.0 6634.6 6661.2 6688.6 6074.5 6146.8 6152.0 6555.9 6563.9 5366.7 5376.1 5392.0 5392.2 5400.2 4776.0 4781.3 4804.6 4810.0 4835.3 5504.9 6102.3 6186.2 6293.3 6373.6 6666.8 6747.1 6747.2 6752.7 6761.3 6313.7 6316.6 6331.8 6332.9 6334.9 5749.5 5883.5 5951.8 5966.1 5997.5 6650.2 6762.1 6843.8 6910.1 6917.1 6717.7 6722.8 6737.6 6743.4 6761.2 6775.7 6810.1 6851.5 6872.2 6888.5 6395.8 6485.6 6553.9 6592.6 6619.8 6753.8 6767.8 6773.2 6782.4 6787.3 6142.6 6351.4 6376.5 6462.4 6554.3 5474.4 6453.6 6502.3 6505.2 6516.9 6083.1 6290.3 6328.2 6335.2 6346.2 5472.7 6497.3 6513.4 6551.5 6558.5 6321.8 6335.3 6346.5 6352.8 6366.4 12:21:23 INFO - PID 1636 | 12:21:23 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:42440.45 Median:3175.17 stddev:71095.25 (2239.1%) stddev-sans-first:71225.77 12:21:23 INFO - PID 1636 | Values: 746.8 800.7 823.3 1275.3 1279.2 32774.5 37303.0 38342.2 38386.6 38453.0 38273.6 38516.0 38656.4 38738.2 38762.6 7952.7 8109.3 8508.5 8733.9 8770.2 7299.9 7415.7 8588.3 8679.2 8727.3 7282.8 8686.5 8695.5 8736.7 8737.8 8686.3 8709.6 8751.7 8758.3 8785.6 8630.9 8638.3 8638.8 8642.3 8679.0 37677.8 37847.2 37988.4 38012.2 38175.8 3582.6 3583.5 3618.0 3620.0 3623.0 23739.5 30417.7 30476.0 30692.0 30757.8 3122.2 3154.0 3156.9 3173.7 3176.6 221854.0 226677.0 250886.5 261840.0 265504.7 47222.0 47360.3 47426.8 47436.8 47455.0 49164.8 49170.5 49235.5 49294.0 49316.0 14358.4 14413.6 14414.4 14557.6 14586.7 11947.8 11967.5 12006.1 12011.8 12052.2 12101.6 12862.9 13404.6 13427.7 13475.5 5926.1 5960.3 5966.4 5988.2 6136.1 960.4 1026.2 1054.2 1055.6 1083.0 23085.3 24176.0 24263.4 24428.4 24527.9 1129.3 1129.7 1134.2 1165.6 1183.0 12316.9 12370.5 12432.6 12453.9 12470.3 307040.0 308427.5 309544.0 309770.0 312250.0 51626.2 51734.0 52113.0 52114.0 52206.5 1493.8 1498.8 1535.2 1536.2 1537.5 23611.5 23647.6 23748.6 23808.4 23926.0 31302.0 37073.6 37353.4 37359.7 37494.4 255093.0 255829.0 256267.0 256736.0 256995.0 33767.8 34217.3 35007.8 35130.8 35160.8 36465.0 44434.6 45123.3 45529.0 45539.3 24708.5 29986.9 30850.2 30883.0 31253.8 30623.7 30918.7 30992.5 31054.5 31723.0 36575.0 36880.0 37235.8 37325.0 37449.8 30394.2 30969.1 31360.5 31387.8 31694.2 21510.8 21768.9 22761.0 24155.9 24343.0 12:21:23 INFO - PID 1636 | 12:21:23 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:39030.20 Median:12375.75 stddev:109682.23 (886.3%) stddev-sans-first:109951.63 12:21:23 INFO - PID 1636 | Values: 432.1 435.2 439.4 451.4 460.3 19739.0 21311.8 22013.8 22114.7 22232.4 21444.7 21837.6 22129.7 22499.2 22805.9 5482.6 6059.0 6133.2 6238.1 6252.3 5977.0 6234.6 6247.7 6272.0 6281.4 6061.2 6221.8 6228.0 6249.2 6250.7 6251.6 6264.6 6273.2 6274.6 6287.7 5418.6 5530.1 5547.1 5558.0 5576.3 26618.9 27096.3 27124.7 27580.0 27905.0 1908.4 2153.4 2212.4 2226.7 2230.1 13593.5 17698.6 18143.9 18727.3 18869.1 2171.7 2179.6 2222.6 2224.7 2227.9 121662.8 152079.0 153602.0 154609.2 156983.2 9726.5 10326.6 10436.8 12551.7 12696.2 16303.9 16541.4 16562.8 16581.7 16643.8 7560.9 8821.3 9090.6 9108.7 9123.4 8684.4 8743.9 8773.0 8930.1 8933.3 6655.7 7847.5 7929.1 7947.9 7951.2 2231.3 2242.4 2244.3 2252.3 2382.1 817.0 943.6 972.3 997.1 1027.9 18979.0 19649.7 20193.2 20752.5 20980.2 974.7 992.9 1024.1 1025.1 1038.4 9090.8 9939.5 10707.7 10719.3 10792.2 646699.0 648002.7 648688.0 657580.0 663713.0 120419.0 147947.0 150315.7 151335.0 152799.0 1000.8 1102.4 1232.4 1265.0 1271.9 17003.4 19896.7 21840.9 22095.8 22206.9 62947.4 63046.2 63458.0 63668.0 64473.7 84456.6 84703.8 85289.6 86480.6 89602.0 8208.5 8663.7 8689.8 8921.2 8936.5 13645.3 13796.0 13847.4 13911.2 14008.2 9477.3 11793.1 11969.0 12079.2 12154.8 10364.4 10799.5 11099.1 11187.5 11505.4 9732.8 11662.0 11678.1 12186.9 12246.8 8659.0 8795.5 8838.7 8869.4 9216.7 12061.4 12108.8 12133.2 12276.9 12361.4 12:21:23 INFO - PID 1636 | 12:21:23 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:7177.28 Median:2518.15 stddev:4513.21 (179.2%) stddev-sans-first:4504.91 12:21:23 INFO - PID 1636 | Values: 1388.2 1417.4 1428.3 1437.1 1456.1 10222.8 11233.9 11413.9 11818.3 11837.7 11556.5 11574.3 11598.7 11729.5 11761.8 2489.2 2509.7 2538.0 2542.6 2549.5 2451.2 2473.5 2500.0 2510.7 2518.3 2470.4 2490.5 2506.1 2512.6 2513.5 2054.8 2426.9 2488.4 2499.6 2501.1 1948.4 2287.4 2290.6 2491.8 2518.0 11613.3 11616.2 11668.2 11693.7 11738.4 5828.9 6421.2 6435.0 6682.5 6701.0 3482.8 3996.7 4269.1 4320.5 4328.9 1236.7 1237.3 1268.7 1280.6 1282.1 15057.5 15581.2 15989.9 16111.4 16236.6 5390.8 5562.9 5723.9 5736.0 5797.2 12018.1 12066.2 12072.2 12113.5 12135.6 6515.0 6518.0 6533.1 6535.7 6543.8 6023.9 6145.9 6150.0 6184.8 6190.0 4307.5 4412.8 4415.9 4435.4 4491.1 1283.9 1297.3 1300.4 1308.3 1319.6 790.2 793.7 816.7 823.4 843.3 6151.5 6847.6 6851.5 6974.2 6974.6 586.6 616.1 937.6 959.7 972.4 4122.2 4213.4 4248.0 4283.0 4387.6 14720.4 15042.7 15292.5 15326.8 15343.8 14643.1 15054.5 15230.8 15258.2 15400.5 10724.4 10727.7 10934.3 11010.6 11026.3 6469.8 6939.1 7089.6 7098.8 7195.5 10826.9 12537.8 12707.5 12902.0 13012.8 13661.8 13730.9 13798.1 13864.4 13917.6 8426.0 9326.8 9498.2 9801.7 9806.2 11167.2 11192.3 11307.8 11309.9 11310.5 9093.2 9257.7 9280.9 9303.0 9323.5 9185.8 9191.5 9252.5 9256.2 9277.6 8219.0 8377.3 8389.8 8399.4 8404.7 6006.4 6039.6 6090.4 6168.1 6181.1 5206.3 6034.8 6136.2 6204.0 6236.8 12:21:23 INFO - PID 1636 | 12:21:23 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5228.23 Median:5973.54 stddev:1687.50 (28.2%) stddev-sans-first:1652.07 12:21:23 INFO - PID 1636 | Values: 352.3 401.5 401.9 411.6 413.8 5587.0 6255.5 6277.9 6285.9 6299.8 4772.8 5780.3 6021.2 6040.3 6101.0 3499.6 4000.0 4472.8 4496.5 4502.1 3618.4 3980.6 4357.8 4446.4 4448.3 3640.7 4385.0 4394.5 4412.8 4420.0 4427.4 4428.5 4441.9 4448.2 4450.4 3593.4 3729.2 3780.2 3796.1 3823.9 5815.5 5860.3 5873.8 5886.0 5910.0 4056.6 5276.4 5290.9 5304.5 5329.7 6244.7 6418.6 6480.8 6491.0 6493.0 4310.8 4312.8 4334.2 4343.1 4360.8 5936.6 6639.0 6830.9 6870.8 6934.9 4385.6 4409.5 4415.3 4421.5 4433.6 4495.9 4497.3 4511.4 4528.5 4540.8 4078.1 5232.4 5319.1 5324.4 5331.9 6498.0 6545.3 6557.5 6576.9 6599.4 1402.6 1407.9 1423.0 1528.8 1540.7 893.5 960.9 983.5 1004.4 1032.7 6156.0 6170.4 6217.6 6243.2 6302.6 5984.8 6435.3 6752.9 6783.6 6819.6 4660.4 5962.3 6116.0 6121.3 6182.6 2802.4 3219.2 3468.5 3499.7 3552.6 5396.8 5406.2 5418.0 5669.7 6983.6 6862.5 6953.5 6962.7 6973.7 7012.0 6597.9 6746.5 6822.4 6941.2 6969.7 6799.2 6805.8 6814.4 6815.6 6820.2 6414.4 6618.6 6687.4 6689.5 6700.0 4718.7 6694.2 6937.4 6949.0 6962.0 3533.6 4282.3 4357.5 4443.2 4453.8 6764.0 6795.8 6796.9 6804.8 6831.4 5463.8 6563.7 6568.4 6568.8 6582.6 5512.3 6243.0 6550.9 6556.9 6561.6 6360.0 6425.6 6461.6 6465.8 6505.3 6000.6 6394.9 6471.8 6488.2 6563.7 5141.4 6144.5 6170.0 6355.8 6420.3 12:21:23 INFO - PID 1636 | 12:21:23 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:20445.29 Median:19052.74 stddev:27065.48 (142.1%) stddev-sans-first:27100.84 12:21:23 INFO - PID 1636 | Values: 710.6 834.4 850.3 877.1 885.4 16323.8 16743.0 16788.7 18531.6 19253.5 17927.0 17966.0 18126.4 18221.7 18554.3 974.8 985.8 996.7 1001.5 1001.5 848.4 988.2 1012.0 1012.1 1026.6 949.8 1055.1 1067.9 1078.2 1080.3 1043.5 1061.8 1062.4 1068.0 1070.4 797.6 799.0 800.4 804.9 809.5 16432.9 16715.0 16725.5 16885.2 17088.6 16756.6 16936.2 17235.4 17377.5 17429.5 13939.7 14013.4 14129.0 14334.6 14463.9 3127.5 3598.3 3618.8 3622.7 3624.0 104777.5 108236.5 108958.0 109237.0 110197.0 13199.8 13453.3 13566.3 13566.7 13571.8 23683.4 24234.5 25121.6 25158.0 25554.4 8849.6 9064.8 9152.8 9157.7 9170.3 4791.5 4796.0 4815.5 4917.1 4926.6 4307.8 4381.2 4400.2 4436.7 4442.4 958.7 961.7 967.6 971.7 972.6 655.7 674.5 679.7 684.6 691.2 11159.5 11664.3 11796.8 11856.3 12215.5 639.9 667.6 689.3 691.0 696.7 5071.3 5409.1 5495.4 5542.1 5617.4 86014.7 89323.1 89933.0 90443.0 91024.0 85718.0 87782.6 88127.0 89372.6 89681.0 61621.0 75886.0 76067.2 77953.7 78563.4 9569.8 10440.8 10772.7 11090.4 11195.0 36003.6 36123.6 36214.0 36290.5 36635.0 43473.6 46348.3 47688.0 52838.0 60010.4 13579.2 16213.7 16747.3 17134.3 17364.7 18309.4 18334.2 18934.1 19015.4 19090.0 17036.0 17279.5 17363.7 17514.8 17618.6 16015.9 17182.7 17283.3 17351.8 17590.3 16269.6 16271.9 16488.3 16527.4 16704.3 11406.4 11573.3 11806.9 11915.0 12035.3 10157.4 12735.4 13293.3 13411.6 13791.5 12:21:23 INFO - PID 1636 | -------- Summary: end -------- 12:21:23 INFO - PID 1636 | 12:21:23 INFO - TEST-INFO | 1636: exit 0 12:21:23 INFO - TEST-OK | dromaeo_css | took 1115292ms 12:21:23 INFO - TEST-START | kraken 12:21:23 INFO - operating with platform_type : mac_ 12:21:23 INFO - Initialising browser for kraken test... 12:21:23 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpWv5I2o/profile http://localhost:49236/getInfo.html) 12:21:26 INFO - PID 1647 | 2017-04-13 12:21:26.833 plugin-container[1648:9978] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444f, name = 'com.apple.tsm.portname' 12:21:26 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:21:26 INFO - PID 1647 | 2017-04-13 12:21:26.836 plugin-container[1648:9978] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 12:21:26 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:21:26 INFO - PID 1647 | 2017-04-13 12:21:26.836 plugin-container[1648:9978] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:21:26 INFO - PID 1647 | __metrics Screen width/height:1600/1200 12:21:26 INFO - PID 1647 | colorDepth:24 12:21:26 INFO - PID 1647 | Browser inner width/height: 1024/689 12:21:26 INFO - PID 1647 | __metrics 12:21:27 ERROR - PID 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 12:21:27 INFO - TEST-INFO | 1647: exit 0 12:21:27 INFO - Browser initialized. 12:21:27 INFO - Running cycle 1/1 for kraken test... 12:21:27 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpWv5I2o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:21:30 INFO - PID 1649 | 2017-04-13 12:21:30.309 plugin-container[1650:10104] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x872b, name = 'com.apple.tsm.portname' 12:21:30 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:21:30 INFO - PID 1649 | 2017-04-13 12:21:30.310 plugin-container[1650:10104] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a17, name = 'com.apple.CFPasteboardClient' 12:21:30 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:21:30 INFO - PID 1649 | 2017-04-13 12:21:30.310 plugin-container[1650:10104] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:21:40 INFO - PID 1649 | RSS: Main: 234446848 12:21:40 INFO - PID 1649 | 12:21:42 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 12:21:43 INFO - PID 1649 | RSS: Main: 248897536 12:21:43 INFO - PID 1649 | 12:21:46 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 12:21:47 INFO - PID 1649 | RSS: Main: 257576960 12:21:47 INFO - PID 1649 | 12:21:50 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 12:21:51 INFO - PID 1649 | RSS: Main: 260079616 12:21:51 INFO - PID 1649 | 12:21:53 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 12:21:53 INFO - PID 1649 | RSS: Main: 261255168 12:21:53 INFO - PID 1649 | 12:21:55 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:21:56 INFO - PID 1649 | RSS: Main: 262975488 12:21:56 INFO - PID 1649 | 12:21:59 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 12:21:59 INFO - PID 1649 | RSS: Main: 268398592 12:21:59 INFO - PID 1649 | 12:22:03 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 12:22:03 INFO - PID 1649 | RSS: Main: 269062144 12:22:03 INFO - PID 1649 | 12:22:06 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 12:22:07 INFO - PID 1649 | RSS: Main: 268931072 12:22:07 INFO - PID 1649 | 12:22:08 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:22:09 INFO - PID 1649 | RSS: Main: 268484608 12:22:09 INFO - PID 1649 | 12:22:11 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:22:12 INFO - PID 1649 | RSS: Main: 268664832 12:22:12 INFO - PID 1649 | 12:22:14 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:22:14 INFO - PID 1649 | RSS: Main: 268615680 12:22:14 INFO - PID 1649 | 12:22:17 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:22:18 INFO - PID 1649 | RSS: Main: 268955648 12:22:18 INFO - PID 1649 | 12:22:21 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:22:22 INFO - PID 1649 | RSS: Main: 268947456 12:22:22 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 12:22:24 INFO - PID 1649 | RSS: Main: 270413824 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | __start_tp_report 12:22:24 INFO - PID 1649 | _x_x_mozilla_page_load 12:22:24 INFO - PID 1649 | _x_x_mozilla_page_load_details 12:22:24 INFO - PID 1649 | |i|pagename|runs| 12:22:24 INFO - PID 1649 | |0;ai-astar;104;173;128;115;117;116;117;118;118;116 12:22:24 INFO - PID 1649 | |1;audio-beat-detection;144;113;133;188;146;114;115;111;135;130 12:22:24 INFO - PID 1649 | |2;audio-dft;196;201;214;223;223;225;224;203;201;195 12:22:24 INFO - PID 1649 | |3;audio-fft;77;59;58;89;59;58;66;64;76;59 12:22:24 INFO - PID 1649 | |4;audio-oscillator;84;70;83;70;75;72;85;73;98;118 12:22:24 INFO - PID 1649 | |5;imaging-gaussian-blur;129;138;89;90;89;88;89;88;89;90 12:22:24 INFO - PID 1649 | |6;imaging-darkroom;96;121;154;98;96;96;137;98;117;101 12:22:24 INFO - PID 1649 | |7;imaging-desaturate;99;93;90;91;89;91;94;92;90;91 12:22:24 INFO - PID 1649 | |8;json-parse-financial;45;44;73;80;60;54;53;51;53;53 12:22:24 INFO - PID 1649 | |9;json-stringify-tinderbox;57;63;58;77;91;97;74;57;58;59 12:22:24 INFO - PID 1649 | |10;stanford-crypto-aes;85;83;83;83;84;84;84;87;85;84 12:22:24 INFO - PID 1649 | |11;stanford-crypto-ccm;140;140;142;141;137;140;140;144;198;151 12:22:24 INFO - PID 1649 | |12;stanford-crypto-pbkdf2;189;192;185;187;185;186;189;184;188;188 12:22:24 INFO - PID 1649 | |13;stanford-crypto-sha256-iterative;63;103;104;102;97;86;72;99;97;103 12:22:24 INFO - PID 1649 | __end_tp_report 12:22:24 INFO - PID 1649 | __start_cc_report 12:22:24 INFO - PID 1649 | _x_x_mozilla_cycle_collect,1001 12:22:24 INFO - PID 1649 | __end_cc_report 12:22:24 INFO - PID 1649 | __startTimestamp1492111344422__endTimestamp 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | ------- Summary: start ------- 12:22:24 INFO - PID 1649 | Number of tests: 14 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | [#0] ai-astar Cycles:10 Average:122.20 Median:117.50 stddev:18.75 (16.0%) stddev-sans-first:18.69 12:22:24 INFO - PID 1649 | Values: 104.0 173.0 128.0 115.0 117.0 116.0 117.0 118.0 118.0 116.0 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | [#1] audio-beat-detection Cycles:10 Average:132.90 Median:134.00 stddev:23.33 (17.4%) stddev-sans-first:24.39 12:22:24 INFO - PID 1649 | Values: 144.0 113.0 133.0 188.0 146.0 114.0 115.0 111.0 135.0 130.0 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | [#2] audio-dft Cycles:10 Average:210.50 Median:218.50 stddev:12.49 (5.7%) stddev-sans-first:12.10 12:22:24 INFO - PID 1649 | Values: 196.0 201.0 214.0 223.0 223.0 225.0 224.0 203.0 201.0 195.0 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | [#3] audio-fft Cycles:10 Average:66.50 Median:65.00 stddev:10.68 (16.4%) stddev-sans-first:10.63 12:22:24 INFO - PID 1649 | Values: 77.0 59.0 58.0 89.0 59.0 58.0 66.0 64.0 76.0 59.0 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | [#4] audio-oscillator Cycles:10 Average:82.80 Median:79.00 stddev:15.19 (19.2%) stddev-sans-first:16.11 12:22:24 INFO - PID 1649 | Values: 84.0 70.0 83.0 70.0 75.0 72.0 85.0 73.0 98.0 118.0 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:97.90 Median:89.00 stddev:18.89 (21.2%) stddev-sans-first:16.35 12:22:24 INFO - PID 1649 | Values: 129.0 138.0 89.0 90.0 89.0 88.0 89.0 88.0 89.0 90.0 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | [#6] imaging-darkroom Cycles:10 Average:111.40 Median:96.00 stddev:20.47 (21.3%) stddev-sans-first:20.94 12:22:24 INFO - PID 1649 | Values: 96.0 121.0 154.0 98.0 96.0 96.0 137.0 98.0 117.0 101.0 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | [#7] imaging-desaturate Cycles:10 Average:92.00 Median:91.50 stddev:2.87 (3.1%) stddev-sans-first:1.56 12:22:24 INFO - PID 1649 | Values: 99.0 93.0 90.0 91.0 89.0 91.0 94.0 92.0 90.0 91.0 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:53.50 stddev:11.54 (21.6%) stddev-sans-first:11.45 12:22:24 INFO - PID 1649 | Values: 45.0 44.0 73.0 80.0 60.0 54.0 53.0 51.0 53.0 53.0 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:68.50 stddev:14.99 (21.9%) stddev-sans-first:15.25 12:22:24 INFO - PID 1649 | Values: 57.0 63.0 58.0 77.0 91.0 97.0 74.0 57.0 58.0 59.0 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.00 stddev:1.23 (1.5%) stddev-sans-first:1.27 12:22:24 INFO - PID 1649 | Values: 85.0 83.0 83.0 83.0 84.0 84.0 84.0 87.0 85.0 84.0 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:141.50 stddev:18.20 (12.9%) stddev-sans-first:19.11 12:22:24 INFO - PID 1649 | Values: 140.0 140.0 142.0 141.0 137.0 140.0 140.0 144.0 198.0 151.0 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.30 Median:188.00 stddev:2.41 (1.3%) stddev-sans-first:2.47 12:22:24 INFO - PID 1649 | Values: 189.0 192.0 185.0 187.0 185.0 186.0 189.0 184.0 188.0 188.0 12:22:24 INFO - PID 1649 | 12:22:24 INFO - PID 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:92.60 Median:79.00 stddev:14.37 (18.2%) stddev-sans-first:10.52 12:22:24 INFO - PID 1649 | Values: 63.0 103.0 104.0 102.0 97.0 86.0 72.0 99.0 97.0 103.0 12:22:24 INFO - PID 1649 | -------- Summary: end -------- 12:22:24 INFO - PID 1649 | 12:22:25 INFO - TEST-INFO | 1649: exit 0 12:22:25 INFO - TEST-OK | kraken | took 61657ms 12:22:25 INFO - SUITE-END | took 1176s 12:22:25 INFO - Completed test suite (00:19:36) 12:22:25 INFO - javascript benchmark 12:22:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7855.536056747752, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3495.4756786482026, 3501.2997400519885, 3524.2246705724733, 3557.5019497270387, 3568.80371579563, 6068.6055406398555, 6099.975683468232, 6106.6030708004, 6132.0, 6148.415900489452, 6128.2646082470055, 6190.473809726179, 6190.535709821759, 6231.968840155804, 6299.77950771723, 4838.63710221733, 5422.91865622016, 5426.131818908975, 5440.401555828856, 5455.509004189622, 5130.127878260686, 5444.455554444564, 5519.862003449905, 5523.199136125279, 5523.608731058818, 5355.143177091685, 5411.350637923474, 5462.754176062087, 5468.371137319219, 5492.450754924515, 5431.809886653988, 5432.972835135799, 5434.0218760623075, 5473.945260547383, 5517.641353312022, 4529.796159172845, 5374.274472946141, 5377.838664840061, 5400.21696853954, 5404.864878378072, 4752.7861246244, 6033.788817391414, 6118.908216376758, 6170.413810687977, 6257.374262573751, 6572.342765723437, 6620.6358650274215, 6628.5028622853, 6645.9366501359555, 6651.102101216322, 5015.3730783652045, 6726.327367263283, 6728.697208625624, 6732.158480189976, 6738.124043874314, 4837.612990960714, 5865.941340586625, 6168.753249869955, 6351.110844481776, 6357.904631430486, 6851.760188393382, 6898.344657257552, 6901.413379862667, 6924.065251191126, 6926.030355750199, 6313.0, 6321.6207027577575, 6412.941864592383, 6465.353464653497, 6475.222973243147, 6671.432928201158, 6773.966130169318, 6774.661266936633, 6786.253512113663, 6787.660616969132, 6374.075759014917, 6484.027395890655, 6634.568753031038, 6661.233958094785, 6688.598684078971, 6074.51403887688, 6146.801373732169, 6152.046432802923, 6555.868882622321, 6563.934360656428, 5366.731663416892, 5376.086065368819, 5392.0294347017125, 5392.191171324254, 5400.189971504227, 4775.95224047762, 4781.306710526954, 4804.615630749601, 4810.0, 4835.250536166829, 5504.944950550524, 6102.298235702951, 6186.226721659793, 6293.339199383999, 6373.6175829450385, 6666.799996000128, 6747.089142965735, 6747.190337159573, 6752.729890804313, 6761.290064543152, 6313.747450102036, 6316.589421687576, 6331.841703957346, 6332.936670633235, 6334.936650633527, 5749.482546570829, 5883.499902508334, 5951.761929522857, 5966.075258334965, 5997.460228579449, 6650.168728908983, 6762.053312536347, 6843.828904277234, 6910.06714093611, 6917.066196063467, 6717.731290748316, 6722.798316050526, 6737.56205846638, 6743.426808721314, 6761.188657361148, 6775.695093720893, 6810.080639113657, 6851.48613853953, 6872.209695885037, 6888.483363747639, 6395.840103997437, 6485.643289619116, 6553.868922621426, 6592.637404942582, 6619.801405957829, 6753.797386078426, 6767.763128290486, 6773.187217533928, 6782.423494003065, 6787.253402125663, 6142.590862071594, 6351.396617321347, 6376.458001069961, 6462.386073323027, 6554.279029306677, 5474.370447398441, 6453.644105065434, 6502.349765023459, 6505.186851643544, 6516.869662606816, 6083.057126145454, 6290.308066112632, 6328.24061112651, 6335.239771227482, 6346.174997250328, 5472.6990015547935, 6497.317781632861, 6513.413792758674, 6551.475881929339, 6558.540902136995, 6321.778737744157, 6335.303116657072, 6346.492280617447, 6352.777652782131, 6366.363363663597], "value": 6042.691451199441, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.8282295072133, 800.7317548621212, 823.2714048067459, 1275.2985857778226, 1279.2324605236865, 32774.50838237425, 37303.0, 38342.23315533688, 38386.61613383865, 38453.0, 38273.61726382736, 38516.0, 38656.42015369765, 38738.22523549527, 38762.612373876254, 7952.681892724284, 8109.270165685087, 8508.48949063055, 8733.912660873406, 8770.210681038705, 7299.89050164248, 7415.66629501671, 8588.312934965219, 8679.17547832955, 8727.301815854717, 7282.834746440544, 8686.478811271312, 8695.478271303711, 8736.694215702451, 8737.781555461133, 8686.305095592368, 8709.60806763694, 8751.693690720795, 8758.255548278405, 8785.6046477908, 8630.913690863137, 8638.308935285162, 8638.827223455495, 8642.265407440374, 8679.0, 37677.811610942044, 37847.243055138744, 37988.43017354742, 38012.23975520502, 38175.809120954495, 3582.5880023797336, 3583.498310236569, 3618.0231337538717, 3619.9683090319395, 3623.0398944279787, 23739.525209495885, 30417.69582304193, 30476.0, 30692.0, 30757.769689212404, 3122.2350524121507, 3153.984230078835, 3156.889508867179, 3173.730232930204, 3176.6029246344206, 221854.0, 226677.0, 250886.49113508995, 261840.0, 265504.67247663834, 47222.0, 47360.28959565609, 47426.76286618614, 47436.7628161857, 47455.0, 49164.7541762296, 49170.508294916595, 49235.50764492309, 49294.0, 49316.0, 14358.425662973574, 14413.567592972227, 14414.351354189086, 14557.636059098608, 14586.708265834624, 11947.76104477923, 11967.521299148111, 12006.099542534344, 12011.759764804656, 12052.216605920587, 12101.608315043706, 12862.871371286354, 13404.597862064154, 13427.731445371039, 13475.528356507475, 5926.111083337448, 5960.314563825215, 5966.373530779292, 5988.221531200916, 6136.110264011693, 960.4381436859376, 1026.1534234256767, 1054.1724746074449, 1055.6041484443333, 1082.978340433187, 23085.307440776804, 24176.0, 24263.39341516441, 24428.389290267885, 24527.87736061308, 1129.3449799116493, 1129.717570607348, 1134.2117228526095, 1165.5570883064381, 1182.9645110646695, 12316.876831231573, 12370.505179792708, 12432.627021189379, 12453.875461245272, 12470.251784892935, 307040.0, 308427.45786270924, 309544.0, 309770.0, 312250.0, 51626.22560661593, 51733.96532069337, 52112.957740845726, 52114.0, 52206.47793522016, 1493.768465887789, 1498.7976623156171, 1535.2016951185321, 1536.1934984133195, 1537.477257732354, 23611.45828125795, 23647.64528532108, 23748.643770343806, 23808.4047898797, 23926.0, 31302.0, 37073.6292637081, 37353.439698404, 37359.69241076658, 37494.43758343573, 255093.0, 255829.0, 256267.0, 256736.0, 256995.0, 33767.83116084404, 34217.31565368629, 35007.824960875034, 35130.82434587811, 35160.82419587886, 36465.0, 44434.555654443044, 45123.323150152115, 45529.0, 45539.31691024571, 24708.505829882943, 29986.850065750405, 30850.228744280674, 30883.0, 31253.84373078211, 30623.693763062976, 30918.69081309158, 30992.53511197379, 31054.534181986837, 31723.0, 36575.0, 36880.0, 37235.81382093072, 37325.0, 37449.81275093607, 30394.240143996576, 30969.0709278722, 31360.529592055682, 31387.84306078455, 31694.207644809063, 21510.784892151503, 21768.89115554412, 22761.0, 24155.912983916118, 24343.0], "value": 17362.085035792694, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.12279073480806, 435.22529896783715, 439.3651174053492, 451.3906226594098, 460.3141319434042, 19739.0, 21311.786882131175, 22013.779862201372, 22114.668279975773, 22232.444188895286, 21444.678329825067, 21837.563248735016, 22129.66805497919, 22499.21252756154, 22805.859707014664, 5482.643628164176, 6059.0, 6133.233345831771, 6238.095476155955, 6252.34350393209, 5977.0, 6234.594751341171, 6247.718852651642, 6271.968640156792, 6281.403266689657, 6061.242344706911, 6221.7822376216845, 6228.03465462854, 6249.218847644044, 6250.656213908233, 6251.562390632657, 6264.59280146789, 6273.215848018997, 6274.623522588637, 6287.748490060415, 5418.593605479605, 5530.059889818741, 5547.140193270049, 5558.027345214612, 5576.302962129734, 26618.86690566535, 27096.32259193516, 27124.728752712417, 27580.0, 27905.0, 1908.4370110817342, 2153.4401055725466, 2212.4358288636454, 2226.6882636430914, 2230.063373383167, 13593.524226652118, 17698.646027079514, 18143.909280453645, 18727.344542940933, 18869.056547172586, 2171.695962565242, 2179.607670619288, 2222.577710235066, 2224.73303203615, 2227.888605569731, 121662.78337216603, 152079.0, 153602.0, 154609.2269538645, 156983.215083925, 9726.513674316257, 10326.586936522604, 10436.843447348296, 12551.686207844694, 12696.17474864131, 16303.91848040776, 16541.421050263423, 16562.83437165637, 16581.668366632843, 16643.750343744854, 7560.886586701204, 8821.294296456268, 9090.590923408463, 9108.726738197865, 9123.406978546373, 8684.43551169172, 8743.901463648679, 8773.0, 8930.106989301017, 8933.330000250007, 6655.670515319696, 7847.450678452567, 7929.1484223524185, 7947.880781788278, 7951.204879512003, 2231.28598848367, 2242.4302987908204, 2244.281829814476, 2252.290528483555, 2382.0819496117465, 817.0195765081926, 943.5753910740194, 972.2790660530962, 997.0976266478848, 1027.9383237005804, 18979.0, 19649.705254421195, 20193.192272308945, 20752.48118797012, 20980.160793568088, 974.6929717139078, 992.8957459466652, 1024.139722632977, 1025.0774303127246, 1038.4236748604596, 9090.772730681785, 9939.503024848873, 10707.732306692395, 10719.303245289031, 10792.244542881923, 646699.0, 648002.7489902909, 648688.0, 657580.0, 663713.0, 120419.0, 147947.0, 150315.74526382113, 151335.0, 152799.0, 1000.8040391731956, 1102.3551222534747, 1232.3776492871223, 1264.9557265495664, 1271.9236845789283, 17003.40488082911, 19896.67558425164, 21840.907954602524, 22095.796109564344, 22206.88896555507, 62947.37052629415, 63046.16292266951, 63458.0, 63668.0, 64473.67763161154, 84456.57771711102, 84703.7533244022, 85289.57355213433, 86480.56759716413, 89602.0, 8208.507489550646, 8663.653453861774, 8689.782755431164, 8921.15249051339, 8936.46381217129, 13645.317734113452, 13796.0, 13847.446102155802, 13911.165330080228, 14008.159510429407, 9477.289203309918, 11793.05655547537, 11969.0, 12079.195053558102, 12154.75690486203, 10364.429956352473, 10799.514021869192, 11099.056580190774, 11187.496562654538, 11505.367204803817, 9732.756681082747, 11662.008729258108, 11678.06575473977, 12186.87813121893, 12246.75506489883, 8659.0, 8795.516246606498, 8838.734837954871, 8869.379143459895, 9216.677416290398, 12061.396930153282, 12108.818367724314, 12133.21134126279, 12276.877231227574, 12361.443735032126], "value": 10536.090259331313, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.201783974215, 1417.3621870158424, 1428.3215472650486, 1437.080268628078, 1456.082667919211, 10222.802097549025, 11233.887661123386, 11413.888430640327, 11818.349990750516, 11837.704057398569, 11556.53773849045, 11574.26386042094, 11598.65204043878, 11729.47217375218, 11761.823572646395, 2489.178571071542, 2509.6737424134926, 2538.0, 2542.6313184588225, 2549.4646124313917, 2451.191106934716, 2473.492933948536, 2499.987500062497, 2510.748925107484, 2518.26970178648, 2470.4441500662388, 2490.4645501217233, 2506.0852788732072, 2512.560301947161, 2513.45960618467, 2054.7739748627637, 2426.927192184246, 2488.390344365624, 2499.562576549106, 2501.0871032073164, 1948.4446932624276, 2287.3531057638475, 2290.633498640226, 2491.750824917512, 2518.0305582350743, 11613.30320180792, 11616.186866919319, 11668.241564298294, 11693.70765730855, 11738.413079346084, 5828.9417105828825, 6421.197350331208, 6435.0, 6682.532222744409, 6701.0, 3482.791032538056, 3996.72022958393, 4269.103488267437, 4320.546342634009, 4328.891777705551, 1236.7341021680293, 1237.2761934268465, 1268.6638040919163, 1280.5518068675983, 1282.0833104330354, 15057.54827355181, 15581.22093895312, 15989.920050399789, 16111.355545778155, 16236.594085147966, 5390.838274851761, 5562.888742225133, 5723.942760572424, 5736.024875771129, 5797.246357973437, 12018.098642601839, 12066.215695979734, 12072.154949153475, 12113.515459381526, 12135.635930922086, 6515.0, 6518.0, 6533.085368048477, 6535.738570457223, 6543.770968016097, 6023.945809483323, 6145.877082458326, 6150.0, 6184.81445556634, 6190.009598464224, 4307.547707490731, 4412.823487060546, 4415.933760993587, 4435.379046933432, 4491.079328737601, 1283.9293838838953, 1297.2864924291603, 1300.4213125159401, 1308.2608326295804, 1319.6041187643668, 790.1584812175015, 793.7499687598388, 816.6570040582969, 823.4030328012143, 843.2874221283006, 6151.507879369639, 6847.588807303116, 6851.48613853963, 6974.163100427981, 6974.58152510851, 586.5981802465325, 616.0851136062943, 937.6249500199974, 959.7024922274118, 972.3533849989677, 4122.196171746481, 4213.431186789744, 4247.978760106242, 4283.036316828751, 4387.605115539617, 14720.411183552538, 15042.721368683784, 15292.464763733216, 15326.846731532765, 15343.846561534241, 14643.12141271527, 15054.548363549111, 15230.771538426932, 15258.237088145548, 15400.537983860502, 10724.410157441416, 10727.731806704738, 10934.289271197347, 11010.559577616967, 11026.283291586022, 6469.77355792545, 6939.097917270722, 7089.574625522485, 7098.787036388917, 7195.46034047438, 10826.945865270623, 12537.811932820832, 12707.491700331882, 12902.032347573782, 13012.804807928078, 13661.79507307411, 13730.862691373359, 13798.103123296954, 13864.445422183, 13917.58247252584, 8425.95787021061, 9326.813463730552, 9498.240140788583, 9801.705948821547, 9806.166475849634, 11167.218294719292, 11192.272502287326, 11307.773844522899, 11309.943450283025, 11310.547578096784, 9093.22707569864, 9257.722268331692, 9280.860787088333, 9302.976672566148, 9323.533823308942, 9185.816283674423, 9191.540422978691, 9252.537373131181, 9256.166944974984, 9277.582508786985, 8219.013718353835, 8377.287930525974, 8389.790255243668, 8399.412041157304, 8404.663813447394, 6006.429389208193, 6039.63762174271, 6090.360512146338, 6168.136460895388, 6181.103739957771, 5206.271122042993, 6034.849128771816, 6136.20229370179, 6204.007358822567, 6236.781712640217], "value": 5405.628841067898, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.2936512292852, 401.51015760771884, 401.8633664554051, 411.56579808302246, 413.7517489506296, 5587.005291216779, 6255.468285195752, 6277.874442511147, 6285.905711414327, 6299.842503937404, 4772.761361931907, 5780.277465316835, 6021.2473440819895, 6040.335563088068, 6100.969495152529, 3499.597546282185, 4000.0, 4472.7539985300955, 4496.505384407713, 4502.122086193193, 3618.3898165316314, 3980.621840925122, 4357.825686972517, 4446.354419120634, 4448.28827387618, 3640.6541378568995, 4385.035292235703, 4394.47266328041, 4412.779361031951, 4419.977900110495, 4427.358033085201, 4428.490723566799, 4441.933370999438, 4448.243798554254, 4450.4436945381985, 3593.3711600469815, 3729.160938788778, 3780.206156707095, 3796.0889386547306, 3823.9426408603895, 5815.505682017034, 5860.296764388258, 5873.765049398019, 5885.970570147165, 5909.965755992684, 4056.6349028587274, 5276.419593844674, 5290.867728306785, 5304.522592966613, 5329.653572517774, 6244.687765611747, 6418.646974416405, 6480.805575832733, 6491.0, 6493.0, 4310.80601372939, 4312.78436078198, 4334.219840428722, 4343.090129613167, 4360.7819609019425, 5936.643801371932, 6638.966805165992, 6830.931690683129, 6870.793876183722, 6934.895976560356, 4385.6052955233545, 4409.5149533551285, 4415.2935530315, 4421.513633500313, 4433.556644335541, 4495.9325610115875, 4497.280435130363, 4511.436070491189, 4528.5245049270015, 4540.818367265339, 4078.1435898461064, 5232.372115346183, 5319.1223448131395, 5324.440933701983, 5331.920021199685, 6497.9675101625135, 6545.312742162099, 6557.540972132, 6576.901346479806, 6599.439047680905, 1402.6283034995733, 1407.8873690104972, 1422.9573112806424, 1528.7553991361551, 1540.6995635851126, 893.4817805672694, 960.9087136722001, 983.465793362357, 1004.4073996342194, 1032.6850310655323, 6155.984262596621, 6170.413810687977, 6217.569958909477, 6243.188385509855, 6302.558820882494, 5984.760609575568, 6435.292117867031, 6752.932470675329, 6783.626900520519, 6819.624920629413, 4660.4174478190225, 5962.314333851663, 6116.0, 6121.265448146251, 6182.629042257479, 2802.4395120975887, 3219.1791093271536, 3468.5317482140094, 3499.65003499648, 3552.5967242938896, 5396.761942834268, 5406.243125962368, 5418.020088951062, 5669.695969926857, 6983.615901125487, 6862.455947411792, 6953.513254072166, 6962.721491140399, 6973.721051157897, 7011.964940175369, 6597.921654678857, 6746.494012949049, 6822.3859852613505, 6941.201761797357, 6969.721211151497, 6799.150106236721, 6805.829854253683, 6814.420774234246, 6815.556988795713, 6820.181578210647, 6414.407931558342, 6618.635975021228, 6687.398134167948, 6689.46484281266, 6700.028495867974, 4718.740469274086, 6694.163229596301, 6937.410320122644, 6949.027136200835, 6961.965190174017, 3533.5583052118486, 4282.293421585404, 4357.542458041987, 4443.155800397914, 4453.821847126208, 6764.0, 6795.830104247433, 6796.898046529207, 6804.76183333581, 6831.419329357061, 5463.7979644478155, 6563.704633291418, 6568.438309059567, 6568.770093046721, 6582.637954912334, 5512.310961129859, 6243.0, 6550.901736474052, 6556.868862622626, 6561.639109848813, 6359.950608149788, 6425.646589437439, 6461.61230326182, 6465.77369792055, 6505.31694172124, 6000.609960352737, 6394.911190097648, 6471.7734879279, 6488.221413430419, 6563.737450501926, 5141.38303403594, 6144.508439324933, 6170.012797952306, 6355.80724649995, 6420.2937676856445], "value": 4701.278525023798, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.5701050864227, 834.3575446905886, 850.2730165708319, 877.1404024056425, 885.3846576629242, 16323.755143672839, 16743.0, 16788.664226715457, 18531.629367412643, 19253.51866203342, 17927.0, 17966.0, 18126.3655772048, 18221.7266740999, 18554.257829686798, 974.8050389922026, 985.7979114281555, 996.7308826616809, 1001.4792307999836, 1001.4942454060725, 848.4145939301893, 988.1748739802274, 1011.9747006324827, 1012.1447376966452, 1026.6150193677372, 949.8480243161096, 1055.1189756553288, 1067.8665166854144, 1078.245228340161, 1080.346390433788, 1043.4626167523732, 1061.8354155105972, 1062.4315990944885, 1067.9946600266987, 1070.3738313086853, 797.5693125712108, 798.9800254993613, 800.427694198645, 804.9154838741965, 809.5021561739546, 16432.917835410866, 16715.0, 16725.498235052968, 16885.24016419264, 17088.57278568033, 16756.581085472837, 16936.153192340334, 17235.3967611133, 17377.47867563975, 17429.47711568655, 13939.721205575934, 14013.369398377097, 14129.0, 14334.569962901129, 14463.927680361636, 3127.5152351385573, 3598.298331825295, 3618.8371523281517, 3622.6739593436455, 3624.039629498185, 104777.47611262047, 108236.4588177062, 108958.0, 109237.0, 110196.9999999992, 13199.802002969964, 13453.26007069601, 13566.253856038013, 13566.660833479242, 13571.796423053465, 23683.407914801923, 24234.515309693707, 25121.62317565238, 25158.0, 25554.361140971254, 8849.60176792046, 9064.818703625891, 9152.771180720401, 9157.725268241964, 9170.266378689688, 4791.520847915181, 4796.016816552598, 4815.518448155157, 4917.139500587383, 4926.630502712311, 4307.8492252771, 4381.16757816014, 4400.229959757094, 4436.733795972252, 4442.400275962768, 958.7153214692346, 961.7018724195522, 967.617790972576, 971.6647756523988, 972.5915115651302, 655.7442597387023, 674.4907594765921, 679.6839469646638, 684.5618803965466, 691.2120182991393, 11159.497822598003, 11664.300141991338, 11796.823047654292, 11856.288622682667, 12215.511379544718, 639.8720255948829, 667.5694177255643, 689.3313485918751, 690.9550879192936, 696.7352406085655, 5071.340725705634, 5409.134538473825, 5495.395506494282, 5542.11326187808, 5617.354004289477, 86014.70977935336, 89323.10676893148, 89933.0, 90443.0, 91024.0, 85718.0, 87782.56108719416, 88127.0, 89372.5531372352, 89681.0, 61621.0, 75886.0, 76067.23932760712, 77953.66139015839, 78563.42873142505, 9569.760755981157, 10440.843387349349, 10772.730681733019, 11090.44547772592, 11195.04842088399, 36003.63996360003, 36123.63876361308, 36214.0, 36290.4556431659, 36635.0, 43473.56526434695, 46348.30477542907, 47688.0, 52838.0, 60010.39989600223, 13579.185248885098, 16213.675726485168, 16747.33010679608, 17134.314627414762, 17364.739528906823, 18309.359172429435, 18334.174962126468, 18934.053297335355, 19015.42953711391, 19090.045497725336, 17036.0, 17279.481615551555, 17363.7395439066, 17514.82485175132, 17618.559536011704, 16015.91992040079, 17182.742258866376, 17283.308667653153, 17351.82648173502, 17590.29638814484, 16269.59326016859, 16271.918640406722, 16488.34046638169, 16527.421540246032, 16704.331826726793, 11406.429678515875, 11573.305601663928, 11806.940965295118, 11915.0, 12035.277883327028, 10157.39055656663, 12735.363231838186, 13293.335333233106, 13411.597652070453, 13791.517296894963], "value": 8364.691881974702, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1553.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 173.0, 128.0, 115.0, 117.0, 116.0, 117.0, 118.0, 118.0, 116.0], "value": 122.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 133.0, 188.0, 146.0, 114.0, 115.0, 111.0, 135.0, 130.0], "value": 132.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 201.0, 214.0, 223.0, 223.0, 225.0, 224.0, 203.0, 201.0, 195.0], "value": 210.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 59.0, 58.0, 89.0, 59.0, 58.0, 66.0, 64.0, 76.0, 59.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 83.0, 70.0, 75.0, 72.0, 85.0, 73.0, 98.0, 118.0], "value": 82.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 138.0, 89.0, 90.0, 89.0, 88.0, 89.0, 88.0, 89.0, 90.0], "value": 97.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 121.0, 154.0, 98.0, 96.0, 96.0, 137.0, 98.0, 117.0, 101.0], "value": 111.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 93.0, 90.0, 91.0, 89.0, 91.0, 94.0, 92.0, 90.0, 91.0], "value": 92.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 44.0, 73.0, 80.0, 60.0, 54.0, 53.0, 51.0, 53.0, 53.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 63.0, 58.0, 77.0, 91.0, 97.0, 74.0, 57.0, 58.0, 59.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 83.0, 83.0, 84.0, 84.0, 84.0, 87.0, 85.0, 84.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 142.0, 141.0, 137.0, 140.0, 140.0, 144.0, 198.0, 151.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 192.0, 185.0, 187.0, 185.0, 186.0, 189.0, 184.0, 188.0, 188.0], "value": 187.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 103.0, 104.0, 102.0, 97.0, 86.0, 72.0, 99.0, 97.0, 103.0], "value": 92.6, "unit": "score"}]}]} 12:22:25 INFO - Return code: 0 12:22:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:22:25 ERROR - # TBPL SUCCESS # 12:22:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:22:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:22:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:22:25 INFO - 2017-04-13 12:22:25.709 screenresolution[1654:10486] starting screenresolution argv=screenresolution get 12:22:25 INFO - 2017-04-13 12:22:25.712 screenresolution[1654:10486] Display 0: 1600x1200x32@60 12:22:25 INFO - 2017-04-13 12:22:25.718 screenresolution[1655:10489] starting screenresolution argv=screenresolution list 12:22:25 INFO - Available Modes on Display 0 12:22:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:22:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:22:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:22:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:22:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:22:25 INFO - Intel Iris: 12:22:25 INFO - Chipset Model: Intel Iris 12:22:25 INFO - Type: GPU 12:22:25 INFO - Bus: Built-In 12:22:25 INFO - VRAM (Dynamic, Max): 1536 MB 12:22:25 INFO - Vendor: Intel (0x8086) 12:22:25 INFO - Device ID: 0x0a2e 12:22:25 INFO - Revision ID: 0x0009 12:22:25 INFO - Displays: 12:22:25 INFO - Display: 12:22:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:22:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:22:25 INFO - Display Serial Number: 1600x1200 60 12:22:25 INFO - Main Display: Yes 12:22:25 INFO - Mirror: Off 12:22:25 INFO - Online: Yes 12:22:25 INFO - Rotation: Supported 12:22:25 INFO - Return code: 0 12:22:25 INFO - Running post-action listener: _resource_record_post_action 12:22:25 INFO - [mozharness: 2017-04-13 19:22:25.962811Z] Finished run-tests step (success) 12:22:25 INFO - Running post-run listener: _resource_record_post_run 12:22:26 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 12846592; Write bytes: 517939200; Read time: 133; Write time: 2031 12:22:26 INFO - TinderboxPrint: CPU usage
54.2% 12:22:26 INFO - TinderboxPrint: I/O read bytes / time
12,846,592 / 133 12:22:26 INFO - TinderboxPrint: I/O write bytes / time
517,939,200 / 2,031 12:22:26 INFO - TinderboxPrint: CPU idle
2,193.8 (45.8%) 12:22:26 INFO - TinderboxPrint: CPU system
960.9 (20.1%) 12:22:26 INFO - TinderboxPrint: CPU user
1,635.0 (34.1%) 12:22:26 INFO - TinderboxPrint: Swap in / out
247,869,440 / 0 12:22:26 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 193552896; Write bytes: 194484224; Read time: 15054; Write time: 401 12:22:26 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 11867648; Write bytes: 306616320; Read time: 113; Write time: 1578 12:22:26 INFO - Running post-run listener: _upload_blobber_files 12:22:26 INFO - Blob upload gear active. 12:22:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:22:26 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 12:22:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:22:26 INFO - (blobuploader) - INFO - Open directory for files ... 12:22:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:22:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:22:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:27 INFO - (blobuploader) - INFO - Done attempting. 12:22:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:22:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:22:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:29 INFO - (blobuploader) - INFO - Done attempting. 12:22:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:22:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:22:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:29 INFO - (blobuploader) - INFO - Done attempting. 12:22:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:22:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:22:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:22:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:22:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:22:32 INFO - (blobuploader) - INFO - Done attempting. 12:22:32 INFO - (blobuploader) - INFO - Iteration through files over. 12:22:32 INFO - Return code: 0 12:22:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:22:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:22:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44012c6807c225c472dd4ad1d36c9cc24cdf6f691096e4f90ec1b8feb859d427f09c8be28c453f123ee41afb9fddd5d6268420d85b2894612a8d553d43e49dbb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca78cfced8d123d3e9ea160a0809c832cc108e9d60e5d5c7fd66773d3b3bf0fdeeff806a325c7a4e0dab86350e5ce0bf46a0f94cf2b7932a7612a7604987fdae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/637ec441d1c5d91410f8a114697ec7cac212a768751cdb1710a4f4281ac7d446e647a7515b8b56352818aff2a9120a43e73a6b350175b231ed702926d77ddbbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5d7bb846be23ffa46117779751deb8dc893204bbcb0598c36c352d4bdc9dcca55216d1399907659e8645f1cddc3e15a7560e7586bb7d5cd7ad516620dd054fc"} 12:22:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:22:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:22:32 INFO - Contents: 12:22:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44012c6807c225c472dd4ad1d36c9cc24cdf6f691096e4f90ec1b8feb859d427f09c8be28c453f123ee41afb9fddd5d6268420d85b2894612a8d553d43e49dbb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca78cfced8d123d3e9ea160a0809c832cc108e9d60e5d5c7fd66773d3b3bf0fdeeff806a325c7a4e0dab86350e5ce0bf46a0f94cf2b7932a7612a7604987fdae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/637ec441d1c5d91410f8a114697ec7cac212a768751cdb1710a4f4281ac7d446e647a7515b8b56352818aff2a9120a43e73a6b350175b231ed702926d77ddbbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5d7bb846be23ffa46117779751deb8dc893204bbcb0598c36c352d4bdc9dcca55216d1399907659e8645f1cddc3e15a7560e7586bb7d5cd7ad516620dd054fc"} 12:22:32 INFO - Running post-run listener: copy_logs_to_upload_dir 12:22:32 INFO - Copying logs to upload dir... 12:22:32 INFO - mkdir: /builds/slave/test/build/upload/logs 12:22:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.506685 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-04-13 12:22:32.185894) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 12:22:32.189537) ========= 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.XfSlDEihSK/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.htlV4eeGK2/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44012c6807c225c472dd4ad1d36c9cc24cdf6f691096e4f90ec1b8feb859d427f09c8be28c453f123ee41afb9fddd5d6268420d85b2894612a8d553d43e49dbb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca78cfced8d123d3e9ea160a0809c832cc108e9d60e5d5c7fd66773d3b3bf0fdeeff806a325c7a4e0dab86350e5ce0bf46a0f94cf2b7932a7612a7604987fdae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/637ec441d1c5d91410f8a114697ec7cac212a768751cdb1710a4f4281ac7d446e647a7515b8b56352818aff2a9120a43e73a6b350175b231ed702926d77ddbbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5d7bb846be23ffa46117779751deb8dc893204bbcb0598c36c352d4bdc9dcca55216d1399907659e8645f1cddc3e15a7560e7586bb7d5cd7ad516620dd054fc"} build_url:https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010168 build_url: 'https://queue.taskcluster.net/v1/task/dkMD1bi6QBKWLzJcwoxCyQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44012c6807c225c472dd4ad1d36c9cc24cdf6f691096e4f90ec1b8feb859d427f09c8be28c453f123ee41afb9fddd5d6268420d85b2894612a8d553d43e49dbb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca78cfced8d123d3e9ea160a0809c832cc108e9d60e5d5c7fd66773d3b3bf0fdeeff806a325c7a4e0dab86350e5ce0bf46a0f94cf2b7932a7612a7604987fdae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/637ec441d1c5d91410f8a114697ec7cac212a768751cdb1710a4f4281ac7d446e647a7515b8b56352818aff2a9120a43e73a6b350175b231ed702926d77ddbbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5d7bb846be23ffa46117779751deb8dc893204bbcb0598c36c352d4bdc9dcca55216d1399907659e8645f1cddc3e15a7560e7586bb7d5cd7ad516620dd054fc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 12:22:32.221438) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 12:22:32.221777) ========= 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.XfSlDEihSK/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.htlV4eeGK2/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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.004727 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 12:22:32.270037) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 12:22:32.270374) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 12:22:32.270694) ========= ========= Total master_lag: 0.18 =========