builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0116 starttime: 1493356987.08 results: success (0) buildid: 20170427134925 builduid: 288e00ddd8874f33b387f318f46c71f9 revision: 2acda68875ee036cf4d26685da4356dcdf308a87 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-27 22:23:07.084432) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-27 22:23:07.084874) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-27 22:23:07.085154) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-27 22:23:07.111103) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 22:23:07.111479) ========= 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.l2F1H5DzU1/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.ARc2RGdXtM/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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-27 22:23:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.39M=0.001s 2017-04-27 22:23:07 (8.39 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153173 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 22:23:07.289414) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 22:23:07.289741) ========= 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.l2F1H5DzU1/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.ARc2RGdXtM/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.022797 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 22:23:07.338467) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-27 22:23:07.338844) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 2acda68875ee036cf4d26685da4356dcdf308a87 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 2acda68875ee036cf4d26685da4356dcdf308a87 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.l2F1H5DzU1/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.ARc2RGdXtM/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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-27 22:23:07,421 truncating revision to first 12 chars 2017-04-27 22:23:07,421 Setting DEBUG logging. 2017-04-27 22:23:07,421 attempt 1/10 2017-04-27 22:23:07,421 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/2acda68875ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-27 22:23:09,011 unpacking tar archive at: pine-2acda68875ee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.849007 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-27 22:23:09.214496) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-27 22:23:09.214827) ========= script_repo_revision: 2acda68875ee036cf4d26685da4356dcdf308a87 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-27 22:23:09.215242) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-27 22:23:09.215550) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-27 22:23:09.238639) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-04-27 22:23:09.238965) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.l2F1H5DzU1/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.ARc2RGdXtM/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 22:23:09 INFO - MultiFileLogger online at 20170427 22:23:09 in /builds/slave/test 22:23:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 22:23:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:23:09 INFO - {'append_to_log': False, 22:23:09 INFO - 'base_work_dir': '/builds/slave/test', 22:23:09 INFO - 'blob_upload_branch': 'Pine', 22:23:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:23:09 INFO - 'branch': 'Pine', 22:23:09 INFO - 'buildbot_json_path': 'buildprops.json', 22:23:09 INFO - 'config_files': ('talos/mac_config.py',), 22:23:09 INFO - 'default_actions': ('clobber', 22:23:09 INFO - 'read-buildbot-config', 22:23:09 INFO - 'download-and-extract', 22:23:09 INFO - 'populate-webroot', 22:23:09 INFO - 'create-virtualenv', 22:23:09 INFO - 'install', 22:23:09 INFO - 'run-tests'), 22:23:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:23:09 INFO - 'download_minidump_stackwalk': True, 22:23:09 INFO - 'download_symbols': 'ondemand', 22:23:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:23:09 INFO - 'tooltool.py': '/tools/tooltool.py', 22:23:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:23:09 INFO - '/tools/misc-python/virtualenv.py')}, 22:23:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:23:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:23:09 INFO - 'gecko_profile': False, 22:23:09 INFO - 'gecko_profile_interval': 0, 22:23:09 INFO - 'installer_path': 'installer.exe', 22:23:09 INFO - 'log_level': 'info', 22:23:09 INFO - 'log_name': 'talos', 22:23:09 INFO - 'log_to_console': True, 22:23:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:23:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:23:09 INFO - 'opt_config_files': (), 22:23:09 INFO - 'pip_index': False, 22:23:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:23:09 INFO - 'cmd': ('bash', 22:23:09 INFO - '-c', 22:23:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:23:09 INFO - 'enabled': True, 22:23:09 INFO - 'halt_on_failure': False, 22:23:09 INFO - 'name': 'check_screen_resolution'},), 22:23:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:23:09 INFO - 'cmd': ('bash', 22:23:09 INFO - '-c', 22:23:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:23:09 INFO - 'enabled': True, 22:23:09 INFO - 'halt_on_failure': False, 22:23:09 INFO - 'name': 'check_screen_resolution'},), 22:23:09 INFO - 'run_cmd_checks_enabled': True, 22:23:09 INFO - 'suite': 'dromaeojs-e10s', 22:23:09 INFO - 'system_bits': '32', 22:23:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:23:09 INFO - 'title': 't-yosemite-r7-0116', 22:23:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:23:09 INFO - 'use_talos_json': True, 22:23:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:23:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:23:09 INFO - 'work_dir': 'build'} 22:23:09 INFO - [mozharness: 2017-04-28 05:23:09.447574Z] Running clobber step. 22:23:09 INFO - Running pre-action listener: _resource_record_pre_action 22:23:09 INFO - Running main action method: clobber 22:23:09 INFO - rmtree: /builds/slave/test/build 22:23:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:23:10 INFO - Running post-action listener: _resource_record_post_action 22:23:10 INFO - [mozharness: 2017-04-28 05:23:10.153103Z] Finished clobber step (success) 22:23:10 INFO - [mozharness: 2017-04-28 05:23:10.153381Z] Running read-buildbot-config step. 22:23:10 INFO - Running pre-action listener: _resource_record_pre_action 22:23:10 INFO - Running main action method: read_buildbot_config 22:23:10 INFO - Using buildbot properties: 22:23:10 INFO - { 22:23:10 INFO - "project": "", 22:23:10 INFO - "product": "firefox", 22:23:10 INFO - "script_repo_revision": "default", 22:23:10 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 22:23:10 INFO - "repository": "", 22:23:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 22:23:10 INFO - "buildid": "20170427134925", 22:23:10 INFO - "pgo_build": "False", 22:23:10 INFO - "basedir": "/builds/slave/test", 22:23:10 INFO - "buildnumber": 4, 22:23:10 INFO - "slavename": "t-yosemite-r7-0116", 22:23:10 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 22:23:10 INFO - "platform": "yosemite_r7", 22:23:10 INFO - "scheduler": "tests-pine-macosx64-talos", 22:23:10 INFO - "branch": "pine", 22:23:10 INFO - "repo_path": "projects/pine", 22:23:10 INFO - "revision": "2acda68875ee036cf4d26685da4356dcdf308a87", 22:23:10 INFO - "stage_platform": "macosx64", 22:23:10 INFO - "builduid": "288e00ddd8874f33b387f318f46c71f9", 22:23:10 INFO - "slavebuilddir": "test" 22:23:10 INFO - } 22:23:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 22:23:10 INFO - Running post-action listener: _resource_record_post_action 22:23:10 INFO - [mozharness: 2017-04-28 05:23:10.159125Z] Finished read-buildbot-config step (success) 22:23:10 INFO - [mozharness: 2017-04-28 05:23:10.159352Z] Running download-and-extract step. 22:23:10 INFO - Running pre-action listener: _resource_record_pre_action 22:23:10 INFO - Running main action method: download_and_extract 22:23:10 INFO - mkdir: /builds/slave/test/build/tests 22:23:10 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')]} 22:23:10 INFO - trying https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 22:23:10 INFO - Downloading https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/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 22:23:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/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 22:23:11 INFO - Downloaded 1325 bytes. 22:23:11 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:23:11 INFO - Using the following test package requirements: 22:23:11 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:23:11 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 22:23:11 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:23:11 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:23:11 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 22:23:11 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:23:11 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 22:23:11 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:23:11 INFO - u'jsshell-mac.zip'], 22:23:11 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:23:11 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 22:23:11 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:23:11 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:23:11 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 22:23:11 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:23:11 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 22:23:11 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:23:11 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 22:23:11 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:23:11 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 22:23:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:23:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:23:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:23:11 INFO - Fetch https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:23:12 INFO - Content-Length response header: 52694981 22:23:12 INFO - Bytes received: 52694981 22:23:29 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 22:23:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:23:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:23:29 INFO - Fetch https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:23:31 INFO - Content-Length response header: 52694981 22:23:31 INFO - Bytes received: 52694981 22:23:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 22:23:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:23:47 INFO - Fetch https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 22:23:48 INFO - Content-Length response header: 14408823 22:23:48 INFO - Bytes received: 14408823 22:23: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')]} 22:23:48 INFO - trying https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:23:48 INFO - Downloading https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 22:23:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/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 22:23:50 INFO - Downloaded 71210505 bytes. 22:23:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:23:50 INFO - mkdir: /builds/slave/test/properties 22:23:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:23:50 INFO - Writing to file /builds/slave/test/properties/build_url 22:23:50 INFO - Contents: 22:23:50 INFO - build_url:https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:23:51 INFO - Running post-action listener: _resource_record_post_action 22:23:51 INFO - Running post-action listener: set_extra_try_arguments 22:23:51 INFO - [mozharness: 2017-04-28 05:23:51.453461Z] Finished download-and-extract step (success) 22:23:51 INFO - [mozharness: 2017-04-28 05:23:51.453606Z] Running populate-webroot step. 22:23:51 INFO - Running pre-action listener: _resource_record_pre_action 22:23:51 INFO - Running main action method: populate_webroot 22:23:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:23:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:23:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:23:51 INFO - '--tppagecycles', 22:23:51 INFO - '7'], 22:23:51 INFO - 'tests': ['tsvgm']}}, 22:23:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:23:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:23:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:23:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:23:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:23:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:23:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:23:51 INFO - 'talos_options': ['--disable-e10s'], 22:23:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:23:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:23:51 INFO - 'talos_options': ['--disable-e10s'], 22:23:51 INFO - 'tests': ['damp', 'tps']}, 22:23:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:23:51 INFO - 'tests': ['damp', 'tps']}, 22:23:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:23:51 INFO - 'tests': ['dromaeo_dom']}, 22:23:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:23:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:23:51 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 22:23:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:23:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:23:51 INFO - 'tests': ['a11yr', 22:23:51 INFO - 'ts_paint', 22:23:51 INFO - 'tpaint', 22:23:51 INFO - 'sessionrestore', 22:23:51 INFO - 'sessionrestore_no_auto_restore', 22:23:51 INFO - 'tabpaint']}, 22:23:51 INFO - 'other-e10s': {'tests': ['a11yr', 22:23:51 INFO - 'ts_paint', 22:23:51 INFO - 'tpaint', 22:23:51 INFO - 'sessionrestore', 22:23:51 INFO - 'sessionrestore_no_auto_restore', 22:23:51 INFO - 'tabpaint']}, 22:23:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:23:51 INFO - 'ts_paint', 22:23:51 INFO - 'tpaint', 22:23:51 INFO - 'sessionrestore', 22:23:51 INFO - 'sessionrestore_no_auto_restore', 22:23:51 INFO - 'tabpaint']}, 22:23:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:23:51 INFO - 'ts_paint', 22:23:51 INFO - 'tpaint', 22:23:51 INFO - 'sessionrestore', 22:23:51 INFO - 'sessionrestore_no_auto_restore', 22:23:51 INFO - 'tabpaint']}, 22:23:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:23:51 INFO - 'tests': ['a11yr', 22:23:51 INFO - 'ts_paint', 22:23:51 INFO - 'tpaint', 22:23:51 INFO - 'sessionrestore', 22:23:51 INFO - 'sessionrestore_no_auto_restore', 22:23:51 INFO - 'tabpaint']}, 22:23:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:23:51 INFO - 'tests': ['a11yr', 22:23:51 INFO - 'ts_paint', 22:23:51 INFO - 'tpaint', 22:23:51 INFO - 'sessionrestore', 22:23:51 INFO - 'sessionrestore_no_auto_restore', 22:23:51 INFO - 'tabpaint']}, 22:23:51 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 22:23:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 22:23:51 INFO - 'bloom_basic_ref']}, 22:23:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:23:51 INFO - 'tests': ['tsvgx', 22:23:51 INFO - 'tsvgr_opacity', 22:23:51 INFO - 'tart', 22:23:51 INFO - 'tscrollx', 22:23:51 INFO - 'cart', 22:23:51 INFO - 'tsvg_static']}, 22:23:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:23:51 INFO - 'tsvgr_opacity', 22:23:51 INFO - 'tart', 22:23:51 INFO - 'tscrollx', 22:23:51 INFO - 'cart', 22:23:51 INFO - 'tsvg_static']}, 22:23:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:23:51 INFO - 'talos_options': ['--disable-e10s'], 22:23:51 INFO - 'tests': ['tp5o']}, 22:23:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:23:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:23:51 INFO - 'talos_options': ['--disable-e10s', 22:23:51 INFO - '--xperf_path', 22:23:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:51 INFO - 'tests': ['tp5n']}, 22:23:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:23:51 INFO - 'talos_options': ['--xperf_path', 22:23:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:51 INFO - 'tests': ['tp5n']}}, 22:23:51 INFO - 'talos.zip': {'path': '', 22:23:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:23:51 INFO - Running post-action listener: _resource_record_post_action 22:23:51 INFO - [mozharness: 2017-04-28 05:23:51.472689Z] Finished populate-webroot step (success) 22:23:51 INFO - [mozharness: 2017-04-28 05:23:51.472822Z] Running create-virtualenv step. 22:23:51 INFO - Running pre-action listener: _resource_record_pre_action 22:23:51 INFO - Running main action method: create_virtualenv 22:23:51 INFO - Creating virtualenv /builds/slave/test/build/venv 22:23:51 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 22:23:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:23:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:23:51 INFO - Using real prefix '/tools/python27' 22:23:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:23:52 INFO - Installing distribute.............................................................................................................................................................................................done. 22:23:55 INFO - Installing pip.................done. 22:23:55 INFO - Return code: 0 22:23:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:23:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:23:55 INFO - Reading from file tmpfile_stdout 22:23:55 INFO - Output received: 22:23:55 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) 22:23:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:55 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 0x1069f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede2d99980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d6d30>, '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 0x1066fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067cbbe8>, '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.l2F1H5DzU1/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.ARc2RGdXtM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 22:23:55 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 22:23:55 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 22:23:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l2F1H5DzU1/Render', 22:23:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:55 INFO - 'HOME': '/Users/cltbld', 22:23:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:55 INFO - 'LOGNAME': 'cltbld', 22:23:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:55 INFO - 'MOZ_NO_REMOTE': '1', 22:23:55 INFO - 'NO_EM_RESTART': '1', 22:23:55 INFO - 'PAGER': '/bin/cat', 22:23:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:55 INFO - 'PWD': '/builds/slave/test', 22:23:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:55 INFO - 'SHELL': '/bin/bash', 22:23:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARc2RGdXtM/Listeners', 22:23:55 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 22:23:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:55 INFO - 'USER': 'cltbld', 22:23:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:55 INFO - 'XPC_FLAGS': '0x0', 22:23:55 INFO - 'XPC_SERVICE_NAME': '0', 22:23:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:55 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 22:23:55 INFO - Cleaning up... 22:23:55 INFO - Return code: 0 22:23:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:55 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 0x1069f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede2d99980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d6d30>, '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 0x1066fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067cbbe8>, '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.l2F1H5DzU1/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.ARc2RGdXtM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 22:23:55 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 22:23:55 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 22:23:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l2F1H5DzU1/Render', 22:23:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:55 INFO - 'HOME': '/Users/cltbld', 22:23:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:55 INFO - 'LOGNAME': 'cltbld', 22:23:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:55 INFO - 'MOZ_NO_REMOTE': '1', 22:23:55 INFO - 'NO_EM_RESTART': '1', 22:23:55 INFO - 'PAGER': '/bin/cat', 22:23:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:55 INFO - 'PWD': '/builds/slave/test', 22:23:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:55 INFO - 'SHELL': '/bin/bash', 22:23:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARc2RGdXtM/Listeners', 22:23:55 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 22:23:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:55 INFO - 'USER': 'cltbld', 22:23:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:55 INFO - 'XPC_FLAGS': '0x0', 22:23:55 INFO - 'XPC_SERVICE_NAME': '0', 22:23:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:56 INFO - Downloading/unpacking psutil>=3.1.1 22:23:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:23:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:23:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:23:59 INFO - Installing collected packages: psutil 22:23:59 INFO - Running setup.py install for psutil 22:23:59 INFO - building 'psutil._psutil_osx' extension 22:23:59 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 22:24:00 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 22:24:00 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 22:24:00 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 22:24:00 INFO - building 'psutil._psutil_posix' extension 22:24:00 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 22:24:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:24:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:24:00 INFO - ^ 22:24:00 INFO - 1 warning generated. 22:24:00 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 22:24:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:24:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:24:00 INFO - Successfully installed psutil 22:24:00 INFO - Cleaning up... 22:24:00 INFO - Return code: 0 22:24:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:24:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1069f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede2d99980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d6d30>, '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 0x1066fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067cbbe8>, '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.l2F1H5DzU1/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.ARc2RGdXtM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 22:24:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:24:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:24:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l2F1H5DzU1/Render', 22:24:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:00 INFO - 'HOME': '/Users/cltbld', 22:24:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:00 INFO - 'LOGNAME': 'cltbld', 22:24:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:00 INFO - 'MOZ_NO_REMOTE': '1', 22:24:00 INFO - 'NO_EM_RESTART': '1', 22:24:00 INFO - 'PAGER': '/bin/cat', 22:24:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:00 INFO - 'PWD': '/builds/slave/test', 22:24:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:00 INFO - 'SHELL': '/bin/bash', 22:24:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARc2RGdXtM/Listeners', 22:24:00 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 22:24:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:00 INFO - 'USER': 'cltbld', 22:24:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:00 INFO - 'XPC_FLAGS': '0x0', 22:24:00 INFO - 'XPC_SERVICE_NAME': '0', 22:24:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:24:00 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 22:24:00 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 22:24:00 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 22:24:00 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 22:24:00 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 22:24:00 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 22:24:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:24:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:24:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:24:03 INFO - Installing collected packages: mozsystemmonitor 22:24:03 INFO - Running setup.py install for mozsystemmonitor 22:24:03 INFO - Successfully installed mozsystemmonitor 22:24:03 INFO - Cleaning up... 22:24:03 INFO - Return code: 0 22:24:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:24:03 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')]} 22:24:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24: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')]} 22:24:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24: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', '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 0x1069f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede2d99980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d6d30>, '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 0x1066fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067cbbe8>, '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.l2F1H5DzU1/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.ARc2RGdXtM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 22:24: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:24: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 jsonschema==2.5.1 22:24:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l2F1H5DzU1/Render', 22:24:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:04 INFO - 'HOME': '/Users/cltbld', 22:24:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:04 INFO - 'LOGNAME': 'cltbld', 22:24:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:04 INFO - 'MOZ_NO_REMOTE': '1', 22:24:04 INFO - 'NO_EM_RESTART': '1', 22:24:04 INFO - 'PAGER': '/bin/cat', 22:24:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:04 INFO - 'PWD': '/builds/slave/test', 22:24:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:04 INFO - 'SHELL': '/bin/bash', 22:24:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARc2RGdXtM/Listeners', 22:24:04 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 22:24:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:04 INFO - 'USER': 'cltbld', 22:24:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:04 INFO - 'XPC_FLAGS': '0x0', 22:24:04 INFO - 'XPC_SERVICE_NAME': '0', 22:24:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:04 INFO - Downloading/unpacking jsonschema==2.5.1 22:24: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 22:24: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 22:24: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 22:24: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 22:24: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 22:24: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 22:24:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:24:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:24:07 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 22:24:07 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 22:24:07 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 22:24:07 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 22:24:07 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 22:24:07 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 22:24:07 INFO - Downloading functools32-3.2.3-2.tar.gz 22:24:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:24:07 INFO - warning: no files found matching '*.txt' 22:24:07 INFO - no previously-included directories found matching 'build' 22:24:07 INFO - no previously-included directories found matching 'dist' 22:24:07 INFO - no previously-included directories found matching '.git*' 22:24:08 INFO - Installing collected packages: jsonschema, functools32 22:24:08 INFO - Running setup.py install for functools32 22:24:08 INFO - warning: no files found matching '*.txt' 22:24:08 INFO - no previously-included directories found matching 'build' 22:24:08 INFO - no previously-included directories found matching 'dist' 22:24:08 INFO - no previously-included directories found matching '.git*' 22:24:08 INFO - Successfully installed jsonschema functools32 22:24:08 INFO - Cleaning up... 22:24:08 INFO - Return code: 0 22:24:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:24: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')]} 22:24:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24: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')]} 22:24:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24: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', '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 0x1069f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede2d99980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d6d30>, '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 0x1066fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067cbbe8>, '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.l2F1H5DzU1/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.ARc2RGdXtM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 22:24: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:24: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 functools32==3.2.3-2 22:24:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l2F1H5DzU1/Render', 22:24:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:08 INFO - 'HOME': '/Users/cltbld', 22:24:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:08 INFO - 'LOGNAME': 'cltbld', 22:24:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:08 INFO - 'MOZ_NO_REMOTE': '1', 22:24:08 INFO - 'NO_EM_RESTART': '1', 22:24:08 INFO - 'PAGER': '/bin/cat', 22:24:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:08 INFO - 'PWD': '/builds/slave/test', 22:24:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:08 INFO - 'SHELL': '/bin/bash', 22:24:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARc2RGdXtM/Listeners', 22:24:08 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 22:24:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:08 INFO - 'USER': 'cltbld', 22:24:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:08 INFO - 'XPC_FLAGS': '0x0', 22:24:08 INFO - 'XPC_SERVICE_NAME': '0', 22:24:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:24:08 INFO - Cleaning up... 22:24:08 INFO - Return code: 0 22:24:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:24: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')]} 22:24:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24: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')]} 22:24:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24: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', '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 0x1069f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede2d99980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d6d30>, '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 0x1066fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067cbbe8>, '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.l2F1H5DzU1/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.ARc2RGdXtM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 22:24: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:24: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 blobuploader==1.2.4 22:24:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l2F1H5DzU1/Render', 22:24:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:08 INFO - 'HOME': '/Users/cltbld', 22:24:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:08 INFO - 'LOGNAME': 'cltbld', 22:24:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:08 INFO - 'MOZ_NO_REMOTE': '1', 22:24:08 INFO - 'NO_EM_RESTART': '1', 22:24:08 INFO - 'PAGER': '/bin/cat', 22:24:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:08 INFO - 'PWD': '/builds/slave/test', 22:24:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:08 INFO - 'SHELL': '/bin/bash', 22:24:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARc2RGdXtM/Listeners', 22:24:08 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 22:24:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:08 INFO - 'USER': 'cltbld', 22:24:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:08 INFO - 'XPC_FLAGS': '0x0', 22:24:08 INFO - 'XPC_SERVICE_NAME': '0', 22:24:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:08 INFO - Downloading/unpacking blobuploader==1.2.4 22:24: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 22:24: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 22:24: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 22:24: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 22:24: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 22:24: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 22:24:11 INFO - Downloading blobuploader-1.2.4.tar.gz 22:24:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:24:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:24: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 22:24: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 22:24: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 22:24: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 22:24: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 22:24: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 22:24:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:24:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:24: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 22:24: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 22:24: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 22:24: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 22:24: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 22:24: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 22:24:12 INFO - Downloading docopt-0.6.1.tar.gz 22:24:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:24:12 INFO - Installing collected packages: blobuploader, requests, docopt 22:24:12 INFO - Running setup.py install for blobuploader 22:24:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:24:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:24:12 INFO - Running setup.py install for requests 22:24:13 INFO - Running setup.py install for docopt 22:24:13 INFO - Successfully installed blobuploader requests docopt 22:24:13 INFO - Cleaning up... 22:24:13 INFO - Return code: 0 22:24:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:13 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')]} 22:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:13 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')]} 22:24:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:13 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 0x1069f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede2d99980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d6d30>, '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 0x1066fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067cbbe8>, '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.l2F1H5DzU1/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.ARc2RGdXtM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 22:24:13 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 22:24:13 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 22:24:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l2F1H5DzU1/Render', 22:24:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:13 INFO - 'HOME': '/Users/cltbld', 22:24:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:13 INFO - 'LOGNAME': 'cltbld', 22:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:13 INFO - 'MOZ_NO_REMOTE': '1', 22:24:13 INFO - 'NO_EM_RESTART': '1', 22:24:13 INFO - 'PAGER': '/bin/cat', 22:24:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:13 INFO - 'PWD': '/builds/slave/test', 22:24:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:13 INFO - 'SHELL': '/bin/bash', 22:24:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARc2RGdXtM/Listeners', 22:24:13 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 22:24:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:13 INFO - 'USER': 'cltbld', 22:24:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:13 INFO - 'XPC_FLAGS': '0x0', 22:24:13 INFO - 'XPC_SERVICE_NAME': '0', 22:24:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:24:13 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ximskE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:24:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-TKoAgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:24:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-PCbvkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:24:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-_OUVKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:24:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-Oio9xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:24:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-nMUmmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:24:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-8mDLKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:24:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-vGqQ3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:24:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-XqJfnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:24:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-dLUC88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:24:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-5srG51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:24:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-BemhKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:24:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-X_u7Dj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:24:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-sioI6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-sY15nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:24:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-nP1SEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:24:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-f9e5PK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:24:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:24:15 INFO - Running setup.py install for manifestparser 22:24:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:24:15 INFO - Running setup.py install for mozcrash 22:24:16 INFO - Running setup.py install for mozdebug 22:24:16 INFO - Running setup.py install for mozdevice 22:24:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:24:16 INFO - Running setup.py install for mozfile 22:24:16 INFO - Running setup.py install for mozhttpd 22:24:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:24:16 INFO - Running setup.py install for mozinfo 22:24:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:24:16 INFO - Running setup.py install for mozInstall 22:24:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:24:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:24:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:24:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:24:16 INFO - Running setup.py install for mozleak 22:24:17 INFO - Running setup.py install for mozlog 22:24:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:24:17 INFO - Running setup.py install for moznetwork 22:24:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:24:17 INFO - Running setup.py install for mozprocess 22:24:17 INFO - Running setup.py install for mozprofile 22:24:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:24:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:24:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:24:17 INFO - Running setup.py install for mozrunner 22:24:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:24:17 INFO - Running setup.py install for mozscreenshot 22:24:18 INFO - Running setup.py install for moztest 22:24:18 INFO - Running setup.py install for mozversion 22:24:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:24:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:24:18 INFO - Cleaning up... 22:24:18 INFO - Return code: 0 22:24:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:18 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')]} 22:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:18 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')]} 22:24:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:18 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 0x1069f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede2d99980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d6d30>, '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 0x1066fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067cbbe8>, '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.l2F1H5DzU1/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.ARc2RGdXtM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 22:24:18 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 22:24:18 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 22:24:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l2F1H5DzU1/Render', 22:24:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:18 INFO - 'HOME': '/Users/cltbld', 22:24:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:18 INFO - 'LOGNAME': 'cltbld', 22:24:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:18 INFO - 'MOZ_NO_REMOTE': '1', 22:24:18 INFO - 'NO_EM_RESTART': '1', 22:24:18 INFO - 'PAGER': '/bin/cat', 22:24:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:18 INFO - 'PWD': '/builds/slave/test', 22:24:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:18 INFO - 'SHELL': '/bin/bash', 22:24:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARc2RGdXtM/Listeners', 22:24:18 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 22:24:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:18 INFO - 'USER': 'cltbld', 22:24:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:18 INFO - 'XPC_FLAGS': '0x0', 22:24:18 INFO - 'XPC_SERVICE_NAME': '0', 22:24:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:24:18 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-7iKczc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:24:18 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)) 22:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:24:18 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ogh83T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:24:18 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)) 22:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:24:18 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-TET1Md-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:24:18 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)) 22:24:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:24:18 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-Ul6Jq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:24:19 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)) 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:24:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-tGNzNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:24:19 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)) 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:24:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-FQDbKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:24:19 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)) 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:24:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-BwJY1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:24:19 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)) 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:24:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-BiY9RK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:24:19 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)) 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:24:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-CnjSHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:24:19 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)) 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:24:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-IXq5FZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:24:19 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)) 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:24:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-P3gemT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:24:19 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)) 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:24:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-42XY8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:24:19 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)) 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:24:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-1uP3Zl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:24:19 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)) 22:24:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:24:20 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-vECc3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:24:20 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)) 22:24:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:20 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-WydvEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:20 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)) 22:24:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:24:20 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-SRXKJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:24:20 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)) 22:24:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:24:20 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-2tEzF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:24:20 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)) 22:24:20 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)) 22:24:20 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)) 22:24:20 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)) 22:24:20 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)) 22:24:20 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)) 22:24:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:24:20 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 22:24:20 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 22:24:20 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 22:24:20 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 22:24:20 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 22:24:20 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 22:24:23 INFO - Downloading blessings-1.6.tar.gz 22:24:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:24:23 INFO - Installing collected packages: blessings 22:24:23 INFO - Running setup.py install for blessings 22:24:23 INFO - Successfully installed blessings 22:24:23 INFO - Cleaning up... 22:24:23 INFO - Return code: 0 22:24:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:24:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:24:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:24:23 INFO - Reading from file tmpfile_stdout 22:24:23 INFO - Current package versions: 22:24:23 INFO - blessings == 1.6 22:24:23 INFO - blobuploader == 1.2.4 22:24:23 INFO - docopt == 0.6.1 22:24:23 INFO - functools32 == 3.2.3-2 22:24:23 INFO - jsonschema == 2.5.1 22:24:23 INFO - manifestparser == 1.1 22:24:23 INFO - mozInstall == 1.12 22:24:23 INFO - mozcrash == 1.0 22:24:23 INFO - mozdebug == 0.1 22:24:23 INFO - mozdevice == 0.50 22:24:23 INFO - mozfile == 1.2 22:24:23 INFO - mozhttpd == 0.7 22:24:23 INFO - mozinfo == 0.9 22:24:23 INFO - mozleak == 0.1 22:24:23 INFO - mozlog == 3.4 22:24:23 INFO - moznetwork == 0.27 22:24:23 INFO - mozprocess == 0.25 22:24:23 INFO - mozprofile == 0.28 22:24:23 INFO - mozrunner == 6.13 22:24:23 INFO - mozscreenshot == 0.1 22:24:23 INFO - mozsystemmonitor == 0.3 22:24:23 INFO - moztest == 0.8 22:24:23 INFO - mozversion == 1.4 22:24:23 INFO - psutil == 3.1.1 22:24:23 INFO - requests == 1.2.3 22:24:23 INFO - wsgiref == 0.1.2 22:24:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:23 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 0x1069f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede2d99980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d6d30>, '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 0x1066fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067cbbe8>, '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.l2F1H5DzU1/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.ARc2RGdXtM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 22:24:23 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 22:24:23 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 22:24:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l2F1H5DzU1/Render', 22:24:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:23 INFO - 'HOME': '/Users/cltbld', 22:24:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:23 INFO - 'LOGNAME': 'cltbld', 22:24:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:23 INFO - 'MOZ_NO_REMOTE': '1', 22:24:23 INFO - 'NO_EM_RESTART': '1', 22:24:23 INFO - 'PAGER': '/bin/cat', 22:24:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:23 INFO - 'PWD': '/builds/slave/test', 22:24:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:23 INFO - 'SHELL': '/bin/bash', 22:24:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARc2RGdXtM/Listeners', 22:24:23 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 22:24:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:23 INFO - 'USER': 'cltbld', 22:24:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:23 INFO - 'XPC_FLAGS': '0x0', 22:24:23 INFO - 'XPC_SERVICE_NAME': '0', 22:24:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:24 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)) 22:24:24 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)) 22:24:24 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)) 22:24:24 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)) 22:24:24 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)) 22:24:24 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)) 22:24:24 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)) 22:24:24 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)) 22:24:24 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)) 22:24:24 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)) 22:24:24 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)) 22:24:24 INFO - Cleaning up... 22:24:24 INFO - Return code: 0 22:24:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:24:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1069f8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069fb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fede2d99980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067d6d30>, '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 0x1066fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067cbbe8>, '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.l2F1H5DzU1/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.ARc2RGdXtM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 22:24:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:24:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:24:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l2F1H5DzU1/Render', 22:24:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:24 INFO - 'HOME': '/Users/cltbld', 22:24:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:24 INFO - 'LOGNAME': 'cltbld', 22:24:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:24 INFO - 'MOZ_NO_REMOTE': '1', 22:24:24 INFO - 'NO_EM_RESTART': '1', 22:24:24 INFO - 'PAGER': '/bin/cat', 22:24:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:24 INFO - 'PWD': '/builds/slave/test', 22:24:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:24 INFO - 'SHELL': '/bin/bash', 22:24:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARc2RGdXtM/Listeners', 22:24:24 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 22:24:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:24 INFO - 'USER': 'cltbld', 22:24:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:24 INFO - 'XPC_FLAGS': '0x0', 22:24:24 INFO - 'XPC_SERVICE_NAME': '0', 22:24:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:24:24 INFO - Cleaning up... 22:24:24 INFO - Return code: 0 22:24:24 INFO - Running post-action listener: _resource_record_post_action 22:24:24 INFO - Running post-action listener: _start_resource_monitoring 22:24:24 INFO - Starting resource monitoring. 22:24:24 INFO - [mozharness: 2017-04-28 05:24:24.440142Z] Finished create-virtualenv step (success) 22:24:24 INFO - [mozharness: 2017-04-28 05:24:24.441212Z] Running install step. 22:24:24 INFO - Running pre-action listener: _resource_record_pre_action 22:24:24 INFO - Running main action method: install 22:24:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:24:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:24:24 INFO - Reading from file tmpfile_stdout 22:24:24 INFO - Detecting whether we're running mozinstall >=1.0... 22:24:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:24:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:24:24 INFO - Reading from file tmpfile_stdout 22:24:24 INFO - Output received: 22:24:24 INFO - Usage: mozinstall [options] installer 22:24:24 INFO - Options: 22:24:24 INFO - -h, --help show this help message and exit 22:24:24 INFO - -d DEST, --destination=DEST 22:24:24 INFO - Directory to install application into. [default: 22:24:24 INFO - "/builds/slave/test"] 22:24:24 INFO - --app=APP Application being installed. [default: firefox] 22:24:24 INFO - mkdir: /builds/slave/test/build/application 22:24:24 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'] 22:24:24 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 22:24:44 INFO - Reading from file tmpfile_stdout 22:24:44 INFO - Output received: 22:24:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:24:44 INFO - Running post-action listener: _resource_record_post_action 22:24:44 INFO - [mozharness: 2017-04-28 05:24:44.789905Z] Finished install step (success) 22:24:44 INFO - [mozharness: 2017-04-28 05:24:44.790033Z] Running run-tests step. 22:24:44 INFO - Running pre-action listener: _resource_record_pre_action 22:24:44 INFO - Running main action method: run_tests 22:24:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:24:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:24:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:24:44 INFO - 2017-04-27 22:24:44.804 screenresolution[6528:122263] starting screenresolution argv=screenresolution get 22:24:44 INFO - 2017-04-27 22:24:44.807 screenresolution[6528:122263] Display 0: 1600x1200x32@60 22:24:44 INFO - 2017-04-27 22:24:44.813 screenresolution[6529:122266] starting screenresolution argv=screenresolution list 22:24:44 INFO - Available Modes on Display 0 22:24:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:24:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:24:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:24:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:24:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:24:45 INFO - Intel Iris: 22:24:45 INFO - Chipset Model: Intel Iris 22:24:45 INFO - Type: GPU 22:24:45 INFO - Bus: Built-In 22:24:45 INFO - VRAM (Dynamic, Max): 1536 MB 22:24:45 INFO - Vendor: Intel (0x8086) 22:24:45 INFO - Device ID: 0x0a2e 22:24:45 INFO - Revision ID: 0x0009 22:24:45 INFO - Displays: 22:24:45 INFO - Display: 22:24:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:24:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:24:45 INFO - Display Serial Number: 1600x1200 60 22:24:45 INFO - Main Display: Yes 22:24:45 INFO - Mirror: Off 22:24:45 INFO - Online: Yes 22:24:45 INFO - Rotation: Supported 22:24:45 INFO - Return code: 0 22:24:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:24:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:24:45 INFO - Python 2.7.3 22:24:45 INFO - Return code: 0 22:24:45 INFO - grabbing minidump binary from tooltool 22:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:45 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 0x1067d6d30>, '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 0x1066fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067cbbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:24:45 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 22:24:45 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 22:24:45 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 22:24:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:24:45 INFO - Return code: 0 22:24:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:24:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:24:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:24:45 INFO - ENV: RUST_BACKTRACE is now 1 22:24:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:24:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:24:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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 22:24:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0116 --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 22:24:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l2F1H5DzU1/Render', 22:24:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:45 INFO - 'HOME': '/Users/cltbld', 22:24:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:45 INFO - 'LOGNAME': 'cltbld', 22:24:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:24:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:24:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:45 INFO - 'MOZ_NO_REMOTE': '1', 22:24:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:24:45 INFO - 'NO_EM_RESTART': '1', 22:24:45 INFO - 'PAGER': '/bin/cat', 22:24:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:45 INFO - 'PWD': '/builds/slave/test', 22:24:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:24:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:45 INFO - 'RUST_BACKTRACE': '1', 22:24:45 INFO - 'SHELL': '/bin/bash', 22:24:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ARc2RGdXtM/Listeners', 22:24:45 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 22:24:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:45 INFO - 'USER': 'cltbld', 22:24:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:45 INFO - 'XPC_FLAGS': '0x0', 22:24:45 INFO - 'XPC_SERVICE_NAME': '0', 22:24:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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 22:24:45 INFO - mozversion application_buildid: 20170427134925 22:24:45 INFO - mozversion application_changeset: 2acda68875ee036cf4d26685da4356dcdf308a87 22:24:45 INFO - mozversion application_display_name: Nightly 22:24:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:24:45 INFO - mozversion application_name: Firefox 22:24:45 INFO - mozversion application_remotingname: firefox 22:24:45 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 22:24:45 INFO - mozversion application_vendor: Mozilla 22:24:45 INFO - mozversion application_version: 55.0a1 22:24:45 INFO - mozversion platform_buildid: 20170427134925 22:24:45 INFO - mozversion platform_changeset: 2acda68875ee036cf4d26685da4356dcdf308a87 22:24:45 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 22:24:45 INFO - mozversion platform_version: 55.0a1 22:24:45 INFO - using testdate: 1493357085 22:24:45 INFO - actual date: 1493357085 22:24:45 INFO - starting webserver on 'localhost:57273' 22:24:45 INFO - SUITE-START | Running 2 tests 22:24:45 INFO - TEST-START | dromaeo_css 22:24:45 INFO - operating with platform_type : mac_ 22:24:45 INFO - Initialising browser for dromaeo_css test... 22:24:45 INFO - TEST-INFO | started process 6539 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmplj4hKo/profile http://localhost:57273/getInfo.html) 22:24:47 INFO - PID 6539 | 2017-04-27 22:24:47.827 plugin-container[6541:122438] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x982f, name = 'com.apple.tsm.portname' 22:24:47 INFO - PID 6539 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:24:47 INFO - PID 6539 | 2017-04-27 22:24:47.832 plugin-container[6541:122438] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4957, name = 'com.apple.CFPasteboardClient' 22:24:47 INFO - PID 6539 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:24:47 INFO - PID 6539 | 2017-04-27 22:24:47.832 plugin-container[6541:122438] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:24:48 INFO - PID 6539 | __metrics Screen width/height:1600/1200 22:24:48 INFO - PID 6539 | colorDepth:24 22:24:48 INFO - PID 6539 | Browser inner width/height: 1024/689 22:24:48 INFO - PID 6539 | __metrics 22:24:48 ERROR - PID 6539 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 22:24:49 INFO - TEST-INFO | 6539: exit 0 22:24:49 INFO - Browser initialized. 22:24:49 INFO - Running cycle 1/1 for dromaeo_css test... 22:24:49 INFO - TEST-INFO | started process 6543 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmplj4hKo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:24:51 INFO - PID 6543 | 2017-04-27 22:24:51.341 plugin-container[6544:122572] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x922b, name = 'com.apple.tsm.portname' 22:24:51 INFO - PID 6543 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:24:51 INFO - PID 6543 | 2017-04-27 22:24:51.342 plugin-container[6544:122572] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 22:24:51 INFO - PID 6543 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:24:51 INFO - PID 6543 | 2017-04-27 22:24:51.342 plugin-container[6544:122572] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:25:01 INFO - PID 6543 | RSS: Main: 251027456 22:25:01 INFO - PID 6543 | 22:28:06 INFO - PID 6543 | Cycle 1(1): loaded http://localhost:57273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57273/tests/dromaeo/cssquery-ext.html) 22:28:07 INFO - PID 6543 | RSS: Main: 255213568 22:28:07 INFO - PID 6543 | 22:29:49 INFO - PID 6543 | ************************* 22:29:49 INFO - PID 6543 | A coding exception was thrown and uncaught in a Task. 22:29:49 INFO - PID 6543 | 22:29:49 ERROR - PID 6543 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:29:49 INFO - PID 6543 | Full stack: 22:29:49 INFO - PID 6543 | ************************* 22:29:49 INFO - PID 6543 | ************************* 22:29:49 INFO - PID 6543 | A coding exception was thrown and uncaught in a Task. 22:29:49 INFO - PID 6543 | 22:29:49 ERROR - PID 6543 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:29:49 INFO - PID 6543 | Full stack: 22:29:49 INFO - PID 6543 | ************************* 22:29:49 INFO - PID 6543 | ************************* 22:29:49 INFO - PID 6543 | A coding exception was thrown and uncaught in a Task. 22:29:49 INFO - PID 6543 | 22:29:49 ERROR - PID 6543 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:29:49 INFO - PID 6543 | Full stack: 22:29:49 INFO - PID 6543 | ************************* 22:29:49 INFO - PID 6543 | ************************* 22:29:49 INFO - PID 6543 | A coding exception was thrown and uncaught in a Task. 22:29:49 INFO - PID 6543 | 22:29:49 ERROR - PID 6543 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:29:49 INFO - PID 6543 | Full stack: 22:29:49 INFO - PID 6543 | ************************* 22:31:09 INFO - PID 6543 | Cycle 1(1): loaded http://localhost:57273/tests/dromaeo/cssquery-ext.html (next: http://localhost:57273/tests/dromaeo/cssquery-jquery.html) 22:31:10 INFO - PID 6543 | RSS: Main: 257712128 22:31:10 INFO - PID 6543 | 22:34:11 INFO - PID 6543 | Cycle 1(1): loaded http://localhost:57273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57273/tests/dromaeo/cssquery-mootools.html) 22:34:12 INFO - PID 6543 | RSS: Main: 269918208 22:34:12 INFO - PID 6543 | 22:37:14 INFO - PID 6543 | Cycle 1(1): loaded http://localhost:57273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57273/tests/dromaeo/cssquery-prototype.html) 22:37:15 INFO - PID 6543 | RSS: Main: 263413760 22:37:15 INFO - PID 6543 | 22:40:17 INFO - PID 6543 | Cycle 1(1): loaded http://localhost:57273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57273/tests/dromaeo/cssquery-yui.html) 22:40:18 INFO - PID 6543 | RSS: Main: 273068032 22:40:18 INFO - PID 6543 | 22:43:20 INFO - PID 6543 | Cycle 1(1): loaded http://localhost:57273/tests/dromaeo/cssquery-yui.html (next: http://localhost:57273/tests/dromaeo/cssquery-dojo.html) 22:43:20 INFO - PID 6543 | RSS: Main: 267722752 22:43:20 INFO - PID 6543 | 22:43:20 INFO - PID 6543 | __start_tp_report 22:43:20 INFO - PID 6543 | _x_x_mozilla_page_load 22:43:20 INFO - PID 6543 | _x_x_mozilla_page_load_details 22:43:20 INFO - PID 6543 | |i|pagename|runs| 22:43:20 INFO - PID 6543 | |0;dojo.html;3582.3730847101765;3594.335048016117;3611.241639255756;3623.166671665516;3638.3814751492246;5253.7373131343375;5343.85107201951;6131.601445906019;6133.080037994303;6242.563020588561;6158.230221222348;6162.168107305512;6212.192414986045;6243.094751261065;6254.7498100075945;5319.148936170213;5335.013022590813;5342.893142137155;5405.135178371462;5463.754131064112;5429.755660995245;5548.91676624851;5625.099984002561;5645.915311270334;5648.774049038034;5414.214938833887;5528.612997090206;5560.443955604427;5571.164325351209;5603.7478313475995;4241.34259189823;5482.972585137089;5496.148097044925;5498.725063746837;5532.170174473821;4340.891477713082;5457.154141108134;5492.093804522249;5495.917561236544;5539.944600553983;6055.455009049207;6239.906401403982;6268.373162683741;6275.560710750249;6297.96851015742;6444.613323200623;6507.8698426031215;6629.966850165766;6658.167729033871;6702.664866756642;6801.047853300542;6831.941049137392;6838.829029274259;6840.931590684079;6849.589024658537;6305.621662700252;6345.270293916166;6367.904481432783;6368.1084648148835;6374.884395230909;6648.235452922879;6796.388325050671;6876.381125698711;6885.621290829053;6978.441724662015;5703.657780533182;6396.5522413431545;6589.077529145923;6651.800445986628;6662.733490660319;5174.327337446108;5369.543588794918;6638.302978187317;6754.966225168842;6761.01965215041;5634.85912852182;6518.185226846645;6682.966585167043;6689.6989635466525;6691.364320389653;6436.645984470899;6566.343365663491;6677.76627818024;6695.732170713214;6703.664816759143;4539.319102134707;5219.2953951216095;5297.152455607084;5310.522053015247;5379.946200537945;4745.738984355894;4770.880727981758;4857.028594281158;4872.43966943806;4881.902361952741;6168.753249870045;6236.158118653923;6367.968160159169;6374.9681251594375;6380.425761681378;6561.1142495763415;6757.526973111835;6788.9660551696925;6837.555558888656;6844.384005439534;5070.7464626768515;6142.78500252489;6266.937330626726;6314.747410103636;6387.552871299057;5923.052311630118;5942.376050514634;5974.581779275409;6012.549058820606;6014.278286605548;6956.79129626112;6996.6151861648095;7040.331168538981;7053.682584283617;7074.752383666547;6503.902441463477;6591.34086591337;6727.360900714424;6834.282400347892;6836.453083753188;6919.515633905578;7013.789586312419;7042.577445353295;7044.647767611701;7046.154461464657;6579.374959378852;6679.632620205935;6681.632510211985;6696.497762667722;6711.161104861892;6493.675316234264;6601.042848786995;6736.191657000995;6803.829904252433;6827.146606674166;5453.427390123979;5466.2620546227345;6454.225492940877;6595.175603049619;6608.70260838273;6300.495960323072;6455.677216139268;6575.2767195607485;6603.339666033358;6612.272650008592;4703.788329525111;6125.448709616156;6279.277883043508;6283.874322513616;6291.622502649856;6344.968275158594;6591.373819487141;6594.571352862049;6600.339966003361;6616.073749674953;6312.43188113072;6326.019466982441;6351.523635727432;6373.776917807854;6433.067205255118 22:43:20 INFO - PID 6543 | |1;ext.html;754.8678981178294;759.780552213697;810.813512892035;1200.0579545057133;1268.448225022116;36835.447468287966;37636.99999999996;37734.622653773455;37818.8109059455;37881.62118378816;38145.618543814555;38492.80753596228;38495;38528.844134675935;38590.42114368287;7099.787006389816;7893.171217022216;8072.071711753136;8279.296259817922;8499.532525711084;8511.787205319886;8640.827183456327;8641.30869530436;8644.697435589756;8650.048494665612;8578.44240124393;8595.226429621332;8629.913700863006;8648.481091134521;8650;7444.622744792204;8154.06228283749;8444.282236009947;8476.49141051539;8578.313734901192;7539;7928.563928983904;8316.83366332676;8375;8387;36815;36980.89057328284;37057.25885482275;37135;37367.4394884077;3592.550931133608;3620.6922411595046;3623.7825730456257;3624.4925710400566;3628.727845411578;29851.70148298511;30400.8479957601;30437;30489;30493.69506304931;3045.558394032853;3056.5262384330463;3078.8614512347;3089.4130115278026;3099.891503797356;182860.17139828563;240296;247592;261155.69422152766;296766;37461;46457.76771116123;46861.828454288916;46992.530074698814;47175;48795;49124.508754912706;49223.26165107526;49622.25566616504;49940;13485;13582.728345433035;13583.660408489866;13617.659558511117;13623.523176688768;10982.670519884416;11076.390798505998;11144.442777861075;11156.386398747984;11170.385628790333;13352.46590136403;13356.73286534264;13419.530316439073;13433.462661493626;13487.932560337333;5813.244278243801;5891.617044892068;5937.228160339128;5937.732802023919;5957.27239100656;964.5563041001218;995.8058178655093;1012.9341592796445;1038.2109596706407;1061.9645845300925;20404.693929591067;22900.083996640282;24199.75800241975;24226.273211803673;24291.149809756575;1111.7109551516664;1114.8049091409134;1130.423484023154;1150.4822829726656;1154.2324354304462;10413.531391087268;12249.142560020891;12430.067744919168;12431.62705118848;12443.440045197987;293120;295770;298000;298584;298770;42029.528966486025;48965.02069958652;52671.73664131655;52752.94494110096;52898.73550632222;1487.9181645009628;1519.5061604978425;1563.2262030295076;1563.7654351847314;1564.241342948669;20230.494237643587;23847.52304953926;24007.759922400553;24014.15950441726;24020.759792401852;31094.445277736475;37491.81254093712;37568;37598;37670.434943476415;248979;269534;270493;270527;271341;24969.87515062486;33595.664043359255;34250.82874585611;34357.656423435445;34496.827515862264;36924.630753692116;40710.59289407068;43364.78317608392;44428.777856110515;45270;28642.427151457272;29611;30379;31049;31499.685003149676;22511.5497690042;27974.881004759583;30589.77640894339;31359.37281254408;31496.37007259888;35164.12089697778;35786.28427431489;36147;36506;36925.815370922974;23808.761912380654;28507.00225492098;31129.533057004617;31274.53088203724;31490.527642085846;23310.83445827668;24151;24395.51208975846;24634.753652463245;24649.876750616848 22:43:20 INFO - PID 6543 | |2;jquery.html;433.1964206397135;442.2680463832356;442.32766195383016;457.59502839986624;459.52209701910016;19192.23231070757;20945.858121281795;21610;21649.242276520326;21822.236221732244;21769.346919592437;22935.19726809562;23256.767432325672;23532.17637382692;23604.173853915116;5095.0574143783515;6165.599236049654;6390.265119511269;6409.583377080475;6413.486921046305;6115.082737589352;6296.842578935541;6323.873522529547;6329.683515824214;6353.555251132422;6197.752089916398;6273.090401891723;6286.434220920116;6483.870322593545;6919.619420931846;6860.211075726305;6955.547889387173;6988.895166572505;7009;7033.226345102034;5555.250041244421;5827.184194212814;6700.894352431892;6785.762498312535;6820.6930688119155;21365.893170534047;28048.859755701294;29460.37967911764;29685.703142968727;29689.40621187564;2021.2521367094123;2318.8840557972035;2339.777721116491;2363.9645405318934;2368.62102063669;18408.81591184098;18919.905400472908;19414.83510989331;19724.80275197244;19841.206351745914;2243.5288589396246;2296.9310920672406;2357.4695693469002;2357.8938947747392;2358.941026474352;152923;167123;168847.99999999878;169241;170804;20987.790122098584;24776.752232477444;24874.5025099497;25319.987200512143;26452.074177403698;40756.38865417021;41280.38079428811;42254.36618450726;42269;42292.46245225296;6906.309369063053;8943.955280223558;9310.162085412345;9436.764080898032;9440.10319019692;6528.445082167974;7750.534967901944;8662.783430414163;8821.735347939572;9052.773680658036;6632.4694024478895;8111.351091912633;8230.135835737281;8232.753017409488;8300.169983001651;2295.3992258888593;2300.367398965298;2303.285981345755;2314.884255787204;2466.938326541815;960.4669408478222;972.4165500699523;985.0691096913348;1003.1623594298842;1028.6039874648166;21404.678929816066;21719.891400542896;21778.89110554437;21788.455288617595;21848;795.5306369242174;990.4898977026837;1016.3292227130058;1022.4989755020135;1038.745507350704;10993.395363255098;11270.661880143609;11343.262687925258;11369.204155709022;11370.431478426046;397697;516873;523201;535609;537820;123207;130896;134823.32588337193;139120.30439847737;140621;1182.4028865422906;1212.1393810394718;1234.1299383934304;1237.9504819807287;1238.8823061809114;21474.71151730901;21821.781782181974;22073.668894966908;22762.772372276726;22845.31464056081;58007.709961449924;58959.231223063376;59102.70448647729;59252.70373648104;59278;78755;79954.60022700082;81255.59372203102;82102.58948705457;82435;8661.13388661129;8847;8935.865962010444;9015.819683606422;9211.677591284273;10799.622013229498;13368.529461759394;14074.507392241223;14112.223827689575;14513.05665131763;10177.982201779763;11836.171467997157;11989.160758747154;12024.398780061138;12065.215760975509;11422.714432139262;11597.07223422141;11639.47622356979;11882.465289062186;11953.924146826826;9520.143187113194;11955.342456164994;12206.20659657155;12220.755584888075;12398.25610463375;8766.167214114648;8935.910640893508;9077.909220907706;9119.589618467318;9206.309526785652;11671.88328116708;11903.583374582195;11943.940280298542;12003.399830008639;12092.516299347928 22:43:20 INFO - PID 6543 | |3;mootools.html;1244.4400019991003;1478.3938585180072;1495.7382458069835;1511.448321362703;1515.901466404684;11476.426178691063;11713;11724.23792453491;11967.880321196797;12078.033757299418;11809.232399894001;11966.940165299184;11968.222065565733;11968.222065565733;11974.7605047899;2505.674262345897;2542.084849454195;2545.350935511442;2554.5274124286975;2554.897804087834;1986.3941497843152;2366.95266094678;2423.75762423758;2430.4774473488196;2430.6597076409316;2458.8032957363457;2512.6984761828608;2536.4166241764406;2537.7208507064206;2538.213153922281;2454.275988583372;2481.565725997943;2496.0015993602706;2512.472380800034;2520.8361456505268;2027.2093883385492;2440.4021014851296;2489.825712200147;2505.837120587174;2531.1520640585427;11582.536698532049;11686.357250351228;11763.058955283557;11781.705457363549;11786.528538858436;6547.770828020996;6807.3873351398115;6808.557443766189;6816.659167041628;6821.590704557742;3865.4781604483314;4173.3114036184;4203.43253660758;4214.91570168598;4253.638440732541;1165.4697112813735;1172.2497601535024;1196.6888608961733;1236.4312416288428;1237.6287113865803;16253.59366015859;16673.416430424877;16820.57948551296;16837.663246734995;16847.07341096239;5440.401555828856;5575.721213939287;5689.658620482702;5732.0255556555485;5740.024195886709;11806.350650714294;11891.821622675667;11901.226420282654;11907.464164112636;11908.047356211482;6484.448821850196;6520.380563846427;6534.477241820643;6545.738170473128;6554.377334153248;5127.487251274917;5196.856691527858;6128.571000030044;6182.69086545671;6223.719932603044;3830.597787232356;4057.6753859691153;4372.759498227628;4438.112377524508;4480.708753930984;1258.7671280813079;1276.8212450256972;1291.180100636103;1291.5479582146174;1294.9805752913715;800.6837299266757;810.3760104719333;826.8222332198607;836.2515548583983;859.7248880358351;5311;5493.505584497414;6125.908111378333;6217.782377616762;6383.138276332728;580.2543731305252;586.8767558812697;615.2217444932164;616.6669998201011;622.1259130921063;4098.877033688994;4199.265128602482;4310.719803212781;4313.417688611998;4364.563543645673;13702.177869327872;14402.711945761026;14853;15246.46637367709;15388.61528461775;14685.265736713121;14784.556463306119;15010;15119.319630616428;15147.772783408258;7430.777076687708;7948.608993426127;8224.259816616413;8595.140485951355;8672.56637168139;5970.850728731816;6442.033694945796;6637.568558043712;7135.143782745896;7227.205007449072;9660.903390966281;12296.077794165294;13081;13286.667833304246;13650.658733531742;13571.660708482366;13684.794728079285;13879.722405552035;13965;14319.926005549418;9103.317251206054;9298.163165314883;9545.093216144454;9545.52272386364;9574.04259574065;11026.062784663394;11173.944130279297;11242.94378528102;11259.493322800658;11304.43477826089;9127.08729127082;9128.863067053866;9249.028851970716;9323.766905827137;9359.766005849908;9026.819463610822;9028.909710902806;9099.363044586815;9107.453552786854;9148.542572871464;8112.553809540532;8191.262786349257;8295.585220738818;8324.376746534368;8411.285040771603;5120.718360490208;6050.667213303311;6075.119107729266;6259.217597800275;6294.716737746904;6125.479334256638;6155.230596175478;6179.660118693515;6203;6304.243490780952 22:43:20 INFO - PID 6543 | |4;prototype.html;403.58833989330867;404.0968435546553;406.5284270246515;406.6238729175513;419.0508498251459;5750.539956803462;6251.187345645066;6312.295680166352;6331.873362532747;6387.201599800025;4603.953960460402;5759.625624334415;6151.538634602401;6186.907196392047;6206.286277078138;4674.883127921812;4693.647976401775;4703.553162449579;4717.528247175289;4726.409198850144;4626.8380606678775;4721.409823772028;4734.147853386389;4749.050189962004;4749.643776716743;4597.494275629695;4659.650526210531;4664.580187783099;4689.484156742756;4696.882577935545;3996.700247481436;4181.498220213593;4563.657725670571;4671.229247174212;4697.694649847749;3507.5089487471773;4046.878593642166;4108.36320370343;4170.582941705836;4171.520275168334;4950.960298337353;6000.599760095996;6078.3921607838865;6078.665673387962;6081.574289799716;5395.7032363220005;5561.77752889884;5568.972155139199;5598.916016259759;5654.46282603152;5415.810446634349;6127.816165515042;6227.408396202353;6385.776497822554;6611.801645950629;4759;4771.614192903548;4793.32893394925;4796.616270698336;4796.640251981081;5818.679972601505;6130.63216207029;6335.904961425582;6926.2034865990045;7132.215456299803;3425.989333146728;4420.027593929331;4566.794494247767;4607.986243026528;4627.768611569475;4095.508538975342;4128.772917489507;4695.882602934954;4725.716457012591;4742.217534106902;5378.811741589026;5438.619296649275;5458.727063646802;5466.3440387153005;5521.254630624894;5801.88396232073;6048.6673232971725;6552.868942621121;6610.8347291318105;6707.926731722999;1351.8377794664702;1702.6254224070685;1723.7069698151345;1727.2745446912327;1769.8938063716218;1014.0721240065405;1159.7854396936593;1192.5706745571588;1200.351810022578;1208.2810727617054;5686.09022556389;6019.879602407927;6267.247930248308;6281.7173227204885;6286.968565157145;6579.80260592183;6828.317168283232;6829.214640316427;6852.383285504328;6864.862702745917;5199.688018718889;5828.88342233153;6274.874502509924;6280.937190628127;6300.936990630126;2984.8358340291493;3002.6847181045678;3132.8903488377796;3346.380919529895;3581.391163502211;5964.194833697482;5989.341172471025;6146.385361463907;7140.714371425189;7430.108386993487;5906.497947674496;6270.529710271748;6783.287754785775;7124.679389427488;7134;7056.717731290793;7063;7118.394936430358;7130.393916517154;7173.820654483679;5089.872753181052;6095.847603809762;6790.049393085071;6847.486438517031;6872.518923675295;6169.56813023084;6689.76585819494;6797.116374871235;6804.183497980274;6820.693068811816;6810;7039.296070392919;7139.178994415501;7168.784936451915;7179.892301615376;3458.4466485362223;3627.4558816177782;4175.791210439526;4401.251787196248;4407.449068866392;5116.769745361393;5456.263404440349;6576.868462630625;6842.05531385634;6882.969523990535;4763.809047738066;5930.11048342752;5962.612430192024;6515.446187074152;6558.901616475661;5286.894262114659;6299.393654618043;6601.3728695773825;6609.4712423007;6629.5359324846795;6226.875462490634;6250.687355655182;6340.302566717565;6439.066335381437;6526.24948130952;5791.333996590446;6538.509611779041;6675.632840193836;6690.264070952288;6722.9663851680425;5486.31421072366;6377.681115944277;6410.839729006812;6520.347965203442;6551.246606640107 22:43:20 INFO - PID 6543 | |5;yui.html;846.7798372423168;849.698357083235;862.6721845698634;876.0582373948007;888.6934007767322;15247.771283430744;15706.842931570682;15758.842411575895;17898.910505447486;18419.815801841978;17627.471175864746;17643.73534396985;17850.64298714025;17858.910705446455;18022.27910883563;976.4336684722862;980.0640388429033;992.0575453319375;995.2684776689127;995.3729150635126;995.248587316575;999.9050090241452;1009.9798004039915;1010.9486134420195;1013.5591017907198;1038.7351225437503;1058.2380685906173;1061.0185578340042;1062.2723434447428;1062.4315990944847;1057.5981127171665;1057.6351158850262;1058.3491152940962;1058.6718117383637;1062.54310646422;748.094805285605;764.9120351159577;766.4404984361447;779.5478622398995;779.6062988190984;15580.454684086002;15597.220138993005;15686.058836469734;15700.842991570053;15702;15993.720502359783;16314;16782.993020418813;17275.481735547954;17307.307707691678;11316.490757915913;13960.650983725387;14000.649983750385;14084.366203520865;14094.154350738989;2808.761255293282;2997.6103106596;3001.5347621118544;3242.221866751986;3249.46383846665;112429.87570124275;125213;128612;128804.067938981;129391;11264.211505194558;13111;13410.731785364238;13426.932865335611;13438.328083595781;20962.47593810167;24138.87930560371;25166.119185828407;25574.360640984123;25710.48579028409;6316.968415157987;6555.672216389162;7789.3768498519985;7827.765167044998;7890.408219383569;3834.1948190880225;3898.064464528493;3898.5321761388814;3905.6680182184264;3936.3308237599676;4297.613214810682;4300.61294483498;4377.124575084996;4428.756418397019;4437.955620443754;936.9578368973413;961.0293603460556;969.7721035556639;972.4845831709206;972.9708108756607;637.7704026550438;669.534673401981;671.9059331693567;680.4182424027484;693.2824526614929;11304.095672346193;11815.818418158287;11848.822267665992;12081.697957551132;12095.57665481704;658.733213048716;658.8023592922104;660.8579155481594;663.1213641924469;692.9826754331082;5351.197320401893;5494.917576236359;5547.389787123413;5571.749271282802;5668.489835914787;95683.12594673873;110138.34792478119;110140.449297753;111186.44406777914;112936;73439;82112;84977.15022849815;86533.70199446888;87549.56225218833;75300.62349688326;77147;77455;78746.21253787504;79385.60307198427;9306.813863722824;12622.179558328678;12803.871961280269;12805.679858003252;12859.16415432994;28318.57522137211;38286.6171338283;38707.225855483295;38831.8350449487;39881.60118398779;58628;66164.66917665381;67203.65592688217;67544.66227668831;68248.6587567059;15961.281742321391;16388.295931171222;16641;16652.167391630126;16726;16415.589610259838;17869.910650446665;18122.184501697193;18190.36333728366;18260.726089108408;16706.164691765607;16740.41408550695;16758;16935.91532042332;17034.574135646708;16115;16209.918950405172;16759.581010474347;16848.15759212059;16857.66284674275;15851.841481585498;15975.680486389974;16423.17884105766;16570.83429165693;16737.497875063767;10003.949980250052;11695.76608467843;12019.75960480768;12075.215111018108;12289.44697488633;12071.939640302093;12149;12726.68183295388;12780.297083660487;12788.232706037668 22:43:20 INFO - PID 6543 | __end_tp_report 22:43:20 INFO - PID 6543 | __start_cc_report 22:43:20 INFO - PID 6543 | _x_x_mozilla_cycle_collect,436 22:43:20 INFO - PID 6543 | __end_cc_report 22:43:20 INFO - PID 6543 | __startTimestamp1493358200544__endTimestamp 22:43:20 INFO - PID 6543 | 22:43:20 INFO - PID 6543 | ------- Summary: start ------- 22:43:20 INFO - PID 6543 | Number of tests: 6 22:43:20 INFO - PID 6543 | 22:43:20 INFO - PID 6543 | [#0] dojo.html Cycles:165 Average:6112.00 Median:6345.27 stddev:771.43 (12.2%) stddev-sans-first:747.84 22:43:20 INFO - PID 6543 | Values: 3582.4 3594.3 3611.2 3623.2 3638.4 5253.7 5343.9 6131.6 6133.1 6242.6 6158.2 6162.2 6212.2 6243.1 6254.7 5319.1 5335.0 5342.9 5405.1 5463.8 5429.8 5548.9 5625.1 5645.9 5648.8 5414.2 5528.6 5560.4 5571.2 5603.7 4241.3 5483.0 5496.1 5498.7 5532.2 4340.9 5457.2 5492.1 5495.9 5539.9 6055.5 6239.9 6268.4 6275.6 6298.0 6444.6 6507.9 6630.0 6658.2 6702.7 6801.0 6831.9 6838.8 6840.9 6849.6 6305.6 6345.3 6367.9 6368.1 6374.9 6648.2 6796.4 6876.4 6885.6 6978.4 5703.7 6396.6 6589.1 6651.8 6662.7 5174.3 5369.5 6638.3 6755.0 6761.0 5634.9 6518.2 6683.0 6689.7 6691.4 6436.6 6566.3 6677.8 6695.7 6703.7 4539.3 5219.3 5297.2 5310.5 5379.9 4745.7 4770.9 4857.0 4872.4 4881.9 6168.8 6236.2 6368.0 6375.0 6380.4 6561.1 6757.5 6789.0 6837.6 6844.4 5070.7 6142.8 6266.9 6314.7 6387.6 5923.1 5942.4 5974.6 6012.5 6014.3 6956.8 6996.6 7040.3 7053.7 7074.8 6503.9 6591.3 6727.4 6834.3 6836.5 6919.5 7013.8 7042.6 7044.6 7046.2 6579.4 6679.6 6681.6 6696.5 6711.2 6493.7 6601.0 6736.2 6803.8 6827.1 5453.4 5466.3 6454.2 6595.2 6608.7 6300.5 6455.7 6575.3 6603.3 6612.3 4703.8 6125.4 6279.3 6283.9 6291.6 6345.0 6591.4 6594.6 6600.3 6616.1 6312.4 6326.0 6351.5 6373.8 6433.1 22:43:20 INFO - PID 6543 | 22:43:20 INFO - PID 6543 | [#1] ext.html Cycles:180 Average:41973.45 Median:31498.03 stddev:70974.38 (225.3%) stddev-sans-first:71106.01 22:43:20 INFO - PID 6543 | Values: 754.9 759.8 810.8 1200.1 1268.4 36835.4 37637.0 37734.6 37818.8 37881.6 38145.6 38492.8 38495.0 38528.8 38590.4 7099.8 7893.2 8072.1 8279.3 8499.5 8511.8 8640.8 8641.3 8644.7 8650.0 8578.4 8595.2 8629.9 8648.5 8650.0 7444.6 8154.1 8444.3 8476.5 8578.3 7539.0 7928.6 8316.8 8375.0 8387.0 36815.0 36980.9 37057.3 37135.0 37367.4 3592.6 3620.7 3623.8 3624.5 3628.7 29851.7 30400.8 30437.0 30489.0 30493.7 3045.6 3056.5 3078.9 3089.4 3099.9 182860.2 240296.0 247592.0 261155.7 296766.0 37461.0 46457.8 46861.8 46992.5 47175.0 48795.0 49124.5 49223.3 49622.3 49940.0 13485.0 13582.7 13583.7 13617.7 13623.5 10982.7 11076.4 11144.4 11156.4 11170.4 13352.5 13356.7 13419.5 13433.5 13487.9 5813.2 5891.6 5937.2 5937.7 5957.3 964.6 995.8 1012.9 1038.2 1062.0 20404.7 22900.1 24199.8 24226.3 24291.1 1111.7 1114.8 1130.4 1150.5 1154.2 10413.5 12249.1 12430.1 12431.6 12443.4 293120.0 295770.0 298000.0 298584.0 298770.0 42029.5 48965.0 52671.7 52752.9 52898.7 1487.9 1519.5 1563.2 1563.8 1564.2 20230.5 23847.5 24007.8 24014.2 24020.8 31094.4 37491.8 37568.0 37598.0 37670.4 248979.0 269534.0 270493.0 270527.0 271341.0 24969.9 33595.7 34250.8 34357.7 34496.8 36924.6 40710.6 43364.8 44428.8 45270.0 28642.4 29611.0 30379.0 31049.0 31499.7 22511.5 27974.9 30589.8 31359.4 31496.4 35164.1 35786.3 36147.0 36506.0 36925.8 23808.8 28507.0 31129.5 31274.5 31490.5 23310.8 24151.0 24395.5 24634.8 24649.9 22:43:20 INFO - PID 6543 | 22:43:20 INFO - PID 6543 | [#2] jquery.html Cycles:180 Average:36104.88 Median:13621.85 stddev:86919.51 (638.1%) stddev-sans-first:87122.08 22:43:20 INFO - PID 6543 | Values: 433.2 442.3 442.3 457.6 459.5 19192.2 20945.9 21610.0 21649.2 21822.2 21769.3 22935.2 23256.8 23532.2 23604.2 5095.1 6165.6 6390.3 6409.6 6413.5 6115.1 6296.8 6323.9 6329.7 6353.6 6197.8 6273.1 6286.4 6483.9 6919.6 6860.2 6955.5 6988.9 7009.0 7033.2 5555.3 5827.2 6700.9 6785.8 6820.7 21365.9 28048.9 29460.4 29685.7 29689.4 2021.3 2318.9 2339.8 2364.0 2368.6 18408.8 18919.9 19414.8 19724.8 19841.2 2243.5 2296.9 2357.5 2357.9 2358.9 152923.0 167123.0 168848.0 169241.0 170804.0 20987.8 24776.8 24874.5 25320.0 26452.1 40756.4 41280.4 42254.4 42269.0 42292.5 6906.3 8944.0 9310.2 9436.8 9440.1 6528.4 7750.5 8662.8 8821.7 9052.8 6632.5 8111.4 8230.1 8232.8 8300.2 2295.4 2300.4 2303.3 2314.9 2466.9 960.5 972.4 985.1 1003.2 1028.6 21404.7 21719.9 21778.9 21788.5 21848.0 795.5 990.5 1016.3 1022.5 1038.7 10993.4 11270.7 11343.3 11369.2 11370.4 397697.0 516873.0 523201.0 535609.0 537820.0 123207.0 130896.0 134823.3 139120.3 140621.0 1182.4 1212.1 1234.1 1238.0 1238.9 21474.7 21821.8 22073.7 22762.8 22845.3 58007.7 58959.2 59102.7 59252.7 59278.0 78755.0 79954.6 81255.6 82102.6 82435.0 8661.1 8847.0 8935.9 9015.8 9211.7 10799.6 13368.5 14074.5 14112.2 14513.1 10178.0 11836.2 11989.2 12024.4 12065.2 11422.7 11597.1 11639.5 11882.5 11953.9 9520.1 11955.3 12206.2 12220.8 12398.3 8766.2 8935.9 9077.9 9119.6 9206.3 11671.9 11903.6 11943.9 12003.4 12092.5 22:43:20 INFO - PID 6543 | 22:43:20 INFO - PID 6543 | [#3] mootools.html Cycles:180 Average:7084.92 Median:3961.58 stddev:4532.61 (114.4%) stddev-sans-first:4524.07 22:43:20 INFO - PID 6543 | Values: 1244.4 1478.4 1495.7 1511.4 1515.9 11476.4 11713.0 11724.2 11967.9 12078.0 11809.2 11966.9 11968.2 11968.2 11974.8 2505.7 2542.1 2545.4 2554.5 2554.9 1986.4 2367.0 2423.8 2430.5 2430.7 2458.8 2512.7 2536.4 2537.7 2538.2 2454.3 2481.6 2496.0 2512.5 2520.8 2027.2 2440.4 2489.8 2505.8 2531.2 11582.5 11686.4 11763.1 11781.7 11786.5 6547.8 6807.4 6808.6 6816.7 6821.6 3865.5 4173.3 4203.4 4214.9 4253.6 1165.5 1172.2 1196.7 1236.4 1237.6 16253.6 16673.4 16820.6 16837.7 16847.1 5440.4 5575.7 5689.7 5732.0 5740.0 11806.4 11891.8 11901.2 11907.5 11908.0 6484.4 6520.4 6534.5 6545.7 6554.4 5127.5 5196.9 6128.6 6182.7 6223.7 3830.6 4057.7 4372.8 4438.1 4480.7 1258.8 1276.8 1291.2 1291.5 1295.0 800.7 810.4 826.8 836.3 859.7 5311.0 5493.5 6125.9 6217.8 6383.1 580.3 586.9 615.2 616.7 622.1 4098.9 4199.3 4310.7 4313.4 4364.6 13702.2 14402.7 14853.0 15246.5 15388.6 14685.3 14784.6 15010.0 15119.3 15147.8 7430.8 7948.6 8224.3 8595.1 8672.6 5970.9 6442.0 6637.6 7135.1 7227.2 9660.9 12296.1 13081.0 13286.7 13650.7 13571.7 13684.8 13879.7 13965.0 14319.9 9103.3 9298.2 9545.1 9545.5 9574.0 11026.1 11173.9 11242.9 11259.5 11304.4 9127.1 9128.9 9249.0 9323.8 9359.8 9026.8 9028.9 9099.4 9107.5 9148.5 8112.6 8191.3 8295.6 8324.4 8411.3 5120.7 6050.7 6075.1 6259.2 6294.7 6125.5 6155.2 6179.7 6203.0 6304.2 22:43:20 INFO - PID 6543 | 22:43:20 INFO - PID 6543 | [#4] prototype.html Cycles:180 Average:5307.79 Median:5823.78 stddev:1644.99 (28.2%) stddev-sans-first:1607.89 22:43:20 INFO - PID 6543 | Values: 403.6 404.1 406.5 406.6 419.1 5750.5 6251.2 6312.3 6331.9 6387.2 4604.0 5759.6 6151.5 6186.9 6206.3 4674.9 4693.6 4703.6 4717.5 4726.4 4626.8 4721.4 4734.1 4749.1 4749.6 4597.5 4659.7 4664.6 4689.5 4696.9 3996.7 4181.5 4563.7 4671.2 4697.7 3507.5 4046.9 4108.4 4170.6 4171.5 4951.0 6000.6 6078.4 6078.7 6081.6 5395.7 5561.8 5569.0 5598.9 5654.5 5415.8 6127.8 6227.4 6385.8 6611.8 4759.0 4771.6 4793.3 4796.6 4796.6 5818.7 6130.6 6335.9 6926.2 7132.2 3426.0 4420.0 4566.8 4608.0 4627.8 4095.5 4128.8 4695.9 4725.7 4742.2 5378.8 5438.6 5458.7 5466.3 5521.3 5801.9 6048.7 6552.9 6610.8 6707.9 1351.8 1702.6 1723.7 1727.3 1769.9 1014.1 1159.8 1192.6 1200.4 1208.3 5686.1 6019.9 6267.2 6281.7 6287.0 6579.8 6828.3 6829.2 6852.4 6864.9 5199.7 5828.9 6274.9 6280.9 6300.9 2984.8 3002.7 3132.9 3346.4 3581.4 5964.2 5989.3 6146.4 7140.7 7430.1 5906.5 6270.5 6783.3 7124.7 7134.0 7056.7 7063.0 7118.4 7130.4 7173.8 5089.9 6095.8 6790.0 6847.5 6872.5 6169.6 6689.8 6797.1 6804.2 6820.7 6810.0 7039.3 7139.2 7168.8 7179.9 3458.4 3627.5 4175.8 4401.3 4407.4 5116.8 5456.3 6576.9 6842.1 6883.0 4763.8 5930.1 5962.6 6515.4 6558.9 5286.9 6299.4 6601.4 6609.5 6629.5 6226.9 6250.7 6340.3 6439.1 6526.2 5791.3 6538.5 6675.6 6690.3 6723.0 5486.3 6377.7 6410.8 6520.3 6551.2 22:43:20 INFO - PID 6543 | 22:43:20 INFO - PID 6543 | [#5] yui.html Cycles:180 Average:21596.47 Median:17960.59 stddev:30524.28 (170.0%) stddev-sans-first:30570.15 22:43:20 INFO - PID 6543 | Values: 846.8 849.7 862.7 876.1 888.7 15247.8 15706.8 15758.8 17898.9 18419.8 17627.5 17643.7 17850.6 17858.9 18022.3 976.4 980.1 992.1 995.3 995.4 995.2 999.9 1010.0 1010.9 1013.6 1038.7 1058.2 1061.0 1062.3 1062.4 1057.6 1057.6 1058.3 1058.7 1062.5 748.1 764.9 766.4 779.5 779.6 15580.5 15597.2 15686.1 15700.8 15702.0 15993.7 16314.0 16783.0 17275.5 17307.3 11316.5 13960.7 14000.6 14084.4 14094.2 2808.8 2997.6 3001.5 3242.2 3249.5 112429.9 125213.0 128612.0 128804.1 129391.0 11264.2 13111.0 13410.7 13426.9 13438.3 20962.5 24138.9 25166.1 25574.4 25710.5 6317.0 6555.7 7789.4 7827.8 7890.4 3834.2 3898.1 3898.5 3905.7 3936.3 4297.6 4300.6 4377.1 4428.8 4438.0 937.0 961.0 969.8 972.5 973.0 637.8 669.5 671.9 680.4 693.3 11304.1 11815.8 11848.8 12081.7 12095.6 658.7 658.8 660.9 663.1 693.0 5351.2 5494.9 5547.4 5571.7 5668.5 95683.1 110138.3 110140.4 111186.4 112936.0 73439.0 82112.0 84977.2 86533.7 87549.6 75300.6 77147.0 77455.0 78746.2 79385.6 9306.8 12622.2 12803.9 12805.7 12859.2 28318.6 38286.6 38707.2 38831.8 39881.6 58628.0 66164.7 67203.7 67544.7 68248.7 15961.3 16388.3 16641.0 16652.2 16726.0 16415.6 17869.9 18122.2 18190.4 18260.7 16706.2 16740.4 16758.0 16935.9 17034.6 16115.0 16209.9 16759.6 16848.2 16857.7 15851.8 15975.7 16423.2 16570.8 16737.5 10003.9 11695.8 12019.8 12075.2 12289.4 12071.9 12149.0 12726.7 12780.3 12788.2 22:43:20 INFO - PID 6543 | -------- Summary: end -------- 22:43:20 INFO - PID 6543 | 22:43:21 INFO - TEST-INFO | 6543: exit 0 22:43:21 INFO - TEST-OK | dromaeo_css | took 1115793ms 22:43:21 INFO - TEST-START | kraken 22:43:21 INFO - operating with platform_type : mac_ 22:43:21 INFO - Initialising browser for kraken test... 22:43:21 INFO - TEST-INFO | started process 6549 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpNFErRj/profile http://localhost:57273/getInfo.html) 22:43:23 INFO - PID 6549 | 2017-04-27 22:43:23.667 plugin-container[6550:124692] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 22:43:23 INFO - PID 6549 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:43:23 INFO - PID 6549 | 2017-04-27 22:43:23.670 plugin-container[6550:124692] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x971b, name = 'com.apple.CFPasteboardClient' 22:43:23 INFO - PID 6549 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:43:23 INFO - PID 6549 | 2017-04-27 22:43:23.670 plugin-container[6550:124692] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:43:23 INFO - PID 6549 | __metrics Screen width/height:1600/1200 22:43:23 INFO - PID 6549 | colorDepth:24 22:43:23 INFO - PID 6549 | Browser inner width/height: 1024/689 22:43:23 INFO - PID 6549 | __metrics 22:43:24 ERROR - PID 6549 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 22:43:25 INFO - TEST-INFO | 6549: exit 0 22:43:25 INFO - Browser initialized. 22:43:25 INFO - Running cycle 1/1 for kraken test... 22:43:25 INFO - TEST-INFO | started process 6551 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpNFErRj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:43:27 INFO - PID 6551 | 2017-04-27 22:43:27.247 plugin-container[6552:124813] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463f, name = 'com.apple.tsm.portname' 22:43:27 INFO - PID 6551 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:43:27 INFO - PID 6551 | 2017-04-27 22:43:27.248 plugin-container[6552:124813] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 22:43:27 INFO - PID 6551 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:43:27 INFO - PID 6551 | 2017-04-27 22:43:27.248 plugin-container[6552:124813] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:43:37 INFO - PID 6551 | RSS: Main: 250978304 22:43:37 INFO - PID 6551 | 22:43:40 INFO - PID 6551 | Cycle 1(1): loaded http://localhost:57273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57273/tests/kraken/driver.html?testName=audio-beat-detection) 22:43:41 INFO - PID 6551 | RSS: Main: 259174400 22:43:41 INFO - PID 6551 | 22:43:44 INFO - PID 6551 | Cycle 1(1): loaded http://localhost:57273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57273/tests/kraken/driver.html?testName=audio-dft) 22:43:44 INFO - PID 6551 | RSS: Main: 266235904 22:43:44 INFO - PID 6551 | 22:43:48 INFO - PID 6551 | Cycle 1(1): loaded http://localhost:57273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57273/tests/kraken/driver.html?testName=audio-fft) 22:43:48 INFO - PID 6551 | RSS: Main: 263577600 22:43:48 INFO - PID 6551 | 22:43:50 INFO - PID 6551 | Cycle 1(1): loaded http://localhost:57273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57273/tests/kraken/driver.html?testName=audio-oscillator) 22:43:51 INFO - PID 6551 | RSS: Main: 264560640 22:43:51 INFO - PID 6551 | 22:43:53 INFO - PID 6551 | Cycle 1(1): loaded http://localhost:57273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:43:53 INFO - PID 6551 | RSS: Main: 266424320 22:43:53 INFO - PID 6551 | 22:43:56 INFO - PID 6551 | Cycle 1(1): loaded http://localhost:57273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57273/tests/kraken/driver.html?testName=imaging-darkroom) 22:43:57 INFO - PID 6551 | RSS: Main: 271679488 22:43:57 INFO - PID 6551 | 22:44:00 INFO - PID 6551 | Cycle 1(1): loaded http://localhost:57273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57273/tests/kraken/driver.html?testName=imaging-desaturate) 22:44:01 INFO - PID 6551 | RSS: Main: 272171008 22:44:01 INFO - PID 6551 | 22:44:04 INFO - PID 6551 | Cycle 1(1): loaded http://localhost:57273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57273/tests/kraken/driver.html?testName=json-parse-financial) 22:44:04 INFO - PID 6551 | RSS: Main: 272019456 22:44:04 INFO - PID 6551 | 22:44:06 INFO - PID 6551 | Cycle 1(1): loaded http://localhost:57273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:44:07 INFO - PID 6551 | RSS: Main: 271712256 22:44:07 INFO - PID 6551 | 22:44:08 INFO - PID 6551 | Cycle 1(1): loaded http://localhost:57273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57273/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:44:09 INFO - PID 6551 | RSS: Main: 271745024 22:44:09 INFO - PID 6551 | 22:44:11 INFO - PID 6551 | Cycle 1(1): loaded http://localhost:57273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:44:12 INFO - PID 6551 | RSS: Main: 271482880 22:44:12 INFO - PID 6551 | 22:44:15 INFO - PID 6551 | Cycle 1(1): loaded http://localhost:57273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:44:15 INFO - PID 6551 | RSS: Main: 271859712 22:44:15 INFO - PID 6551 | 22:44:19 INFO - PID 6551 | Cycle 1(1): loaded http://localhost:57273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:44:19 INFO - PID 6551 | RSS: Main: 271634432 22:44:19 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | Cycle 1(1): loaded http://localhost:57273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57273/tests/kraken/driver.html?testName=ai-astar) 22:44:21 INFO - PID 6551 | RSS: Main: 273108992 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | __start_tp_report 22:44:21 INFO - PID 6551 | _x_x_mozilla_page_load 22:44:21 INFO - PID 6551 | _x_x_mozilla_page_load_details 22:44:21 INFO - PID 6551 | |i|pagename|runs| 22:44:21 INFO - PID 6551 | |0;ai-astar;148;155;119;163;94;159;174;170;95;173 22:44:21 INFO - PID 6551 | |1;audio-beat-detection;155;111;110;152;109;115;113;123;166;122 22:44:21 INFO - PID 6551 | |2;audio-dft;197;216;214;231;200;198;199;197;202;204 22:44:21 INFO - PID 6551 | |3;audio-fft;83;58;57;82;61;74;88;73;77;67 22:44:21 INFO - PID 6551 | |4;audio-oscillator;87;71;83;71;75;87;84;70;70;90 22:44:21 INFO - PID 6551 | |5;imaging-gaussian-blur;89;91;91;90;92;135;100;91;92;91 22:44:21 INFO - PID 6551 | |6;imaging-darkroom;119;154;134;97;95;97;99;131;141;97 22:44:21 INFO - PID 6551 | |7;imaging-desaturate;93;95;92;90;94;92;92;92;92;90 22:44:21 INFO - PID 6551 | |8;json-parse-financial;52;49;51;54;51;54;52;47;49;51 22:44:21 INFO - PID 6551 | |9;json-stringify-tinderbox;58;65;87;85;63;64;57;57;58;61 22:44:21 INFO - PID 6551 | |10;stanford-crypto-aes;83;82;84;84;84;82;85;86;82;86 22:44:21 INFO - PID 6551 | |11;stanford-crypto-ccm;148;137;147;141;138;194;140;147;222;157 22:44:21 INFO - PID 6551 | |12;stanford-crypto-pbkdf2;184;192;191;188;188;184;187;191;194;247 22:44:21 INFO - PID 6551 | |13;stanford-crypto-sha256-iterative;64;104;94;72;70;72;70;69;71;70 22:44:21 INFO - PID 6551 | __end_tp_report 22:44:21 INFO - PID 6551 | __start_cc_report 22:44:21 INFO - PID 6551 | _x_x_mozilla_cycle_collect,1040 22:44:21 INFO - PID 6551 | __end_cc_report 22:44:21 INFO - PID 6551 | __startTimestamp1493358261746__endTimestamp 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | ------- Summary: start ------- 22:44:21 INFO - PID 6551 | Number of tests: 14 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | [#0] ai-astar Cycles:10 Average:145.00 Median:171.50 stddev:31.01 (18.1%) stddev-sans-first:32.87 22:44:21 INFO - PID 6551 | Values: 148.0 155.0 119.0 163.0 94.0 159.0 174.0 170.0 95.0 173.0 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | [#1] audio-beat-detection Cycles:10 Average:127.60 Median:122.50 stddev:21.54 (17.6%) stddev-sans-first:20.44 22:44:21 INFO - PID 6551 | Values: 155.0 111.0 110.0 152.0 109.0 115.0 113.0 123.0 166.0 122.0 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | [#2] audio-dft Cycles:10 Average:205.80 Median:203.00 stddev:11.15 (5.5%) stddev-sans-first:11.37 22:44:21 INFO - PID 6551 | Values: 197.0 216.0 214.0 231.0 200.0 198.0 199.0 197.0 202.0 204.0 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | [#3] audio-fft Cycles:10 Average:72.00 Median:75.50 stddev:10.92 (14.5%) stddev-sans-first:10.84 22:44:21 INFO - PID 6551 | Values: 83.0 58.0 57.0 82.0 61.0 74.0 88.0 73.0 77.0 67.0 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:83.50 stddev:8.13 (9.7%) stddev-sans-first:8.07 22:44:21 INFO - PID 6551 | Values: 87.0 71.0 83.0 71.0 75.0 87.0 84.0 70.0 70.0 90.0 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | [#5] imaging-gaussian-blur Cycles:10 Average:96.20 Median:91.00 stddev:13.96 (15.3%) stddev-sans-first:14.56 22:44:21 INFO - PID 6551 | Values: 89.0 91.0 91.0 90.0 92.0 135.0 100.0 91.0 92.0 91.0 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | [#6] imaging-darkroom Cycles:10 Average:116.40 Median:96.00 stddev:22.21 (23.1%) stddev-sans-first:23.53 22:44:21 INFO - PID 6551 | Values: 119.0 154.0 134.0 97.0 95.0 97.0 99.0 131.0 141.0 97.0 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | [#7] imaging-desaturate Cycles:10 Average:92.20 Median:92.00 stddev:1.55 (1.7%) stddev-sans-first:1.62 22:44:21 INFO - PID 6551 | Values: 93.0 95.0 92.0 90.0 94.0 92.0 92.0 92.0 92.0 90.0 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.50 stddev:2.21 (4.3%) stddev-sans-first:2.32 22:44:21 INFO - PID 6551 | Values: 52.0 49.0 51.0 54.0 51.0 54.0 52.0 47.0 49.0 51.0 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:63.50 stddev:11.20 (17.6%) stddev-sans-first:11.54 22:44:21 INFO - PID 6551 | Values: 58.0 65.0 87.0 85.0 63.0 64.0 57.0 57.0 58.0 61.0 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.00 stddev:1.55 (1.8%) stddev-sans-first:1.62 22:44:21 INFO - PID 6551 | Values: 83.0 82.0 84.0 84.0 84.0 82.0 85.0 86.0 82.0 86.0 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | [#11] stanford-crypto-ccm Cycles:10 Average:157.10 Median:147.50 stddev:28.25 (19.2%) stddev-sans-first:29.77 22:44:21 INFO - PID 6551 | Values: 148.0 137.0 147.0 141.0 138.0 194.0 140.0 147.0 222.0 157.0 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.60 Median:191.00 stddev:18.70 (9.8%) stddev-sans-first:19.44 22:44:21 INFO - PID 6551 | Values: 184.0 192.0 191.0 188.0 188.0 184.0 187.0 191.0 194.0 247.0 22:44:21 INFO - PID 6551 | 22:44:21 INFO - PID 6551 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.60 Median:70.50 stddev:12.76 (18.1%) stddev-sans-first:12.82 22:44:21 INFO - PID 6551 | Values: 64.0 104.0 94.0 72.0 70.0 72.0 70.0 69.0 71.0 70.0 22:44:21 INFO - PID 6551 | -------- Summary: end -------- 22:44:21 INFO - PID 6551 | 22:44:22 INFO - TEST-INFO | 6551: exit 0 22:44:22 INFO - TEST-OK | kraken | took 61658ms 22:44:22 INFO - SUITE-END | took 1177s 22:44:22 INFO - Completed test suite (00:19:37) 22:44:22 INFO - javascript benchmark 22:44:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7914.606713087881, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3582.3730847101765, 3594.335048016117, 3611.241639255756, 3623.166671665516, 3638.3814751492246, 5253.7373131343375, 5343.85107201951, 6131.601445906019, 6133.080037994303, 6242.563020588561, 6158.230221222348, 6162.168107305512, 6212.192414986045, 6243.094751261065, 6254.7498100075945, 5319.148936170213, 5335.013022590813, 5342.893142137155, 5405.135178371462, 5463.754131064112, 5429.755660995245, 5548.91676624851, 5625.099984002561, 5645.915311270334, 5648.774049038034, 5414.214938833887, 5528.612997090206, 5560.443955604427, 5571.164325351209, 5603.7478313475995, 4241.34259189823, 5482.972585137089, 5496.148097044925, 5498.725063746837, 5532.170174473821, 4340.891477713082, 5457.154141108134, 5492.093804522249, 5495.917561236544, 5539.944600553983, 6055.455009049207, 6239.906401403982, 6268.373162683741, 6275.560710750249, 6297.96851015742, 6444.613323200623, 6507.8698426031215, 6629.966850165766, 6658.167729033871, 6702.664866756642, 6801.047853300542, 6831.941049137392, 6838.829029274259, 6840.931590684079, 6849.589024658537, 6305.621662700252, 6345.270293916166, 6367.904481432783, 6368.1084648148835, 6374.884395230909, 6648.235452922879, 6796.388325050671, 6876.381125698711, 6885.621290829053, 6978.441724662015, 5703.657780533182, 6396.5522413431545, 6589.077529145923, 6651.800445986628, 6662.733490660319, 5174.327337446108, 5369.543588794918, 6638.302978187317, 6754.966225168842, 6761.01965215041, 5634.85912852182, 6518.185226846645, 6682.966585167043, 6689.6989635466525, 6691.364320389653, 6436.645984470899, 6566.343365663491, 6677.76627818024, 6695.732170713214, 6703.664816759143, 4539.319102134707, 5219.2953951216095, 5297.152455607084, 5310.522053015247, 5379.946200537945, 4745.738984355894, 4770.880727981758, 4857.028594281158, 4872.43966943806, 4881.902361952741, 6168.753249870045, 6236.158118653923, 6367.968160159169, 6374.9681251594375, 6380.425761681378, 6561.1142495763415, 6757.526973111835, 6788.9660551696925, 6837.555558888656, 6844.384005439534, 5070.7464626768515, 6142.78500252489, 6266.937330626726, 6314.747410103636, 6387.552871299057, 5923.052311630118, 5942.376050514634, 5974.581779275409, 6012.549058820606, 6014.278286605548, 6956.79129626112, 6996.6151861648095, 7040.331168538981, 7053.682584283617, 7074.752383666547, 6503.902441463477, 6591.34086591337, 6727.360900714424, 6834.282400347892, 6836.453083753188, 6919.515633905578, 7013.789586312419, 7042.577445353295, 7044.647767611701, 7046.154461464657, 6579.374959378852, 6679.632620205935, 6681.632510211985, 6696.497762667722, 6711.161104861892, 6493.675316234264, 6601.042848786995, 6736.191657000995, 6803.829904252433, 6827.146606674166, 5453.427390123979, 5466.2620546227345, 6454.225492940877, 6595.175603049619, 6608.70260838273, 6300.495960323072, 6455.677216139268, 6575.2767195607485, 6603.339666033358, 6612.272650008592, 4703.788329525111, 6125.448709616156, 6279.277883043508, 6283.874322513616, 6291.622502649856, 6344.968275158594, 6591.373819487141, 6594.571352862049, 6600.339966003361, 6616.073749674953, 6312.43188113072, 6326.019466982441, 6351.523635727432, 6373.776917807854, 6433.067205255118], "value": 6063.434037133778, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [754.8678981178294, 759.780552213697, 810.813512892035, 1200.0579545057133, 1268.448225022116, 36835.447468287966, 37636.99999999996, 37734.622653773455, 37818.8109059455, 37881.62118378816, 38145.618543814555, 38492.80753596228, 38495.0, 38528.844134675935, 38590.42114368287, 7099.787006389816, 7893.171217022216, 8072.071711753136, 8279.296259817922, 8499.532525711084, 8511.787205319886, 8640.827183456327, 8641.30869530436, 8644.697435589756, 8650.048494665612, 8578.44240124393, 8595.226429621332, 8629.913700863006, 8648.481091134521, 8650.0, 7444.622744792204, 8154.06228283749, 8444.282236009947, 8476.49141051539, 8578.313734901192, 7539.0, 7928.563928983904, 8316.83366332676, 8375.0, 8387.0, 36815.0, 36980.89057328284, 37057.25885482275, 37135.0, 37367.4394884077, 3592.550931133608, 3620.6922411595046, 3623.7825730456257, 3624.4925710400566, 3628.727845411578, 29851.70148298511, 30400.8479957601, 30437.0, 30489.0, 30493.69506304931, 3045.558394032853, 3056.5262384330463, 3078.8614512347, 3089.4130115278026, 3099.891503797356, 182860.17139828563, 240296.0, 247592.0, 261155.69422152766, 296766.0, 37461.0, 46457.76771116123, 46861.828454288916, 46992.530074698814, 47175.0, 48795.0, 49124.508754912706, 49223.26165107526, 49622.25566616504, 49940.0, 13485.0, 13582.728345433035, 13583.660408489866, 13617.659558511117, 13623.523176688768, 10982.670519884416, 11076.390798505998, 11144.442777861075, 11156.386398747984, 11170.385628790333, 13352.46590136403, 13356.73286534264, 13419.530316439073, 13433.462661493626, 13487.932560337333, 5813.244278243801, 5891.617044892068, 5937.228160339128, 5937.732802023919, 5957.27239100656, 964.5563041001218, 995.8058178655093, 1012.9341592796445, 1038.2109596706407, 1061.9645845300925, 20404.693929591067, 22900.083996640282, 24199.75800241975, 24226.273211803673, 24291.149809756575, 1111.7109551516664, 1114.8049091409134, 1130.423484023154, 1150.4822829726656, 1154.2324354304462, 10413.531391087268, 12249.142560020891, 12430.067744919168, 12431.62705118848, 12443.440045197987, 293120.0, 295770.0, 298000.0, 298584.0, 298770.0, 42029.528966486025, 48965.02069958652, 52671.73664131655, 52752.94494110096, 52898.73550632222, 1487.9181645009628, 1519.5061604978425, 1563.2262030295076, 1563.7654351847314, 1564.241342948669, 20230.494237643587, 23847.52304953926, 24007.759922400553, 24014.15950441726, 24020.759792401852, 31094.445277736475, 37491.81254093712, 37568.0, 37598.0, 37670.434943476415, 248979.0, 269534.0, 270493.0, 270527.0, 271341.0, 24969.87515062486, 33595.664043359255, 34250.82874585611, 34357.656423435445, 34496.827515862264, 36924.630753692116, 40710.59289407068, 43364.78317608392, 44428.777856110515, 45270.0, 28642.427151457272, 29611.0, 30379.0, 31049.0, 31499.685003149676, 22511.5497690042, 27974.881004759583, 30589.77640894339, 31359.37281254408, 31496.37007259888, 35164.12089697778, 35786.28427431489, 36147.0, 36506.0, 36925.815370922974, 23808.761912380654, 28507.00225492098, 31129.533057004617, 31274.53088203724, 31490.527642085846, 23310.83445827668, 24151.0, 24395.51208975846, 24634.753652463245, 24649.876750616848], "value": 17054.18860259922, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.1964206397135, 442.2680463832356, 442.32766195383016, 457.59502839986624, 459.52209701910016, 19192.23231070757, 20945.858121281795, 21610.0, 21649.242276520326, 21822.236221732244, 21769.346919592437, 22935.19726809562, 23256.767432325672, 23532.17637382692, 23604.173853915116, 5095.0574143783515, 6165.599236049654, 6390.265119511269, 6409.583377080475, 6413.486921046305, 6115.082737589352, 6296.842578935541, 6323.873522529547, 6329.683515824214, 6353.555251132422, 6197.752089916398, 6273.090401891723, 6286.434220920116, 6483.870322593545, 6919.619420931846, 6860.211075726305, 6955.547889387173, 6988.895166572505, 7009.0, 7033.226345102034, 5555.250041244421, 5827.184194212814, 6700.894352431892, 6785.762498312535, 6820.6930688119155, 21365.893170534047, 28048.859755701294, 29460.37967911764, 29685.703142968727, 29689.40621187564, 2021.2521367094123, 2318.8840557972035, 2339.777721116491, 2363.9645405318934, 2368.62102063669, 18408.81591184098, 18919.905400472908, 19414.83510989331, 19724.80275197244, 19841.206351745914, 2243.5288589396246, 2296.9310920672406, 2357.4695693469002, 2357.8938947747392, 2358.941026474352, 152923.0, 167123.0, 168847.99999999878, 169241.0, 170804.0, 20987.790122098584, 24776.752232477444, 24874.5025099497, 25319.987200512143, 26452.074177403698, 40756.38865417021, 41280.38079428811, 42254.36618450726, 42269.0, 42292.46245225296, 6906.309369063053, 8943.955280223558, 9310.162085412345, 9436.764080898032, 9440.10319019692, 6528.445082167974, 7750.534967901944, 8662.783430414163, 8821.735347939572, 9052.773680658036, 6632.4694024478895, 8111.351091912633, 8230.135835737281, 8232.753017409488, 8300.169983001651, 2295.3992258888593, 2300.367398965298, 2303.285981345755, 2314.884255787204, 2466.938326541815, 960.4669408478222, 972.4165500699523, 985.0691096913348, 1003.1623594298842, 1028.6039874648166, 21404.678929816066, 21719.891400542896, 21778.89110554437, 21788.455288617595, 21848.0, 795.5306369242174, 990.4898977026837, 1016.3292227130058, 1022.4989755020135, 1038.745507350704, 10993.395363255098, 11270.661880143609, 11343.262687925258, 11369.204155709022, 11370.431478426046, 397697.0, 516873.0, 523201.0, 535609.0, 537820.0, 123207.0, 130896.0, 134823.32588337193, 139120.30439847737, 140621.0, 1182.4028865422906, 1212.1393810394718, 1234.1299383934304, 1237.9504819807287, 1238.8823061809114, 21474.71151730901, 21821.781782181974, 22073.668894966908, 22762.772372276726, 22845.31464056081, 58007.709961449924, 58959.231223063376, 59102.70448647729, 59252.70373648104, 59278.0, 78755.0, 79954.60022700082, 81255.59372203102, 82102.58948705457, 82435.0, 8661.13388661129, 8847.0, 8935.865962010444, 9015.819683606422, 9211.677591284273, 10799.622013229498, 13368.529461759394, 14074.507392241223, 14112.223827689575, 14513.05665131763, 10177.982201779763, 11836.171467997157, 11989.160758747154, 12024.398780061138, 12065.215760975509, 11422.714432139262, 11597.07223422141, 11639.47622356979, 11882.465289062186, 11953.924146826826, 9520.143187113194, 11955.342456164994, 12206.20659657155, 12220.755584888075, 12398.25610463375, 8766.167214114648, 8935.910640893508, 9077.909220907706, 9119.589618467318, 9206.309526785652, 11671.88328116708, 11903.583374582195, 11943.940280298542, 12003.399830008639, 12092.516299347928], "value": 11218.080202309466, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1244.4400019991003, 1478.3938585180072, 1495.7382458069835, 1511.448321362703, 1515.901466404684, 11476.426178691063, 11713.0, 11724.23792453491, 11967.880321196797, 12078.033757299418, 11809.232399894001, 11966.940165299184, 11968.222065565733, 11968.222065565733, 11974.7605047899, 2505.674262345897, 2542.084849454195, 2545.350935511442, 2554.5274124286975, 2554.897804087834, 1986.3941497843152, 2366.95266094678, 2423.75762423758, 2430.4774473488196, 2430.6597076409316, 2458.8032957363457, 2512.6984761828608, 2536.4166241764406, 2537.7208507064206, 2538.213153922281, 2454.275988583372, 2481.565725997943, 2496.0015993602706, 2512.472380800034, 2520.8361456505268, 2027.2093883385492, 2440.4021014851296, 2489.825712200147, 2505.837120587174, 2531.1520640585427, 11582.536698532049, 11686.357250351228, 11763.058955283557, 11781.705457363549, 11786.528538858436, 6547.770828020996, 6807.3873351398115, 6808.557443766189, 6816.659167041628, 6821.590704557742, 3865.4781604483314, 4173.3114036184, 4203.43253660758, 4214.91570168598, 4253.638440732541, 1165.4697112813735, 1172.2497601535024, 1196.6888608961733, 1236.4312416288428, 1237.6287113865803, 16253.59366015859, 16673.416430424877, 16820.57948551296, 16837.663246734995, 16847.07341096239, 5440.401555828856, 5575.721213939287, 5689.658620482702, 5732.0255556555485, 5740.024195886709, 11806.350650714294, 11891.821622675667, 11901.226420282654, 11907.464164112636, 11908.047356211482, 6484.448821850196, 6520.380563846427, 6534.477241820643, 6545.738170473128, 6554.377334153248, 5127.487251274917, 5196.856691527858, 6128.571000030044, 6182.69086545671, 6223.719932603044, 3830.597787232356, 4057.6753859691153, 4372.759498227628, 4438.112377524508, 4480.708753930984, 1258.7671280813079, 1276.8212450256972, 1291.180100636103, 1291.5479582146174, 1294.9805752913715, 800.6837299266757, 810.3760104719333, 826.8222332198607, 836.2515548583983, 859.7248880358351, 5311.0, 5493.505584497414, 6125.908111378333, 6217.782377616762, 6383.138276332728, 580.2543731305252, 586.8767558812697, 615.2217444932164, 616.6669998201011, 622.1259130921063, 4098.877033688994, 4199.265128602482, 4310.719803212781, 4313.417688611998, 4364.563543645673, 13702.177869327872, 14402.711945761026, 14853.0, 15246.46637367709, 15388.61528461775, 14685.265736713121, 14784.556463306119, 15010.0, 15119.319630616428, 15147.772783408258, 7430.777076687708, 7948.608993426127, 8224.259816616413, 8595.140485951355, 8672.56637168139, 5970.850728731816, 6442.033694945796, 6637.568558043712, 7135.143782745896, 7227.205007449072, 9660.903390966281, 12296.077794165294, 13081.0, 13286.667833304246, 13650.658733531742, 13571.660708482366, 13684.794728079285, 13879.722405552035, 13965.0, 14319.926005549418, 9103.317251206054, 9298.163165314883, 9545.093216144454, 9545.52272386364, 9574.04259574065, 11026.062784663394, 11173.944130279297, 11242.94378528102, 11259.493322800658, 11304.43477826089, 9127.08729127082, 9128.863067053866, 9249.028851970716, 9323.766905827137, 9359.766005849908, 9026.819463610822, 9028.909710902806, 9099.363044586815, 9107.453552786854, 9148.542572871464, 8112.553809540532, 8191.262786349257, 8295.585220738818, 8324.376746534368, 8411.285040771603, 5120.718360490208, 6050.667213303311, 6075.119107729266, 6259.217597800275, 6294.716737746904, 6125.479334256638, 6155.230596175478, 6179.660118693515, 6203.0, 6304.243490780952], "value": 5295.9471204560605, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.58833989330867, 404.0968435546553, 406.5284270246515, 406.6238729175513, 419.0508498251459, 5750.539956803462, 6251.187345645066, 6312.295680166352, 6331.873362532747, 6387.201599800025, 4603.953960460402, 5759.625624334415, 6151.538634602401, 6186.907196392047, 6206.286277078138, 4674.883127921812, 4693.647976401775, 4703.553162449579, 4717.528247175289, 4726.409198850144, 4626.8380606678775, 4721.409823772028, 4734.147853386389, 4749.050189962004, 4749.643776716743, 4597.494275629695, 4659.650526210531, 4664.580187783099, 4689.484156742756, 4696.882577935545, 3996.700247481436, 4181.498220213593, 4563.657725670571, 4671.229247174212, 4697.694649847749, 3507.5089487471773, 4046.878593642166, 4108.36320370343, 4170.582941705836, 4171.520275168334, 4950.960298337353, 6000.599760095996, 6078.3921607838865, 6078.665673387962, 6081.574289799716, 5395.7032363220005, 5561.77752889884, 5568.972155139199, 5598.916016259759, 5654.46282603152, 5415.810446634349, 6127.816165515042, 6227.408396202353, 6385.776497822554, 6611.801645950629, 4759.0, 4771.614192903548, 4793.32893394925, 4796.616270698336, 4796.640251981081, 5818.679972601505, 6130.63216207029, 6335.904961425582, 6926.2034865990045, 7132.215456299803, 3425.989333146728, 4420.027593929331, 4566.794494247767, 4607.986243026528, 4627.768611569475, 4095.508538975342, 4128.772917489507, 4695.882602934954, 4725.716457012591, 4742.217534106902, 5378.811741589026, 5438.619296649275, 5458.727063646802, 5466.3440387153005, 5521.254630624894, 5801.88396232073, 6048.6673232971725, 6552.868942621121, 6610.8347291318105, 6707.926731722999, 1351.8377794664702, 1702.6254224070685, 1723.7069698151345, 1727.2745446912327, 1769.8938063716218, 1014.0721240065405, 1159.7854396936593, 1192.5706745571588, 1200.351810022578, 1208.2810727617054, 5686.09022556389, 6019.879602407927, 6267.247930248308, 6281.7173227204885, 6286.968565157145, 6579.80260592183, 6828.317168283232, 6829.214640316427, 6852.383285504328, 6864.862702745917, 5199.688018718889, 5828.88342233153, 6274.874502509924, 6280.937190628127, 6300.936990630126, 2984.8358340291493, 3002.6847181045678, 3132.8903488377796, 3346.380919529895, 3581.391163502211, 5964.194833697482, 5989.341172471025, 6146.385361463907, 7140.714371425189, 7430.108386993487, 5906.497947674496, 6270.529710271748, 6783.287754785775, 7124.679389427488, 7134.0, 7056.717731290793, 7063.0, 7118.394936430358, 7130.393916517154, 7173.820654483679, 5089.872753181052, 6095.847603809762, 6790.049393085071, 6847.486438517031, 6872.518923675295, 6169.56813023084, 6689.76585819494, 6797.116374871235, 6804.183497980274, 6820.693068811816, 6810.0, 7039.296070392919, 7139.178994415501, 7168.784936451915, 7179.892301615376, 3458.4466485362223, 3627.4558816177782, 4175.791210439526, 4401.251787196248, 4407.449068866392, 5116.769745361393, 5456.263404440349, 6576.868462630625, 6842.05531385634, 6882.969523990535, 4763.809047738066, 5930.11048342752, 5962.612430192024, 6515.446187074152, 6558.901616475661, 5286.894262114659, 6299.393654618043, 6601.3728695773825, 6609.4712423007, 6629.5359324846795, 6226.875462490634, 6250.687355655182, 6340.302566717565, 6439.066335381437, 6526.24948130952, 5791.333996590446, 6538.509611779041, 6675.632840193836, 6690.264070952288, 6722.9663851680425, 5486.31421072366, 6377.681115944277, 6410.839729006812, 6520.347965203442, 6551.246606640107], "value": 4814.344403388909, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [846.7798372423168, 849.698357083235, 862.6721845698634, 876.0582373948007, 888.6934007767322, 15247.771283430744, 15706.842931570682, 15758.842411575895, 17898.910505447486, 18419.815801841978, 17627.471175864746, 17643.73534396985, 17850.64298714025, 17858.910705446455, 18022.27910883563, 976.4336684722862, 980.0640388429033, 992.0575453319375, 995.2684776689127, 995.3729150635126, 995.248587316575, 999.9050090241452, 1009.9798004039915, 1010.9486134420195, 1013.5591017907198, 1038.7351225437503, 1058.2380685906173, 1061.0185578340042, 1062.2723434447428, 1062.4315990944847, 1057.5981127171665, 1057.6351158850262, 1058.3491152940962, 1058.6718117383637, 1062.54310646422, 748.094805285605, 764.9120351159577, 766.4404984361447, 779.5478622398995, 779.6062988190984, 15580.454684086002, 15597.220138993005, 15686.058836469734, 15700.842991570053, 15702.0, 15993.720502359783, 16314.0, 16782.993020418813, 17275.481735547954, 17307.307707691678, 11316.490757915913, 13960.650983725387, 14000.649983750385, 14084.366203520865, 14094.154350738989, 2808.761255293282, 2997.6103106596, 3001.5347621118544, 3242.221866751986, 3249.46383846665, 112429.87570124275, 125213.0, 128612.0, 128804.067938981, 129391.0, 11264.211505194558, 13111.0, 13410.731785364238, 13426.932865335611, 13438.328083595781, 20962.47593810167, 24138.87930560371, 25166.119185828407, 25574.360640984123, 25710.48579028409, 6316.968415157987, 6555.672216389162, 7789.3768498519985, 7827.765167044998, 7890.408219383569, 3834.1948190880225, 3898.064464528493, 3898.5321761388814, 3905.6680182184264, 3936.3308237599676, 4297.613214810682, 4300.61294483498, 4377.124575084996, 4428.756418397019, 4437.955620443754, 936.9578368973413, 961.0293603460556, 969.7721035556639, 972.4845831709206, 972.9708108756607, 637.7704026550438, 669.534673401981, 671.9059331693567, 680.4182424027484, 693.2824526614929, 11304.095672346193, 11815.818418158287, 11848.822267665992, 12081.697957551132, 12095.57665481704, 658.733213048716, 658.8023592922104, 660.8579155481594, 663.1213641924469, 692.9826754331082, 5351.197320401893, 5494.917576236359, 5547.389787123413, 5571.749271282802, 5668.489835914787, 95683.12594673873, 110138.34792478119, 110140.449297753, 111186.44406777914, 112936.0, 73439.0, 82112.0, 84977.15022849815, 86533.70199446888, 87549.56225218833, 75300.62349688326, 77147.0, 77455.0, 78746.21253787504, 79385.60307198427, 9306.813863722824, 12622.179558328678, 12803.871961280269, 12805.679858003252, 12859.16415432994, 28318.57522137211, 38286.6171338283, 38707.225855483295, 38831.8350449487, 39881.60118398779, 58628.0, 66164.66917665381, 67203.65592688217, 67544.66227668831, 68248.6587567059, 15961.281742321391, 16388.295931171222, 16641.0, 16652.167391630126, 16726.0, 16415.589610259838, 17869.910650446665, 18122.184501697193, 18190.36333728366, 18260.726089108408, 16706.164691765607, 16740.41408550695, 16758.0, 16935.91532042332, 17034.574135646708, 16115.0, 16209.918950405172, 16759.581010474347, 16848.15759212059, 16857.66284674275, 15851.841481585498, 15975.680486389974, 16423.17884105766, 16570.83429165693, 16737.497875063767, 10003.949980250052, 11695.76608467843, 12019.75960480768, 12075.215111018108, 12289.44697488633, 12071.939640302093, 12149.0, 12726.68183295388, 12780.297083660487, 12788.232706037668], "value": 8309.90277318071, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1561.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 155.0, 119.0, 163.0, 94.0, 159.0, 174.0, 170.0, 95.0, 173.0], "value": 145.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 111.0, 110.0, 152.0, 109.0, 115.0, 113.0, 123.0, 166.0, 122.0], "value": 127.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 216.0, 214.0, 231.0, 200.0, 198.0, 199.0, 197.0, 202.0, 204.0], "value": 205.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 57.0, 82.0, 61.0, 74.0, 88.0, 73.0, 77.0, 67.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 83.0, 71.0, 75.0, 87.0, 84.0, 70.0, 70.0, 90.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 91.0, 90.0, 92.0, 135.0, 100.0, 91.0, 92.0, 91.0], "value": 96.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 154.0, 134.0, 97.0, 95.0, 97.0, 99.0, 131.0, 141.0, 97.0], "value": 116.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 92.0, 90.0, 94.0, 92.0, 92.0, 92.0, 92.0, 90.0], "value": 92.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 51.0, 54.0, 51.0, 54.0, 52.0, 47.0, 49.0, 51.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 65.0, 87.0, 85.0, 63.0, 64.0, 57.0, 57.0, 58.0, 61.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 84.0, 84.0, 84.0, 82.0, 85.0, 86.0, 82.0, 86.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 137.0, 147.0, 141.0, 138.0, 194.0, 140.0, 147.0, 222.0, 157.0], "value": 157.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 192.0, 191.0, 188.0, 188.0, 184.0, 187.0, 191.0, 194.0, 247.0], "value": 194.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 104.0, 94.0, 72.0, 70.0, 72.0, 70.0, 69.0, 71.0, 70.0], "value": 75.6, "unit": "score"}]}]} 22:44:22 INFO - Return code: 0 22:44:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:44:23 ERROR - # TBPL SUCCESS # 22:44:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:44:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:44:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:44:23 INFO - 2017-04-27 22:44:23.066 screenresolution[6556:125203] starting screenresolution argv=screenresolution get 22:44:23 INFO - 2017-04-27 22:44:23.069 screenresolution[6556:125203] Display 0: 1600x1200x32@60 22:44:23 INFO - 2017-04-27 22:44:23.075 screenresolution[6557:125206] starting screenresolution argv=screenresolution list 22:44:23 INFO - Available Modes on Display 0 22:44:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:44:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:44:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:44:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:44:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:44:23 INFO - Intel Iris: 22:44:23 INFO - Chipset Model: Intel Iris 22:44:23 INFO - Type: GPU 22:44:23 INFO - Bus: Built-In 22:44:23 INFO - VRAM (Dynamic, Max): 1536 MB 22:44:23 INFO - Vendor: Intel (0x8086) 22:44:23 INFO - Device ID: 0x0a2e 22:44:23 INFO - Revision ID: 0x0009 22:44:23 INFO - Displays: 22:44:23 INFO - Display: 22:44:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:44:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:44:23 INFO - Display Serial Number: 1600x1200 60 22:44:23 INFO - Main Display: Yes 22:44:23 INFO - Mirror: Off 22:44:23 INFO - Online: Yes 22:44:23 INFO - Rotation: Supported 22:44:23 INFO - Return code: 0 22:44:23 INFO - Running post-action listener: _resource_record_post_action 22:44:23 INFO - [mozharness: 2017-04-28 05:44:23.324806Z] Finished run-tests step (success) 22:44:23 INFO - Running post-run listener: _resource_record_post_run 22:44:23 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 1522688; Write bytes: 512932864; Read time: 22; Write time: 1799 22:44:23 INFO - TinderboxPrint: CPU usage
54.0% 22:44:23 INFO - TinderboxPrint: I/O read bytes / time
1,522,688 / 22 22:44:23 INFO - TinderboxPrint: I/O write bytes / time
512,932,864 / 1,799 22:44:23 INFO - TinderboxPrint: CPU idle
2,204.2 (46.0%) 22:44:23 INFO - TinderboxPrint: CPU system
966.6 (20.2%) 22:44:23 INFO - TinderboxPrint: CPU user
1,622.8 (33.9%) 22:44:23 INFO - TinderboxPrint: Swap in / out
211,345,408 / 0 22:44:23 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206074368; Write bytes: 206792704; Read time: 15988; Write time: 522 22:44:23 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1485824; Write bytes: 299025408; Read time: 22; Write time: 1251 22:44:23 INFO - Running post-run listener: _upload_blobber_files 22:44:23 INFO - Blob upload gear active. 22:44:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:44:23 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 22:44:23 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:44:23 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:44:24 INFO - (blobuploader) - INFO - Open directory for files ... 22:44:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:44:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:44:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:25 INFO - (blobuploader) - INFO - Done attempting. 22:44:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:44:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:44:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:26 INFO - (blobuploader) - INFO - Done attempting. 22:44:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:44:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:44:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:27 INFO - (blobuploader) - INFO - Done attempting. 22:44:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:44:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:44:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:28 INFO - (blobuploader) - INFO - Done attempting. 22:44:28 INFO - (blobuploader) - INFO - Iteration through files over. 22:44:28 INFO - Return code: 0 22:44:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:44:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:44:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/97af0ff6dafa68cd7246754e13493460123245bd3f4f13d08e9c74c03caed73c12b6332cc2f56e7e45c0d8be88abf933dfbcb877494bba89a78bdf6b674d358b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d64f502e36bcb9dd879cf04e199bf744a02c0640bb62aa5389778973e3dc73b8c7277b7adef2292411391a41391b76b56f80cd0eebf7d613c882091bfb32d99f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6cfbe4fec68b37b81e6a0ddc8cf53b00d79b4498a255ac04fdba73639dbca58790db72f7314dcbebd1775fa7d41b7774a3a8da6c5eeb0b4abdab910c8dc8f17f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/34c8cec65951536214eb73b5b840762742c83b5613c18da967cde49199b81823ccc725d6e4495a32eb1bce99c589dd77e0f09ae5062c1dbbd72dabcb1fb1231e"} 22:44:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:44:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:44:28 INFO - Contents: 22:44:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/97af0ff6dafa68cd7246754e13493460123245bd3f4f13d08e9c74c03caed73c12b6332cc2f56e7e45c0d8be88abf933dfbcb877494bba89a78bdf6b674d358b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d64f502e36bcb9dd879cf04e199bf744a02c0640bb62aa5389778973e3dc73b8c7277b7adef2292411391a41391b76b56f80cd0eebf7d613c882091bfb32d99f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6cfbe4fec68b37b81e6a0ddc8cf53b00d79b4498a255ac04fdba73639dbca58790db72f7314dcbebd1775fa7d41b7774a3a8da6c5eeb0b4abdab910c8dc8f17f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/34c8cec65951536214eb73b5b840762742c83b5613c18da967cde49199b81823ccc725d6e4495a32eb1bce99c589dd77e0f09ae5062c1dbbd72dabcb1fb1231e"} 22:44:28 INFO - Running post-run listener: copy_logs_to_upload_dir 22:44:28 INFO - Copying logs to upload dir... 22:44:28 INFO - mkdir: /builds/slave/test/build/upload/logs 22:44:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.736961 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-04-27 22:44:29.018534) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-27 22:44:29.021939) ========= 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.l2F1H5DzU1/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.ARc2RGdXtM/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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/Pine/sha512/97af0ff6dafa68cd7246754e13493460123245bd3f4f13d08e9c74c03caed73c12b6332cc2f56e7e45c0d8be88abf933dfbcb877494bba89a78bdf6b674d358b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d64f502e36bcb9dd879cf04e199bf744a02c0640bb62aa5389778973e3dc73b8c7277b7adef2292411391a41391b76b56f80cd0eebf7d613c882091bfb32d99f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6cfbe4fec68b37b81e6a0ddc8cf53b00d79b4498a255ac04fdba73639dbca58790db72f7314dcbebd1775fa7d41b7774a3a8da6c5eeb0b4abdab910c8dc8f17f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/34c8cec65951536214eb73b5b840762742c83b5613c18da967cde49199b81823ccc725d6e4495a32eb1bce99c589dd77e0f09ae5062c1dbbd72dabcb1fb1231e"} build_url:https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011561 build_url: 'https://queue.taskcluster.net/v1/task/JVLOEHCDTOmnmYo7IHuNew/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/97af0ff6dafa68cd7246754e13493460123245bd3f4f13d08e9c74c03caed73c12b6332cc2f56e7e45c0d8be88abf933dfbcb877494bba89a78bdf6b674d358b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d64f502e36bcb9dd879cf04e199bf744a02c0640bb62aa5389778973e3dc73b8c7277b7adef2292411391a41391b76b56f80cd0eebf7d613c882091bfb32d99f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6cfbe4fec68b37b81e6a0ddc8cf53b00d79b4498a255ac04fdba73639dbca58790db72f7314dcbebd1775fa7d41b7774a3a8da6c5eeb0b4abdab910c8dc8f17f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/34c8cec65951536214eb73b5b840762742c83b5613c18da967cde49199b81823ccc725d6e4495a32eb1bce99c589dd77e0f09ae5062c1dbbd72dabcb1fb1231e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-27 22:44:29.060874) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 22:44:29.061204) ========= 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.l2F1H5DzU1/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.ARc2RGdXtM/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.005173 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 22:44:29.110606) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-27 22:44:29.110926) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-27 22:44:29.111247) ========= ========= Total master_lag: 0.19 =========