builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0003 starttime: 1494218284.24 results: success (0) buildid: 20170507201733 builduid: ce63e0c2c032488ba6e57e8223420a16 revision: c3e5497cff1c995821b1c9320fa71f1ef9a8c30e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-07 21:38:04.244729) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-07 21:38:04.245159) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-07 21:38:04.245493) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-07 21:38:04.279707) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 21:38:04.280134) ========= 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.vFNJdw3uDM/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.rvKdYTRg9U/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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-05-07 21:38:04-- 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% 19.9M=0.001s 2017-05-07 21:38:04 (19.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.352864 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 21:38:04.658423) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 21:38:04.658735) ========= 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.vFNJdw3uDM/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.rvKdYTRg9U/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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.029061 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 21:38:04.710583) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-07 21:38:04.710932) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c3e5497cff1c995821b1c9320fa71f1ef9a8c30e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c3e5497cff1c995821b1c9320fa71f1ef9a8c30e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vFNJdw3uDM/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.rvKdYTRg9U/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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-05-07 21:38:04,824 truncating revision to first 12 chars 2017-05-07 21:38:04,824 Setting DEBUG logging. 2017-05-07 21:38:04,824 attempt 1/10 2017-05-07 21:38:04,824 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c3e5497cff1c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-07 21:38:05,515 unpacking tar archive at: mozilla-central-c3e5497cff1c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.075309 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-07 21:38:05.810860) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-07 21:38:05.811431) ========= script_repo_revision: c3e5497cff1c995821b1c9320fa71f1ef9a8c30e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-07 21:38:05.812174) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-07 21:38:05.812642) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-07 21:38:05.833900) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-07 21:38:05.834313) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vFNJdw3uDM/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.rvKdYTRg9U/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 21:38:06 INFO - MultiFileLogger online at 20170507 21:38:06 in /builds/slave/test 21:38:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:38:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:38:06 INFO - {'append_to_log': False, 21:38:06 INFO - 'base_work_dir': '/builds/slave/test', 21:38:06 INFO - 'blob_upload_branch': 'Firefox', 21:38:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:38:06 INFO - 'branch': 'Firefox', 21:38:06 INFO - 'buildbot_json_path': 'buildprops.json', 21:38:06 INFO - 'config_files': ('talos/mac_config.py',), 21:38:06 INFO - 'default_actions': ('clobber', 21:38:06 INFO - 'read-buildbot-config', 21:38:06 INFO - 'download-and-extract', 21:38:06 INFO - 'populate-webroot', 21:38:06 INFO - 'create-virtualenv', 21:38:06 INFO - 'install', 21:38:06 INFO - 'run-tests'), 21:38:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:38:06 INFO - 'download_minidump_stackwalk': True, 21:38:06 INFO - 'download_symbols': 'ondemand', 21:38:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:38:06 INFO - 'tooltool.py': '/tools/tooltool.py', 21:38:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:38:06 INFO - '/tools/misc-python/virtualenv.py')}, 21:38:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:38:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:38:06 INFO - 'gecko_profile': False, 21:38:06 INFO - 'gecko_profile_interval': 0, 21:38:06 INFO - 'installer_path': 'installer.exe', 21:38:06 INFO - 'log_level': 'info', 21:38:06 INFO - 'log_name': 'talos', 21:38:06 INFO - 'log_to_console': True, 21:38:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:38:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:38:06 INFO - 'opt_config_files': (), 21:38:06 INFO - 'pip_index': False, 21:38:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:38:06 INFO - 'cmd': ('bash', 21:38:06 INFO - '-c', 21:38:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:38:06 INFO - 'enabled': True, 21:38:06 INFO - 'halt_on_failure': False, 21:38:06 INFO - 'name': 'check_screen_resolution'},), 21:38:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:38:06 INFO - 'cmd': ('bash', 21:38:06 INFO - '-c', 21:38:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:38:06 INFO - 'enabled': True, 21:38:06 INFO - 'halt_on_failure': False, 21:38:06 INFO - 'name': 'check_screen_resolution'},), 21:38:06 INFO - 'run_cmd_checks_enabled': True, 21:38:06 INFO - 'suite': 'dromaeojs', 21:38:06 INFO - 'system_bits': '32', 21:38:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:38:06 INFO - 'title': 't-yosemite-r7-0003', 21:38:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:38:06 INFO - 'use_talos_json': True, 21:38:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:38:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:38:06 INFO - 'work_dir': 'build'} 21:38:06 INFO - [mozharness: 2017-05-08 04:38:06.050496Z] Running clobber step. 21:38:06 INFO - Running pre-action listener: _resource_record_pre_action 21:38:06 INFO - Running main action method: clobber 21:38:06 INFO - rmtree: /builds/slave/test/build 21:38:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:38:10 INFO - Running post-action listener: _resource_record_post_action 21:38:10 INFO - [mozharness: 2017-05-08 04:38:10.657447Z] Finished clobber step (success) 21:38:10 INFO - [mozharness: 2017-05-08 04:38:10.657661Z] Running read-buildbot-config step. 21:38:10 INFO - Running pre-action listener: _resource_record_pre_action 21:38:10 INFO - Running main action method: read_buildbot_config 21:38:10 INFO - Using buildbot properties: 21:38:10 INFO - { 21:38:10 INFO - "project": "", 21:38:10 INFO - "product": "firefox", 21:38:10 INFO - "script_repo_revision": "production", 21:38:10 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 21:38:10 INFO - "repository": "", 21:38:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 21:38:10 INFO - "buildid": "20170507201733", 21:38:10 INFO - "pgo_build": "False", 21:38:10 INFO - "basedir": "/builds/slave/test", 21:38:10 INFO - "buildnumber": 27, 21:38:10 INFO - "slavename": "t-yosemite-r7-0003", 21:38:10 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 21:38:10 INFO - "platform": "yosemite_r7", 21:38:10 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:38:10 INFO - "branch": "mozilla-central", 21:38:10 INFO - "repo_path": "mozilla-central", 21:38:10 INFO - "revision": "c3e5497cff1c995821b1c9320fa71f1ef9a8c30e", 21:38:10 INFO - "stage_platform": "macosx64", 21:38:10 INFO - "builduid": "ce63e0c2c032488ba6e57e8223420a16", 21:38:10 INFO - "slavebuilddir": "test" 21:38:10 INFO - } 21:38:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 21:38:10 INFO - Running post-action listener: _resource_record_post_action 21:38:10 INFO - [mozharness: 2017-05-08 04:38:10.663492Z] Finished read-buildbot-config step (success) 21:38:10 INFO - [mozharness: 2017-05-08 04:38:10.663714Z] Running download-and-extract step. 21:38:10 INFO - Running pre-action listener: _resource_record_pre_action 21:38:10 INFO - Running main action method: download_and_extract 21:38:10 INFO - mkdir: /builds/slave/test/build/tests 21:38: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')]} 21:38:10 INFO - trying https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 21:38:10 INFO - Downloading https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/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 21:38:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/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 21:38:11 INFO - Downloaded 1325 bytes. 21:38:11 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 21:38:11 INFO - Using the following test package requirements: 21:38:11 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:38:11 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 21:38:11 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 21:38:11 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:38:11 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 21:38:11 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:38:11 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 21:38:11 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:38:11 INFO - u'jsshell-mac.zip'], 21:38:11 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:38:11 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 21:38:11 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 21:38:11 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:38:11 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 21:38:11 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:38:11 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 21:38:11 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:38:11 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 21:38:11 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 21:38:11 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 21:38:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:38:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 21:38:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:38:11 INFO - Fetch https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 21:38:13 INFO - Content-Length response header: 52705616 21:38:13 INFO - Bytes received: 52705616 21:38: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 21:38:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 21:38:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:38:29 INFO - Fetch https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 21:38:31 INFO - Content-Length response header: 52705616 21:38:31 INFO - Bytes received: 52705616 21:38:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 21:38:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:38:47 INFO - Fetch https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 21:38:48 INFO - Content-Length response header: 14409362 21:38:48 INFO - Bytes received: 14409362 21:38: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')]} 21:38:48 INFO - trying https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:38:48 INFO - Downloading https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 21:38:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/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 21:38:50 INFO - Downloaded 70921823 bytes. 21:38:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:38:50 INFO - mkdir: /builds/slave/test/properties 21:38:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:38:50 INFO - Writing to file /builds/slave/test/properties/build_url 21:38:50 INFO - Contents: 21:38:50 INFO - build_url:https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:38:51 INFO - Running post-action listener: _resource_record_post_action 21:38:51 INFO - Running post-action listener: set_extra_try_arguments 21:38:51 INFO - [mozharness: 2017-05-08 04:38:51.257045Z] Finished download-and-extract step (success) 21:38:51 INFO - [mozharness: 2017-05-08 04:38:51.257159Z] Running populate-webroot step. 21:38:51 INFO - Running pre-action listener: _resource_record_pre_action 21:38:51 INFO - Running main action method: populate_webroot 21:38:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:38:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:38:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:38:51 INFO - '--tppagecycles', 21:38:51 INFO - '7'], 21:38:51 INFO - 'tests': ['tsvgm']}}, 21:38:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:38:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:38:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:38:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:38:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:38:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:38:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:38:51 INFO - 'talos_options': ['--disable-e10s'], 21:38:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:38:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:38:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:38:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:38:51 INFO - 'talos_options': ['--disable-e10s'], 21:38:51 INFO - 'tests': ['damp', 'tps']}, 21:38:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:38:51 INFO - 'tests': ['damp', 'tps']}, 21:38:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:38:51 INFO - 'tests': ['dromaeo_dom']}, 21:38:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:38:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:38:51 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 21:38:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:38:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:38:51 INFO - 'tests': ['a11yr', 21:38:51 INFO - 'ts_paint', 21:38:51 INFO - 'tpaint', 21:38:51 INFO - 'sessionrestore', 21:38:51 INFO - 'sessionrestore_no_auto_restore', 21:38:51 INFO - 'tabpaint']}, 21:38:51 INFO - 'other-e10s': {'tests': ['a11yr', 21:38:51 INFO - 'ts_paint', 21:38:51 INFO - 'tpaint', 21:38:51 INFO - 'sessionrestore', 21:38:51 INFO - 'sessionrestore_no_auto_restore', 21:38:51 INFO - 'tabpaint']}, 21:38:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:38:51 INFO - 'ts_paint', 21:38:51 INFO - 'tpaint', 21:38:51 INFO - 'sessionrestore', 21:38:51 INFO - 'sessionrestore_no_auto_restore', 21:38:51 INFO - 'tabpaint']}, 21:38:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:38:51 INFO - 'ts_paint', 21:38:51 INFO - 'tpaint', 21:38:51 INFO - 'sessionrestore', 21:38:51 INFO - 'sessionrestore_no_auto_restore', 21:38:51 INFO - 'tabpaint']}, 21:38:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:38:51 INFO - 'tests': ['a11yr', 21:38:51 INFO - 'ts_paint', 21:38:51 INFO - 'tpaint', 21:38:51 INFO - 'sessionrestore', 21:38:51 INFO - 'sessionrestore_no_auto_restore', 21:38:51 INFO - 'tabpaint']}, 21:38:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:38:51 INFO - 'tests': ['a11yr', 21:38:51 INFO - 'ts_paint', 21:38:51 INFO - 'tpaint', 21:38:51 INFO - 'sessionrestore', 21:38:51 INFO - 'sessionrestore_no_auto_restore', 21:38:51 INFO - 'tabpaint']}, 21:38:51 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 21:38:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 21:38:51 INFO - 'bloom_basic_ref']}, 21:38:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:38:51 INFO - 'tests': ['tsvgx', 21:38:51 INFO - 'tsvgr_opacity', 21:38:51 INFO - 'tart', 21:38:51 INFO - 'tscrollx', 21:38:51 INFO - 'cart', 21:38:51 INFO - 'tsvg_static']}, 21:38:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:38:51 INFO - 'tsvgr_opacity', 21:38:51 INFO - 'tart', 21:38:51 INFO - 'tscrollx', 21:38:51 INFO - 'cart', 21:38:51 INFO - 'tsvg_static']}, 21:38:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:38:51 INFO - 'talos_options': ['--disable-e10s'], 21:38:51 INFO - 'tests': ['tp5o']}, 21:38:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:38:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:38:51 INFO - 'talos_options': ['--disable-e10s', 21:38:51 INFO - '--xperf_path', 21:38:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:38:51 INFO - 'tests': ['tp5n']}, 21:38:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:38:51 INFO - 'talos_options': ['--xperf_path', 21:38:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:38:51 INFO - 'tests': ['tp5n']}}, 21:38:51 INFO - 'talos.zip': {'path': '', 21:38:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:38:51 INFO - Running post-action listener: _resource_record_post_action 21:38:51 INFO - [mozharness: 2017-05-08 04:38:51.275809Z] Finished populate-webroot step (success) 21:38:51 INFO - [mozharness: 2017-05-08 04:38:51.275913Z] Running create-virtualenv step. 21:38:51 INFO - Running pre-action listener: _resource_record_pre_action 21:38:51 INFO - Running main action method: create_virtualenv 21:38:51 INFO - Creating virtualenv /builds/slave/test/build/venv 21:38: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 21:38:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:38:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:38:51 INFO - Using real prefix '/tools/python27' 21:38:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:38:52 INFO - Installing distribute.............................................................................................................................................................................................done. 21:38:54 INFO - Installing pip.................done. 21:38:54 INFO - Return code: 0 21:38:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:38:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:38:55 INFO - Reading from file tmpfile_stdout 21:38:55 INFO - Output received: 21:38: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) 21:38:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:38: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')]} 21:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38: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')]} 21:38:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38: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 0x10ab40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c85aaaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a851d30>, '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 0x10a804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a845be8>, '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.vFNJdw3uDM/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.rvKdYTRg9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 21:38: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 21:38: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 21:38:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFNJdw3uDM/Render', 21:38:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:38:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:38:55 INFO - 'HOME': '/Users/cltbld', 21:38:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:38:55 INFO - 'LOGNAME': 'cltbld', 21:38:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:55 INFO - 'MOZ_NO_REMOTE': '1', 21:38:55 INFO - 'NO_EM_RESTART': '1', 21:38:55 INFO - 'PAGER': '/bin/cat', 21:38:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:38:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:55 INFO - 'PWD': '/builds/slave/test', 21:38:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:38:55 INFO - 'SHELL': '/bin/bash', 21:38:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rvKdYTRg9U/Listeners', 21:38:55 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 21:38:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:38:55 INFO - 'USER': 'cltbld', 21:38:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:38:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:38:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:55 INFO - 'XPC_FLAGS': '0x0', 21:38:55 INFO - 'XPC_SERVICE_NAME': '0', 21:38:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:38:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38: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 21:38:55 INFO - Cleaning up... 21:38:55 INFO - Return code: 0 21:38:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:38: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')]} 21:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38: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')]} 21:38:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38: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 0x10ab40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c85aaaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a851d30>, '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 0x10a804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a845be8>, '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.vFNJdw3uDM/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.rvKdYTRg9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 21:38: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 21:38: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 21:38:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFNJdw3uDM/Render', 21:38:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:38:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:38:55 INFO - 'HOME': '/Users/cltbld', 21:38:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:38:55 INFO - 'LOGNAME': 'cltbld', 21:38:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:55 INFO - 'MOZ_NO_REMOTE': '1', 21:38:55 INFO - 'NO_EM_RESTART': '1', 21:38:55 INFO - 'PAGER': '/bin/cat', 21:38:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:38:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:55 INFO - 'PWD': '/builds/slave/test', 21:38:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:38:55 INFO - 'SHELL': '/bin/bash', 21:38:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rvKdYTRg9U/Listeners', 21:38:55 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 21:38:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:38:55 INFO - 'USER': 'cltbld', 21:38:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:38:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:38:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:55 INFO - 'XPC_FLAGS': '0x0', 21:38:55 INFO - 'XPC_SERVICE_NAME': '0', 21:38:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:38:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:55 INFO - Downloading/unpacking psutil>=3.1.1 21:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:38:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:38:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:38:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:38:58 INFO - Installing collected packages: psutil 21:38:58 INFO - Running setup.py install for psutil 21:38:58 INFO - building 'psutil._psutil_osx' extension 21:38:58 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 21:38:58 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 21:38:58 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 21:38:58 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 21:38:58 INFO - building 'psutil._psutil_posix' extension 21:38:58 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 21:38:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:38:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:38:59 INFO - ^ 21:38:59 INFO - 1 warning generated. 21:38:59 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 21:38:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:38:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:38:59 INFO - Successfully installed psutil 21:38:59 INFO - Cleaning up... 21:38:59 INFO - Return code: 0 21:38:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39: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 0x10ab40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c85aaaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a851d30>, '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 0x10a804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a845be8>, '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.vFNJdw3uDM/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.rvKdYTRg9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 21:39: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 21:39: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 21:39:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFNJdw3uDM/Render', 21:39:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:00 INFO - 'HOME': '/Users/cltbld', 21:39:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:00 INFO - 'LOGNAME': 'cltbld', 21:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:00 INFO - 'MOZ_NO_REMOTE': '1', 21:39:00 INFO - 'NO_EM_RESTART': '1', 21:39:00 INFO - 'PAGER': '/bin/cat', 21:39:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:00 INFO - 'PWD': '/builds/slave/test', 21:39:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:00 INFO - 'SHELL': '/bin/bash', 21:39:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rvKdYTRg9U/Listeners', 21:39:00 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 21:39:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:00 INFO - 'USER': 'cltbld', 21:39:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:00 INFO - 'XPC_FLAGS': '0x0', 21:39:00 INFO - 'XPC_SERVICE_NAME': '0', 21:39:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:39: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 21:39: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 21:39: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 21:39: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 21:39: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 21:39: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 21:39:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:39:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:39:02 INFO - Installing collected packages: mozsystemmonitor 21:39:02 INFO - Running setup.py install for mozsystemmonitor 21:39:02 INFO - Successfully installed mozsystemmonitor 21:39:02 INFO - Cleaning up... 21:39:02 INFO - Return code: 0 21:39:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:39:02 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')]} 21:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:02 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')]} 21:39:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:02 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 0x10ab40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c85aaaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a851d30>, '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 0x10a804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a845be8>, '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.vFNJdw3uDM/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.rvKdYTRg9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 21:39:02 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 21:39:02 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 21:39:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFNJdw3uDM/Render', 21:39:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:02 INFO - 'HOME': '/Users/cltbld', 21:39:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:02 INFO - 'LOGNAME': 'cltbld', 21:39:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:02 INFO - 'MOZ_NO_REMOTE': '1', 21:39:02 INFO - 'NO_EM_RESTART': '1', 21:39:02 INFO - 'PAGER': '/bin/cat', 21:39:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:02 INFO - 'PWD': '/builds/slave/test', 21:39:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:02 INFO - 'SHELL': '/bin/bash', 21:39:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rvKdYTRg9U/Listeners', 21:39:02 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 21:39:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:02 INFO - 'USER': 'cltbld', 21:39:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:02 INFO - 'XPC_FLAGS': '0x0', 21:39:02 INFO - 'XPC_SERVICE_NAME': '0', 21:39:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:02 INFO - Downloading/unpacking jsonschema==2.5.1 21:39:02 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 21:39:02 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 21:39:02 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 21:39:02 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 21:39:02 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 21:39:02 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 21:39:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:39:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:39:03 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 21:39:03 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 21:39:03 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 21:39:03 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 21:39:03 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 21:39:03 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 21:39:04 INFO - Downloading functools32-3.2.3-2.tar.gz 21:39:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:39:04 INFO - warning: no files found matching '*.txt' 21:39:04 INFO - no previously-included directories found matching 'build' 21:39:04 INFO - no previously-included directories found matching 'dist' 21:39:04 INFO - no previously-included directories found matching '.git*' 21:39:04 INFO - Installing collected packages: jsonschema, functools32 21:39:04 INFO - Running setup.py install for functools32 21:39:04 INFO - warning: no files found matching '*.txt' 21:39:04 INFO - no previously-included directories found matching 'build' 21:39:04 INFO - no previously-included directories found matching 'dist' 21:39:04 INFO - no previously-included directories found matching '.git*' 21:39:04 INFO - Successfully installed jsonschema functools32 21:39:04 INFO - Cleaning up... 21:39:04 INFO - Return code: 0 21:39:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:39: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')]} 21:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39: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')]} 21:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39: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', '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 0x10ab40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c85aaaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a851d30>, '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 0x10a804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a845be8>, '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.vFNJdw3uDM/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.rvKdYTRg9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 21:39: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:39: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 functools32==3.2.3-2 21:39:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFNJdw3uDM/Render', 21:39:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:04 INFO - 'HOME': '/Users/cltbld', 21:39:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:04 INFO - 'LOGNAME': 'cltbld', 21:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:04 INFO - 'MOZ_NO_REMOTE': '1', 21:39:04 INFO - 'NO_EM_RESTART': '1', 21:39:04 INFO - 'PAGER': '/bin/cat', 21:39:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:04 INFO - 'PWD': '/builds/slave/test', 21:39:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:04 INFO - 'SHELL': '/bin/bash', 21:39:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rvKdYTRg9U/Listeners', 21:39:04 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 21:39:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:04 INFO - 'USER': 'cltbld', 21:39:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:04 INFO - 'XPC_FLAGS': '0x0', 21:39:04 INFO - 'XPC_SERVICE_NAME': '0', 21:39:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:39:04 INFO - Cleaning up... 21:39:04 INFO - Return code: 0 21:39:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:39: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')]} 21:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39: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')]} 21:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39: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', '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 0x10ab40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c85aaaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a851d30>, '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 0x10a804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a845be8>, '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.vFNJdw3uDM/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.rvKdYTRg9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 21:39: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:39: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 blobuploader==1.2.4 21:39:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFNJdw3uDM/Render', 21:39:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:04 INFO - 'HOME': '/Users/cltbld', 21:39:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:04 INFO - 'LOGNAME': 'cltbld', 21:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:04 INFO - 'MOZ_NO_REMOTE': '1', 21:39:04 INFO - 'NO_EM_RESTART': '1', 21:39:04 INFO - 'PAGER': '/bin/cat', 21:39:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:04 INFO - 'PWD': '/builds/slave/test', 21:39:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:04 INFO - 'SHELL': '/bin/bash', 21:39:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rvKdYTRg9U/Listeners', 21:39:04 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 21:39:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:04 INFO - 'USER': 'cltbld', 21:39:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:04 INFO - 'XPC_FLAGS': '0x0', 21:39:04 INFO - 'XPC_SERVICE_NAME': '0', 21:39:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:05 INFO - Downloading/unpacking blobuploader==1.2.4 21:39:05 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 21:39:05 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 21:39:05 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 21:39:05 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 21:39:05 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 21:39:05 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 21:39:06 INFO - Downloading blobuploader-1.2.4.tar.gz 21:39:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:39:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:39:06 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 21:39:06 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 21:39:06 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 21:39:06 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 21:39:06 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 21:39:06 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 21:39:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:39:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:39:06 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 21:39:06 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 21:39:06 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 21:39:06 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 21:39:06 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 21:39:06 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 21:39:07 INFO - Downloading docopt-0.6.1.tar.gz 21:39:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:39:07 INFO - Installing collected packages: blobuploader, requests, docopt 21:39:07 INFO - Running setup.py install for blobuploader 21:39:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:39:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:39:07 INFO - Running setup.py install for requests 21:39:08 INFO - Running setup.py install for docopt 21:39:08 INFO - Successfully installed blobuploader requests docopt 21:39:08 INFO - Cleaning up... 21:39:08 INFO - Return code: 0 21:39:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:39: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')]} 21:39:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39: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')]} 21:39:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:08 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 0x10ab40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c85aaaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a851d30>, '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 0x10a804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a845be8>, '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.vFNJdw3uDM/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.rvKdYTRg9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 21:39:08 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 21:39:08 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 21:39:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFNJdw3uDM/Render', 21:39:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:08 INFO - 'HOME': '/Users/cltbld', 21:39:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:08 INFO - 'LOGNAME': 'cltbld', 21:39:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:08 INFO - 'MOZ_NO_REMOTE': '1', 21:39:08 INFO - 'NO_EM_RESTART': '1', 21:39:08 INFO - 'PAGER': '/bin/cat', 21:39:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:08 INFO - 'PWD': '/builds/slave/test', 21:39:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:08 INFO - 'SHELL': '/bin/bash', 21:39:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rvKdYTRg9U/Listeners', 21:39:08 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 21:39:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:08 INFO - 'USER': 'cltbld', 21:39:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:08 INFO - 'XPC_FLAGS': '0x0', 21:39:08 INFO - 'XPC_SERVICE_NAME': '0', 21:39:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:39:08 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-XRgpoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:39:08 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-C5IaBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:39:08 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-Wca1bg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:39:08 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-V5yQsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:39:08 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-gaikaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:39:08 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-cGqQwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:39:09 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-66E1_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:39:09 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-9OFCRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:39:09 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-ZudD1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:39:09 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-c5XMvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:39:09 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-dofjb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:39:09 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-lvjpx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:39:09 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-ujwQ5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:39:09 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-7SIj2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:39:09 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-jJ1Yke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:39:10 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-k6BtJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:39:10 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-Q8dH3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:39:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:39:10 INFO - Running setup.py install for manifestparser 21:39:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:39:10 INFO - Running setup.py install for mozcrash 21:39:10 INFO - Running setup.py install for mozdebug 21:39:10 INFO - Running setup.py install for mozdevice 21:39:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:39:10 INFO - Running setup.py install for mozfile 21:39:10 INFO - Running setup.py install for mozhttpd 21:39:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:39:11 INFO - Running setup.py install for mozinfo 21:39:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:39:11 INFO - Running setup.py install for mozInstall 21:39:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:39:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:39:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:39:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:39:11 INFO - Running setup.py install for mozleak 21:39:11 INFO - Running setup.py install for mozlog 21:39:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:39:11 INFO - Running setup.py install for moznetwork 21:39:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:39:11 INFO - Running setup.py install for mozprocess 21:39:11 INFO - Running setup.py install for mozprofile 21:39:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:39:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:39:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:39:12 INFO - Running setup.py install for mozrunner 21:39:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:39:12 INFO - Running setup.py install for mozscreenshot 21:39:12 INFO - Running setup.py install for moztest 21:39:12 INFO - Running setup.py install for mozversion 21:39:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:39:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:39:12 INFO - Cleaning up... 21:39:12 INFO - Return code: 0 21:39:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:12 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 0x10ab40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c85aaaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a851d30>, '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 0x10a804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a845be8>, '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.vFNJdw3uDM/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.rvKdYTRg9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 21:39:12 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 21:39:12 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 21:39:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFNJdw3uDM/Render', 21:39:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:12 INFO - 'HOME': '/Users/cltbld', 21:39:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:12 INFO - 'LOGNAME': 'cltbld', 21:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:12 INFO - 'MOZ_NO_REMOTE': '1', 21:39:12 INFO - 'NO_EM_RESTART': '1', 21:39:12 INFO - 'PAGER': '/bin/cat', 21:39:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:12 INFO - 'PWD': '/builds/slave/test', 21:39:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:12 INFO - 'SHELL': '/bin/bash', 21:39:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rvKdYTRg9U/Listeners', 21:39:12 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 21:39:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:12 INFO - 'USER': 'cltbld', 21:39:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:12 INFO - 'XPC_FLAGS': '0x0', 21:39:12 INFO - 'XPC_SERVICE_NAME': '0', 21:39:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:39:13 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-jtK8gC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:39:13 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)) 21:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:39:13 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-9fwBZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:39:13 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)) 21:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:39:13 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-N8glvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:39:13 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)) 21:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:39:13 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-yUt8n8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:39:13 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)) 21:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:39:13 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-5On4Qk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:39:13 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)) 21:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:39:13 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-h6J7Bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:39:13 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)) 21:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:39:13 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-XIL0CN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:39:13 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)) 21:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:39:13 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-KoAlWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:39:13 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)) 21:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:39:13 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-bbp7Qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:39:13 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)) 21:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:39:13 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-b1FoSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:39:14 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)) 21:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:39:14 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-Xcm7fu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:39:14 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)) 21:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:39:14 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-M4JOWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:39:14 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)) 21:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:39:14 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-cTM7aE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:39:14 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)) 21:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:39:14 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-eVOgSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:39:14 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)) 21:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:39:14 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-MPTdHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:39:14 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)) 21:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:39:14 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-9eU_7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:39:14 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)) 21:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:39:14 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-BJk7o7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:39:14 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)) 21:39:14 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)) 21:39:14 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)) 21:39:14 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)) 21:39:14 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)) 21:39:14 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)) 21:39:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:39:14 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 21:39:14 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 21:39:14 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 21:39:14 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 21:39:14 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 21:39:14 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 21:39:16 INFO - Downloading blessings-1.6.tar.gz 21:39:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:39:16 INFO - Installing collected packages: blessings 21:39:16 INFO - Running setup.py install for blessings 21:39:16 INFO - Successfully installed blessings 21:39:16 INFO - Cleaning up... 21:39:16 INFO - Return code: 0 21:39:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:39:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:39:16 INFO - Reading from file tmpfile_stdout 21:39:16 INFO - Current package versions: 21:39:16 INFO - blessings == 1.6 21:39:16 INFO - blobuploader == 1.2.4 21:39:16 INFO - docopt == 0.6.1 21:39:16 INFO - functools32 == 3.2.3-2 21:39:16 INFO - jsonschema == 2.5.1 21:39:16 INFO - manifestparser == 1.1 21:39:16 INFO - mozInstall == 1.12 21:39:16 INFO - mozcrash == 1.0 21:39:16 INFO - mozdebug == 0.1 21:39:16 INFO - mozdevice == 0.50 21:39:16 INFO - mozfile == 1.2 21:39:16 INFO - mozhttpd == 0.7 21:39:16 INFO - mozinfo == 0.9 21:39:16 INFO - mozleak == 0.1 21:39:16 INFO - mozlog == 3.4 21:39:16 INFO - moznetwork == 0.27 21:39:16 INFO - mozprocess == 0.25 21:39:16 INFO - mozprofile == 0.28 21:39:16 INFO - mozrunner == 6.13 21:39:16 INFO - mozscreenshot == 0.1 21:39:16 INFO - mozsystemmonitor == 0.3 21:39:16 INFO - moztest == 0.8 21:39:16 INFO - mozversion == 1.4 21:39:16 INFO - psutil == 3.1.1 21:39:16 INFO - requests == 1.2.3 21:39:16 INFO - wsgiref == 0.1.2 21:39:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:16 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 0x10ab40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c85aaaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a851d30>, '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 0x10a804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a845be8>, '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.vFNJdw3uDM/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.rvKdYTRg9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 21:39:16 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 21:39:16 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 21:39:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFNJdw3uDM/Render', 21:39:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:16 INFO - 'HOME': '/Users/cltbld', 21:39:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:16 INFO - 'LOGNAME': 'cltbld', 21:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:16 INFO - 'MOZ_NO_REMOTE': '1', 21:39:16 INFO - 'NO_EM_RESTART': '1', 21:39:16 INFO - 'PAGER': '/bin/cat', 21:39:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:16 INFO - 'PWD': '/builds/slave/test', 21:39:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:16 INFO - 'SHELL': '/bin/bash', 21:39:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rvKdYTRg9U/Listeners', 21:39:16 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 21:39:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:16 INFO - 'USER': 'cltbld', 21:39:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:16 INFO - 'XPC_FLAGS': '0x0', 21:39:16 INFO - 'XPC_SERVICE_NAME': '0', 21:39:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:17 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)) 21:39:17 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)) 21:39:17 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)) 21:39:17 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)) 21:39:17 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)) 21:39:17 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)) 21:39:17 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)) 21:39:17 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)) 21:39:17 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)) 21:39:17 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)) 21:39:17 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)) 21:39:17 INFO - Cleaning up... 21:39:17 INFO - Return code: 0 21:39:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:17 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 0x10ab40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c85aaaa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a851d30>, '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 0x10a804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a845be8>, '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.vFNJdw3uDM/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.rvKdYTRg9U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 21:39:17 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 21:39:17 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 21:39:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFNJdw3uDM/Render', 21:39:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:17 INFO - 'HOME': '/Users/cltbld', 21:39:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:17 INFO - 'LOGNAME': 'cltbld', 21:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:17 INFO - 'MOZ_NO_REMOTE': '1', 21:39:17 INFO - 'NO_EM_RESTART': '1', 21:39:17 INFO - 'PAGER': '/bin/cat', 21:39:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:17 INFO - 'PWD': '/builds/slave/test', 21:39:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:17 INFO - 'SHELL': '/bin/bash', 21:39:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rvKdYTRg9U/Listeners', 21:39:17 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 21:39:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:17 INFO - 'USER': 'cltbld', 21:39:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:17 INFO - 'XPC_FLAGS': '0x0', 21:39:17 INFO - 'XPC_SERVICE_NAME': '0', 21:39:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:39:17 INFO - Cleaning up... 21:39:17 INFO - Return code: 0 21:39:17 INFO - Running post-action listener: _resource_record_post_action 21:39:17 INFO - Running post-action listener: _start_resource_monitoring 21:39:17 INFO - Starting resource monitoring. 21:39:17 INFO - [mozharness: 2017-05-08 04:39:17.447517Z] Finished create-virtualenv step (success) 21:39:17 INFO - [mozharness: 2017-05-08 04:39:17.448487Z] Running install step. 21:39:17 INFO - Running pre-action listener: _resource_record_pre_action 21:39:17 INFO - Running main action method: install 21:39:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:39:17 INFO - Reading from file tmpfile_stdout 21:39:17 INFO - Detecting whether we're running mozinstall >=1.0... 21:39:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:39:17 INFO - Reading from file tmpfile_stdout 21:39:17 INFO - Output received: 21:39:17 INFO - Usage: mozinstall [options] installer 21:39:17 INFO - Options: 21:39:17 INFO - -h, --help show this help message and exit 21:39:17 INFO - -d DEST, --destination=DEST 21:39:17 INFO - Directory to install application into. [default: 21:39:17 INFO - "/builds/slave/test"] 21:39:17 INFO - --app=APP Application being installed. [default: firefox] 21:39:17 INFO - mkdir: /builds/slave/test/build/application 21:39:17 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'] 21:39:17 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 21:39:37 INFO - Reading from file tmpfile_stdout 21:39:37 INFO - Output received: 21:39:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:39:37 INFO - Running post-action listener: _resource_record_post_action 21:39:37 INFO - [mozharness: 2017-05-08 04:39:37.661906Z] Finished install step (success) 21:39:37 INFO - [mozharness: 2017-05-08 04:39:37.662033Z] Running run-tests step. 21:39:37 INFO - Running pre-action listener: _resource_record_pre_action 21:39:37 INFO - Running main action method: run_tests 21:39:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:39:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:39:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:39:37 INFO - 2017-05-07 21:39:37.675 screenresolution[1631:7349] starting screenresolution argv=screenresolution get 21:39:37 INFO - 2017-05-07 21:39:37.678 screenresolution[1631:7349] Display 0: 1600x1200x32@60 21:39:37 INFO - 2017-05-07 21:39:37.684 screenresolution[1632:7351] starting screenresolution argv=screenresolution list 21:39:37 INFO - Available Modes on Display 0 21:39:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:39:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:39:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:39:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:39:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:39:37 INFO - Intel Iris: 21:39:37 INFO - Chipset Model: Intel Iris 21:39:37 INFO - Type: GPU 21:39:37 INFO - Bus: Built-In 21:39:37 INFO - VRAM (Dynamic, Max): 1536 MB 21:39:37 INFO - Vendor: Intel (0x8086) 21:39:37 INFO - Device ID: 0x0a2e 21:39:37 INFO - Revision ID: 0x0009 21:39:37 INFO - Displays: 21:39:37 INFO - Display: 21:39:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:39:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:39:37 INFO - Display Serial Number: 1600x1200 60 21:39:37 INFO - Main Display: Yes 21:39:37 INFO - Mirror: Off 21:39:37 INFO - Online: Yes 21:39:37 INFO - Rotation: Supported 21:39:37 INFO - Return code: 0 21:39:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:39:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:39:37 INFO - Python 2.7.3 21:39:37 INFO - Return code: 0 21:39:37 INFO - grabbing minidump binary from tooltool 21:39:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:39:37 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 0x10a851d30>, '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 0x10a804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a845be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:39:37 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 21:39:37 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 21:39:37 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 21:39:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:39:38 INFO - Return code: 0 21:39:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:39:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:39:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:39:38 INFO - ENV: RUST_BACKTRACE is now 1 21:39:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:39:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:39:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0003 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:39:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFNJdw3uDM/Render', 21:39:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:39:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:39:38 INFO - 'HOME': '/Users/cltbld', 21:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:39:38 INFO - 'LOGNAME': 'cltbld', 21:39:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:39:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:38 INFO - 'MOZ_NO_REMOTE': '1', 21:39:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:39:38 INFO - 'NO_EM_RESTART': '1', 21:39:38 INFO - 'PAGER': '/bin/cat', 21:39:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:38 INFO - 'PWD': '/builds/slave/test', 21:39:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:39:38 INFO - 'RUST_BACKTRACE': '1', 21:39:38 INFO - 'SHELL': '/bin/bash', 21:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rvKdYTRg9U/Listeners', 21:39:38 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 21:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:39:38 INFO - 'USER': 'cltbld', 21:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:38 INFO - 'XPC_FLAGS': '0x0', 21:39:38 INFO - 'XPC_SERVICE_NAME': '0', 21:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:39:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:39:38 INFO - mozversion application_buildid: 20170507201733 21:39:38 INFO - mozversion application_changeset: c3e5497cff1c995821b1c9320fa71f1ef9a8c30e 21:39:38 INFO - mozversion application_display_name: Nightly 21:39:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:39:38 INFO - mozversion application_name: Firefox 21:39:38 INFO - mozversion application_remotingname: firefox 21:39:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:39:38 INFO - mozversion application_vendor: Mozilla 21:39:38 INFO - mozversion application_version: 55.0a1 21:39:38 INFO - mozversion platform_buildid: 20170507201733 21:39:38 INFO - mozversion platform_changeset: c3e5497cff1c995821b1c9320fa71f1ef9a8c30e 21:39:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:39:38 INFO - mozversion platform_version: 55.0a1 21:39:38 INFO - using testdate: 1494218378 21:39:38 INFO - actual date: 1494218378 21:39:38 INFO - starting webserver on 'localhost:49240' 21:39:38 INFO - SUITE-START | Running 2 tests 21:39:38 INFO - TEST-START | dromaeo_css 21:39:38 INFO - operating with platform_type : mac_ 21:39:38 INFO - Initialising browser for dromaeo_css test... 21:39:38 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpzwcuzZ/profile http://localhost:49240/getInfo.html) 21:39:40 INFO - PID 1642 | __metrics Screen width/height:1600/1200 21:39:40 INFO - PID 1642 | colorDepth:24 21:39:40 INFO - PID 1642 | Browser inner width/height: 1024/691 21:39:40 INFO - PID 1642 | __metrics 21:39:40 INFO - PID 1642 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:39:40 INFO - PID 1642 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:39:41 INFO - TEST-INFO | 1642: exit 0 21:39:41 INFO - Browser initialized. 21:39:41 INFO - Running cycle 1/1 for dromaeo_css test... 21:39:41 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpzwcuzZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:39:53 INFO - PID 1644 | RSS: Main: 239636480 21:39:53 INFO - PID 1644 | 21:42:58 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 21:43:00 INFO - PID 1644 | RSS: Main: 380612608 21:43:00 INFO - PID 1644 | 21:46:02 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 21:46:03 INFO - PID 1644 | RSS: Main: 366190592 21:46:03 INFO - PID 1644 | 21:49:05 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 21:49:06 INFO - PID 1644 | RSS: Main: 328892416 21:49:06 INFO - PID 1644 | 21:52:08 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 21:52:09 INFO - PID 1644 | RSS: Main: 328282112 21:52:09 INFO - PID 1644 | 21:55:12 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 21:55:13 INFO - PID 1644 | RSS: Main: 326578176 21:55:13 INFO - PID 1644 | 21:58:15 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 21:58:15 INFO - PID 1644 | RSS: Main: 368422912 21:58:15 INFO - PID 1644 | 21:58:15 INFO - PID 1644 | __start_tp_report 21:58:15 INFO - PID 1644 | _x_x_mozilla_page_load 21:58:15 INFO - PID 1644 | _x_x_mozilla_page_load_details 21:58:15 INFO - PID 1644 | |i|pagename|runs| 21:58:15 INFO - PID 1644 | |0;dojo.html;2656.5749480083186;3499.912502187445;3502.12446888278;3503.7722548034358;3542.0790594445452;5022.673526220798;5835.0663893777;5976.671283079429;6088.421599948003;6132.908006379902;5900.852478688035;5925.437083477073;5936.465718085371;5941.5246780257585;5942.1978032965535;5126.461721519243;5152.484751524837;5154.69071855688;5158.458361872005;5203.2975548301065;5309.389420216667;5315.867103322409;5319.840404787843;5319.893602127955;5329.653572517774;4414;5214.139666954967;5301.125314323132;5310.70791106489;5329.653572517794;5263.86840328991;5270.604704647148;5282.128448805981;5290.523852853262;5293.179557168645;5146.794128234867;5230.163173892159;5239.947600523984;5245.423003469654;5248.239005344242;6057.818265452043;6062.727177277033;6078.756849726005;6081.148639190517;6119.357467465941;6359.8092057238355;6379.521535884781;6384.680765961683;6402;6417.2299324081405;6297.716602752887;6351.237851457809;6358.936410635881;6377.71300291488;6380.234371875405;5541.27963364764;6072.726727297194;6100.420460056288;6102.0541816018585;6124.38756124384;6459.128017717642;6466.321036291121;6628.734850606018;6646.767363142363;6655.134832471747;6323.399277068671;6327.430531252117;6329.113924050636;6331.018692102732;6341.904871426932;6324.146240257506;6458.160439142881;6461.353864613501;6463.095166676669;6485.935140648533;5287.3126493556365;6292.590981586182;6326.2092238470195;6338.049292606146;6372.203474565679;6380.202474690664;6387.329330420332;6392.232932048184;6401.64790936503;6410.967945160244;4240.1307731914885;5048.974755126201;5203.661761985459;5209.244659524424;5212.270282160501;4551.613112885442;4593.724376537419;4617.1227466781875;4618.145643056046;4706;6066.726997285133;6118;6120.294761999619;6141.355157708465;6144.8771024579255;5386.91919621206;6471.255805582324;6471.417572418505;6484.383983521558;6486.156799616114;6018.102021442119;6032.336442991267;6062.484688801501;6066.575339726265;6080.179175811209;5573.749181286852;5636.689982051026;5709.486146246858;5712.171735098387;5725.51333136688;6395.968020159869;6402.519811014193;6573.671316434064;6664.700088496126;6678.499112566674;6434.549581529435;6451.645159516272;6458.321876203119;6458.709358078804;6476.6114033158165;6653.733850646114;6655.866882662416;6662.700178491883;6670;6686.665666716742;6267.5299352549655;6311.0217916223055;6343.0485427186295;6355.269144048493;6361.745530178741;5947.3457919627945;6067.847109049267;6298.212723409574;6447.516436267392;6471.352864713491;6268;6268.655223962726;6279.654618995999;6304.7162877671535;6316.431521163117;6301.40136687014;6304.338044505261;6308.842278943063;6310.337414571588;6316.684165791784;6175;6223;6244.406781355764;6269.31037585856;6297.149884765498;6290.811275661737;6302.495800336053;6331.176946996861;6337.493000560036;6347.079673447417;6251.218597675291;6277.435030847063;6290.99344104941;6293.96853015732;6296.33888441707 21:58:15 INFO - PID 1644 | |1;ext.html;772.9574873381963;1078.7896360209756;1101.9696583694251;1103.1119948441506;1234.7900856854335;33267;34960.65039349606;34996.30007399851;35053.82473087634;35140.82429587848;35111.297774044506;35503.822480887626;35572.93281201561;35590.57637694489;35611.82194089026;5837.153612726173;5908.734106965197;7477.813054673622;7630.580318082503;8038.598070096501;8055.677772889107;8165.020197576299;8165.6733730651;8176.672933082669;8183.877241841377;8125.9842519685035;8169.79575510614;8182.386321025917;8204.261616454518;8218.342532597379;5791.21818554494;5874.1776151338845;8057.35541156706;8138.918610813876;8167.79580510489;8026.959865200637;8092.757217283491;8100.918990810134;8109.75670729879;8149.714759983432;34777;35079.82460087709;35085.82457087698;35107.82446087779;35289;3525.823708814549;3556.199855032623;3557.2529768748595;3558.430651095812;3558.6975107115927;28479.57280640792;28550.571741423897;28615.57076643852;28625.57061644077;28632.856835715895;3049.7255247027874;3080.7843450958444;3118.656947735747;3132.6710695377114;3134.7335476484527;285429;293395.5330223357;293719;294043.52978235186;295684;36423.4536481953;37542.43686344707;37554.624453755656;37667.62332376696;46713.299300510516;46426.83932901704;46591;46599.30101048487;46633;46653.76673116613;13467.912473566614;13566;13572.389242484112;13575;13589.728205435837;10906.67279981586;11085.334879907232;11091.279066860629;11092.611758588411;11096.778064438666;11692.298462092302;11696.356700381391;11707.473163707653;11732.94133529327;11857.16999810005;4941.209406494943;4945.23348880924;4946.876328091755;4951.207806750903;4961.82633607822;966.7679756858304;983.8376667849868;985.9605615775432;993.3940296419179;1019.5564929255797;23480.64779028316;23602.527949440915;23627.173048943205;23657.40856478602;23774.286771396884;916.5921165081608;1005.2912696549062;1014.0062738516295;1069.989300106989;1127.3686735428023;11267.15496337778;11851.644450666494;11972.161948663685;12059.276443413422;12093;298466;298654;300051;301290;301562;49294.01411971812;49368;49378.50621493811;49391.50608493941;49391.753041234566;1372.7302245423143;1446.1901335252082;1456.6940942402002;1502.451605164129;1502.7295086884246;22705.659415108457;22979.88510057439;23251.534969300857;23324.3002709919;23338.416539585967;31508.054758357284;36069.91790246297;36130.916072517866;36138.8193059033;36186.45720314145;262912;263935;264115;264521;264599;25347.493050139263;26155.215343539723;30973;32275.677243227266;32473.350532988734;40352.596474034886;40881.79559102185;40985;41034;41038.9740256496;27184.864075679496;27435.86282068577;29030.564541531472;29156.416871662874;29543.681456562408;28285.43429131447;28572.57141142843;29056;29400.7059929398;29462.41075178527;27436.58845117285;34569.82715086408;34636.65363346334;34653;34767.30465390729;27728;28040;28645.14064578066;29129;29436;18396.540086497946;22803.429914252276;23092.4226894329;23242.418939526648;23248.302550923498 21:58:15 INFO - PID 1644 | |2;jquery.html;423.37998353522283;429.4117059628306;437.91460665170297;439.5318985280677;450.98421555245574;18782.06089695515;20620.587588248225;21034.894825525887;21322.57354852902;21326.573468530638;20694.48263793406;22750.77249227507;22830.429239269026;23257.953392097355;23516.412089697766;5145.511176438233;5858.677772722509;6086.026235802273;6135.539834512407;6147.4159954804445;5737.741801618917;6028.577999540012;6149.108379285001;6153;6161.507079433634;5822.621529600584;6083.513318934474;6106.694665266719;6107.847303817418;6128.57100003;5902.88194236115;6032.939670603281;6039.486643635296;6053.394660533933;6074.119252708332;5289.338832645919;5366.570674346043;5368.32895888014;5370.489803468664;5407.729613519308;28178.71821281802;28312.858435707894;28412.863485460555;28529.429411411656;28683.71316286852;2147.7744836792226;2221.111555377862;2221.389117992549;2221.8444708870384;2224.5773303072365;17538.649227015514;17644.853084549464;17725.822741772547;18347.99086050267;18559.907200464047;2099.7795231500627;2117.2589593642256;2154.569086182754;2162.38372063961;2162.816160626349;165725;167265;168338.1583092089;168918;169395;9829.508524573886;12176.512939482498;12315.19951203169;12344.691382715324;12565.623031309075;16530.58673533147;16539.338426463048;16567.585810354598;16573.917130414513;16579.59595858594;8749.650013999497;8988.955055224682;9008.63965441388;9034.277257819358;9119.908800912039;8518.446300990416;8547.871781923277;8552.657893684307;8742.912570874338;8746.781330466789;7587.696492140363;7748.57382843949;7765;7780.377569794403;7785.766427007199;2125.4473836802545;2136.305700647265;2139.465133716571;2144.5067634444167;2284.6230371988436;883.6112110671413;953.8759961204998;956.3974695941512;965.2422848064236;983.5721461164417;19677.245185674015;20249.392518224184;20752.962351882346;20805;20823.79176208249;969.520087556664;973.9269554783419;1006.5772375602264;1006.9597216111273;1008.818412685709;9708.514574271258;10012.599496020222;10156.949215253877;10589;10614.416207108683;541650;545666;547943;559448;562243;117255;118598;122327;123317.38341308237;123738;1139.2537887683582;1156.7686462707325;1171.1333613126226;1172.876847930972;1177.6584790410684;13479.528216512375;17178.96926184433;17556.50769684591;17901.19444624969;18048.548786280448;46734.53265467302;51086.489135108175;51408.74295628498;52077.69805754886;52152.739236303576;49965.75017124891;64385.35614643981;65885.0234492966;67053;67537.31156720925;7264.200937896932;8003.799905002422;8180.754577362689;8403.915960840313;8437.34223719551;12748.553800616934;12780.041496887585;13029.739405211652;13063.150895191782;13070.411831467796;9403.482808445327;9819.950900245452;10310.123639490726;11168.776624467302;11387.975082242638;10421.374717516974;10429.374237545771;10444.529996150039;10740.409277489813;10822.729431764268;11175.209007684973;11500.137489688474;11576.88423115758;11581.378606995197;11603.477843496894;8347.916520834957;8355.83288334242;8517.48895066298;8591.31269498426;8606.612702428281;11198.272112312674;11254.21220514556;11376;12093.818592721293;12165.452554634885 21:58:15 INFO - PID 1644 | |3;mootools.html;1168.0888906652804;1364.972700545989;1407.7677183264761;1424.6936908564658;1436.561848636166;9496.430214187145;10842.457877106142;11594.072474202065;11605.419729013547;11651.883481165185;11136.276142050781;11217.775644487105;11238.887611123886;11251.987321141096;11286.322820630769;2472.5920223163157;2515.861627610481;2517.1315896015844;2519.3197836584113;2519.7858182054547;2094.581083783242;2167.5448155887284;2304.850184737995;2366.715994080713;2387.1525608409024;2490.9003639854473;2521.5461216980934;2522.7729504344607;2523.14213167523;2524.911628093017;2460.458699086198;2488.776010159085;2490.256820225849;2492.028109037477;2500.2999160235163;2424.599941009731;2444.633305004246;2450.803935685141;2451.2523680277686;2456.4595788926586;11239.943800281028;11498.540058397653;11503.712407189803;11505.884941150565;11548.422578871023;6403.103565500834;6617.669116544202;6622.1722284714415;6624.80125596228;6637.834054148685;4025.5571887092397;4151.97924010381;4270.957290427087;4327.091310824699;4332.263515202391;1252.7431876465303;1282.9743405131844;1293.1917551530294;1304.4716889659703;1307.8822905938512;16253.268602912898;16278;16284.75572866408;16303.918480407641;16330.75503867443;5579.6652200868075;5715.142728590745;5956.255468066492;5979.282486101695;5993.430624090704;13541.796873046913;13563.457461701619;13564.118332308368;13582.524611638546;13593.252371119486;6358.809235722936;6668.333166683293;6668.83327916806;6668.933310666928;6674;6022.5483088768515;6133.509319254449;6139.140520327158;6146.631202127886;6158.692065396713;3758.3986562149926;4129.69027322952;4282.993496528314;4471.754053527087;4581.335706322631;1398.9370478328499;1414.5261337452068;1418.1987177244823;1440.0855456785016;1442.1419255542935;711.839836036898;774.4307933668749;787.6376866641409;791.0744429018063;803.883436901646;6553.508486863504;6622.973439116945;6671.466282697373;6709.563878347892;6783.932160678429;915.7985243246475;924.8011677489372;933.355984370782;950.805084957582;959.4243453927587;4122.670186385082;4150.315197992298;4158.209940111369;4275.187714334266;4290.506591741927;14441.133531988115;14666.340014699364;14939;14945.626359340886;14973.251337433085;14171.503997360043;14840.851591484163;14996.550103496913;15106.697866042838;15129.924350378178;7847;8105.82465542493;8547.487150770849;8687.087855775178;8728.869066964;5983.252093488314;6478.611283323015;6684.866302674017;6709.93290067093;6737.25890152073;11846.940765296118;13123.278219697626;13220.603381898558;13333.799992999919;13386.464541418234;10514.94850514973;12347.259164450163;12808.719128087117;13389.933050334686;13432.932835335761;7152.213256541672;8209.055958564602;8387.245147936716;9020;9256.768580785265;10640.946795265974;10715.303505272132;10737.463126843782;10749.301295415773;10754.085902698358;7740.883886741591;8629.266512346521;8786;8955.328350373618;8989;8691.826163476822;8765.60554775024;8785;8862.955685221532;8915.974662913588;7807.726729564438;8058.428606421691;8101.554414507023;8160.673573057011;8212.753617391489;5780.132980053026;5912.674802885883;5942.584019118794;5968.343482217039;6143.293521245114;5767.769289228384;6073.969630151821;6155.630662160284;6168.753249869955;6220.720067596879 21:58:15 INFO - PID 1644 | |4;prototype.html;387.8991462219823;390.90227443139213;398.1499498570551;400.38740726688167;401.3598310694441;5444.863878403042;5873.7650493980245;5900.586958912878;5942.316633587139;5986.371430999737;4705.411823522059;4706.952475673076;5261.631685781997;5623.212750214963;5765.6828874411995;4465.9330110048295;4486.1700585391645;4487.865364039084;4495.213337665912;4509.053098849229;3551.591566969793;3741.906452338686;4413.911721765563;4447.977760111195;4492.707973981697;3639.5632524097146;3775.5846856845724;3906.0430194602363;4384.8026838792175;4440.666949978765;4433.1577000369825;4447.155040542319;4449.755263460508;4452;4454.487733910593;3949.545802232751;3955.4462375267485;3966.7421617594764;3977.687363170176;3984.8406063757416;5368.597355198376;5394.541463975567;5622.550195984311;5719.513841323493;5722.885542289173;4604.309353596954;5313.362396512443;5343.8510720195345;5356.946430535684;5368.194770784375;6254.34329395416;6266.655333956631;6270.432391902024;6273.435390814803;6289.811305660792;4193.1404062167485;4346.413234213373;4472.284434490466;4520.4575450946095;4524.932126018112;5075.365579302587;5934.821955341304;6358.713857876361;6540.215174179081;6556.623338980429;4054.675625949917;4568.725876447424;4572.702774319659;4575.986565910988;4595.149897269016;4139.19285739284;4665.300204969281;4805.207140821725;4834.226523756182;4848.587870031087;5295.364556253275;5423.403425623178;5444.5916556258435;5461.399246082928;5471.2887324647545;5385.703786291713;5771.759071799584;5827.475527202488;6027.246594175728;6365.331640177715;1545.1501674079211;1655.6854197702398;1678.7565802958502;1683.5791052236941;1692.7884014498188;1073.9636251017648;1144.7710457908452;1176.2413243458093;1180.864200616923;1193.498730533178;5194.844154675366;5392.299001129828;5504.2018907258225;5795.94204057954;5870.383609720918;5413.621046526705;5799.420057994251;6207.975684012178;6560.704768285439;6591.637459939653;5254.738862673007;5343.866403339947;5429.755660995264;5793.797217097382;5805.506531944748;2578.8839502222445;3278.1969916545804;3417.5898892133105;3422.8801991930163;3428.211511352353;6005.189299444606;6442.581232219891;6686.063951046857;6691.431228345645;6772.796816095525;6659.933400665931;6697.531172817953;6704.765333213314;6705.027770973182;6709.161354830646;5346.866328341823;6414.5189110815945;6594.604323740591;6666.600013997018;6675.599464032173;6197.318294987457;6280.403361680633;6285.43431091204;6397.520185986163;6530.510211734044;6422.197225346832;6426.967865160645;6444.355564443706;6451;6464.22429308486;5731.369549349485;6564.409203171737;6666.40129765457;6682.766103186365;6726.260111387841;4322.956770432255;4410.911781764411;4580.923482981563;4815.831445899376;4817.927731084106;5656.86862627468;5996.610220335664;6544.3782840630065;6569.343065693392;6570.014497825365;5427.158790387496;5464.398916119303;6308.243010838729;6332.321934687219;6410;5202.97398513005;5758.568107391879;6295.527835412271;6295.716692748929;6324.304326523987;5134.460881607527;5486.039943009878;6134.478569321657;6204;6253.37466253371;5230.038735474197;6329.367063293634;6332.493400527855;6340.682965851597;6354.4916406686425;5068.7972481101815;5130.692158470503;6136.9873970794315;6172.290186628596;6174.753009879554 21:58:15 INFO - PID 1644 | |5;yui.html;727.2436665867498;861.4271509446219;863.3913091270655;863.4430792139075;873.1704880363654;16221.83778162218;16314.83685163148;16674.666506669873;16682.916585417086;16724.66550668986;15124.319405626746;15409.46066887659;15611;15629.921850390732;15634.983726057799;978.1343510992787;983.2134292565954;995.0248756218909;995.144176008632;996.9252306077037;983.4050399508302;991.5438898106844;992.0724122945034;994.2543092680489;998.7652901568125;1044.5404022230193;1048.6172547020321;1049.218332342406;1049.2445439283742;1049.4542837724416;1047.4343854318658;1049.559185142242;1051.2483574244452;1051.279873286797;1060.8779990301098;772.3049255669943;772.8995230619984;773.957432341221;774.465618723085;782.6751897962339;16587.419440319652;16871.409500667418;17044.97730136196;17159.656806863917;17453.47639570815;16903.98576085439;16950.660986780196;17262.136893155417;17302.826971730374;17498.475045748648;13935.233562154077;14042.578722638336;14112.435502579885;14150.787738183935;14222.431102755878;3499.7900125992523;3519.5072689823446;3519.5072689823446;3521.0845180253064;3523.982380088083;76244.99999999945;84018.15981840226;118827;121544.1768373475;123224.38387808093;9546.713598592054;10377.429241391796;11082.944585277022;11225.550977960953;13325.06724529293;23884.40288992789;24661.01355945742;24814.751852481244;24967.875160624444;25023.87488062548;7962.7213047543055;7963.681452741941;8062.516249025077;8074.798130046678;8083.514989100555;3914.725969182187;3977.3835055566433;3977.602239776057;3979.820908059144;3984.4222587724616;4206.810693518799;4213.536510983789;4252.659787217015;4279.037216626234;4284.293091639907;937.4515908193783;953.2516974175239;956.0678338619929;959.9040095990345;961.6153538584622;643.3020173112226;643.7811144210991;648.6302807399832;654.3816093791321;679.7824696097201;10859.13126949842;11190.496427660775;11615.128865335133;11624.94187529057;11757.588484403004;629.9794333180186;645.7029766307458;650.9902351464732;672.0154972964631;685.2154283345542;5222.21666749983;5233.319668443154;5286.682799032071;5307.840764777063;5350.759215835296;92447.61328580076;94434.52782736042;95314;96617.5169124164;109052;62585.122446326684;79472;81962.59018704869;82183;85143.57428212819;57453.42546574481;63187;72786.63606682039;73596.52806943939;74385.62807185929;11700.239484433485;11914.880851191723;12372.690682733004;12462.065345098972;12564.685882853002;32899;38284.617153828105;38701;39005.219895602495;39612;51173;51403.97192056213;51751.48248517467;51806.74096629493;51914.22128668149;13266;15124.697506050037;15507.379704812132;15889.602759930633;16107.436239732022;16157.67684646324;16406.67186656284;16458.75311870299;16532.173391330143;16639.584010399838;16268.67462650764;16538.669226615642;16545;16619.3352265908;16746.665066698355;15751.291191896164;15909.124998125215;16201.837981620032;16350.754738678692;16379.986903208313;15757.842421576097;15805.52583422499;16033.839661603235;16196.919015404848;16300.42948496797;9812.362196457207;11393.373364465035;11528.596499122827;11701.88298117042;11711.414429278673;11149.163812713916;12482.438290277141;12569.994400448126;12635.1787133836;12700.682482938 21:58:15 INFO - PID 1644 | __end_tp_report 21:58:15 INFO - PID 1644 | __start_cc_report 21:58:15 INFO - PID 1644 | _x_x_mozilla_cycle_collect,1874 21:58:15 INFO - PID 1644 | __end_cc_report 21:58:15 INFO - PID 1644 | __startTimestamp1494219495707__endTimestamp 21:58:15 INFO - PID 1644 | 21:58:15 INFO - PID 1644 | ------- Summary: start ------- 21:58:15 INFO - PID 1644 | Number of tests: 6 21:58:15 INFO - PID 1644 | 21:58:15 INFO - PID 1644 | [#0] dojo.html Cycles:165 Average:5915.93 Median:6251.22 stddev:714.86 (11.4%) stddev-sans-first:669.77 21:58:15 INFO - PID 1644 | Values: 2656.6 3499.9 3502.1 3503.8 3542.1 5022.7 5835.1 5976.7 6088.4 6132.9 5900.9 5925.4 5936.5 5941.5 5942.2 5126.5 5152.5 5154.7 5158.5 5203.3 5309.4 5315.9 5319.8 5319.9 5329.7 4414.0 5214.1 5301.1 5310.7 5329.7 5263.9 5270.6 5282.1 5290.5 5293.2 5146.8 5230.2 5239.9 5245.4 5248.2 6057.8 6062.7 6078.8 6081.1 6119.4 6359.8 6379.5 6384.7 6402.0 6417.2 6297.7 6351.2 6358.9 6377.7 6380.2 5541.3 6072.7 6100.4 6102.1 6124.4 6459.1 6466.3 6628.7 6646.8 6655.1 6323.4 6327.4 6329.1 6331.0 6341.9 6324.1 6458.2 6461.4 6463.1 6485.9 5287.3 6292.6 6326.2 6338.0 6372.2 6380.2 6387.3 6392.2 6401.6 6411.0 4240.1 5049.0 5203.7 5209.2 5212.3 4551.6 4593.7 4617.1 4618.1 4706.0 6066.7 6118.0 6120.3 6141.4 6144.9 5386.9 6471.3 6471.4 6484.4 6486.2 6018.1 6032.3 6062.5 6066.6 6080.2 5573.7 5636.7 5709.5 5712.2 5725.5 6396.0 6402.5 6573.7 6664.7 6678.5 6434.5 6451.6 6458.3 6458.7 6476.6 6653.7 6655.9 6662.7 6670.0 6686.7 6267.5 6311.0 6343.0 6355.3 6361.7 5947.3 6067.8 6298.2 6447.5 6471.4 6268.0 6268.7 6279.7 6304.7 6316.4 6301.4 6304.3 6308.8 6310.3 6316.7 6175.0 6223.0 6244.4 6269.3 6297.1 6290.8 6302.5 6331.2 6337.5 6347.1 6251.2 6277.4 6291.0 6294.0 6296.3 21:58:15 INFO - PID 1644 | 21:58:15 INFO - PID 1644 | [#1] ext.html Cycles:180 Average:42277.79 Median:3125.66 stddev:75016.53 (2400.0%) stddev-sans-first:75162.24 21:58:15 INFO - PID 1644 | Values: 773.0 1078.8 1102.0 1103.1 1234.8 33267.0 34960.7 34996.3 35053.8 35140.8 35111.3 35503.8 35572.9 35590.6 35611.8 5837.2 5908.7 7477.8 7630.6 8038.6 8055.7 8165.0 8165.7 8176.7 8183.9 8126.0 8169.8 8182.4 8204.3 8218.3 5791.2 5874.2 8057.4 8138.9 8167.8 8027.0 8092.8 8100.9 8109.8 8149.7 34777.0 35079.8 35085.8 35107.8 35289.0 3525.8 3556.2 3557.3 3558.4 3558.7 28479.6 28550.6 28615.6 28625.6 28632.9 3049.7 3080.8 3118.7 3132.7 3134.7 285429.0 293395.5 293719.0 294043.5 295684.0 36423.5 37542.4 37554.6 37667.6 46713.3 46426.8 46591.0 46599.3 46633.0 46653.8 13467.9 13566.0 13572.4 13575.0 13589.7 10906.7 11085.3 11091.3 11092.6 11096.8 11692.3 11696.4 11707.5 11732.9 11857.2 4941.2 4945.2 4946.9 4951.2 4961.8 966.8 983.8 986.0 993.4 1019.6 23480.6 23602.5 23627.2 23657.4 23774.3 916.6 1005.3 1014.0 1070.0 1127.4 11267.2 11851.6 11972.2 12059.3 12093.0 298466.0 298654.0 300051.0 301290.0 301562.0 49294.0 49368.0 49378.5 49391.5 49391.8 1372.7 1446.2 1456.7 1502.5 1502.7 22705.7 22979.9 23251.5 23324.3 23338.4 31508.1 36069.9 36130.9 36138.8 36186.5 262912.0 263935.0 264115.0 264521.0 264599.0 25347.5 26155.2 30973.0 32275.7 32473.4 40352.6 40881.8 40985.0 41034.0 41039.0 27184.9 27435.9 29030.6 29156.4 29543.7 28285.4 28572.6 29056.0 29400.7 29462.4 27436.6 34569.8 34636.7 34653.0 34767.3 27728.0 28040.0 28645.1 29129.0 29436.0 18396.5 22803.4 23092.4 23242.4 23248.3 21:58:15 INFO - PID 1644 | 21:58:15 INFO - PID 1644 | [#2] jquery.html Cycles:180 Average:34940.79 Median:12557.53 stddev:93665.14 (745.9%) stddev-sans-first:93892.04 21:58:15 INFO - PID 1644 | Values: 423.4 429.4 437.9 439.5 451.0 18782.1 20620.6 21034.9 21322.6 21326.6 20694.5 22750.8 22830.4 23258.0 23516.4 5145.5 5858.7 6086.0 6135.5 6147.4 5737.7 6028.6 6149.1 6153.0 6161.5 5822.6 6083.5 6106.7 6107.8 6128.6 5902.9 6032.9 6039.5 6053.4 6074.1 5289.3 5366.6 5368.3 5370.5 5407.7 28178.7 28312.9 28412.9 28529.4 28683.7 2147.8 2221.1 2221.4 2221.8 2224.6 17538.6 17644.9 17725.8 18348.0 18559.9 2099.8 2117.3 2154.6 2162.4 2162.8 165725.0 167265.0 168338.2 168918.0 169395.0 9829.5 12176.5 12315.2 12344.7 12565.6 16530.6 16539.3 16567.6 16573.9 16579.6 8749.7 8989.0 9008.6 9034.3 9119.9 8518.4 8547.9 8552.7 8742.9 8746.8 7587.7 7748.6 7765.0 7780.4 7785.8 2125.4 2136.3 2139.5 2144.5 2284.6 883.6 953.9 956.4 965.2 983.6 19677.2 20249.4 20753.0 20805.0 20823.8 969.5 973.9 1006.6 1007.0 1008.8 9708.5 10012.6 10156.9 10589.0 10614.4 541650.0 545666.0 547943.0 559448.0 562243.0 117255.0 118598.0 122327.0 123317.4 123738.0 1139.3 1156.8 1171.1 1172.9 1177.7 13479.5 17179.0 17556.5 17901.2 18048.5 46734.5 51086.5 51408.7 52077.7 52152.7 49965.8 64385.4 65885.0 67053.0 67537.3 7264.2 8003.8 8180.8 8403.9 8437.3 12748.6 12780.0 13029.7 13063.2 13070.4 9403.5 9820.0 10310.1 11168.8 11388.0 10421.4 10429.4 10444.5 10740.4 10822.7 11175.2 11500.1 11576.9 11581.4 11603.5 8347.9 8355.8 8517.5 8591.3 8606.6 11198.3 11254.2 11376.0 12093.8 12165.5 21:58:15 INFO - PID 1644 | 21:58:15 INFO - PID 1644 | [#3] mootools.html Cycles:180 Average:7020.65 Median:4074.11 stddev:4431.10 (108.8%) stddev-sans-first:4421.70 21:58:15 INFO - PID 1644 | Values: 1168.1 1365.0 1407.8 1424.7 1436.6 9496.4 10842.5 11594.1 11605.4 11651.9 11136.3 11217.8 11238.9 11252.0 11286.3 2472.6 2515.9 2517.1 2519.3 2519.8 2094.6 2167.5 2304.9 2366.7 2387.2 2490.9 2521.5 2522.8 2523.1 2524.9 2460.5 2488.8 2490.3 2492.0 2500.3 2424.6 2444.6 2450.8 2451.3 2456.5 11239.9 11498.5 11503.7 11505.9 11548.4 6403.1 6617.7 6622.2 6624.8 6637.8 4025.6 4152.0 4271.0 4327.1 4332.3 1252.7 1283.0 1293.2 1304.5 1307.9 16253.3 16278.0 16284.8 16303.9 16330.8 5579.7 5715.1 5956.3 5979.3 5993.4 13541.8 13563.5 13564.1 13582.5 13593.3 6358.8 6668.3 6668.8 6668.9 6674.0 6022.5 6133.5 6139.1 6146.6 6158.7 3758.4 4129.7 4283.0 4471.8 4581.3 1398.9 1414.5 1418.2 1440.1 1442.1 711.8 774.4 787.6 791.1 803.9 6553.5 6623.0 6671.5 6709.6 6783.9 915.8 924.8 933.4 950.8 959.4 4122.7 4150.3 4158.2 4275.2 4290.5 14441.1 14666.3 14939.0 14945.6 14973.3 14171.5 14840.9 14996.6 15106.7 15129.9 7847.0 8105.8 8547.5 8687.1 8728.9 5983.3 6478.6 6684.9 6709.9 6737.3 11846.9 13123.3 13220.6 13333.8 13386.5 10514.9 12347.3 12808.7 13389.9 13432.9 7152.2 8209.1 8387.2 9020.0 9256.8 10640.9 10715.3 10737.5 10749.3 10754.1 7740.9 8629.3 8786.0 8955.3 8989.0 8691.8 8765.6 8785.0 8863.0 8916.0 7807.7 8058.4 8101.6 8160.7 8212.8 5780.1 5912.7 5942.6 5968.3 6143.3 5767.8 6074.0 6155.6 6168.8 6220.7 21:58:15 INFO - PID 1644 | 21:58:15 INFO - PID 1644 | [#4] prototype.html Cycles:180 Average:5083.70 Median:5428.46 stddev:1526.31 (28.1%) stddev-sans-first:1489.34 21:58:15 INFO - PID 1644 | Values: 387.9 390.9 398.1 400.4 401.4 5444.9 5873.8 5900.6 5942.3 5986.4 4705.4 4707.0 5261.6 5623.2 5765.7 4465.9 4486.2 4487.9 4495.2 4509.1 3551.6 3741.9 4413.9 4448.0 4492.7 3639.6 3775.6 3906.0 4384.8 4440.7 4433.2 4447.2 4449.8 4452.0 4454.5 3949.5 3955.4 3966.7 3977.7 3984.8 5368.6 5394.5 5622.6 5719.5 5722.9 4604.3 5313.4 5343.9 5356.9 5368.2 6254.3 6266.7 6270.4 6273.4 6289.8 4193.1 4346.4 4472.3 4520.5 4524.9 5075.4 5934.8 6358.7 6540.2 6556.6 4054.7 4568.7 4572.7 4576.0 4595.1 4139.2 4665.3 4805.2 4834.2 4848.6 5295.4 5423.4 5444.6 5461.4 5471.3 5385.7 5771.8 5827.5 6027.2 6365.3 1545.2 1655.7 1678.8 1683.6 1692.8 1074.0 1144.8 1176.2 1180.9 1193.5 5194.8 5392.3 5504.2 5795.9 5870.4 5413.6 5799.4 6208.0 6560.7 6591.6 5254.7 5343.9 5429.8 5793.8 5805.5 2578.9 3278.2 3417.6 3422.9 3428.2 6005.2 6442.6 6686.1 6691.4 6772.8 6659.9 6697.5 6704.8 6705.0 6709.2 5346.9 6414.5 6594.6 6666.6 6675.6 6197.3 6280.4 6285.4 6397.5 6530.5 6422.2 6427.0 6444.4 6451.0 6464.2 5731.4 6564.4 6666.4 6682.8 6726.3 4323.0 4410.9 4580.9 4815.8 4817.9 5656.9 5996.6 6544.4 6569.3 6570.0 5427.2 5464.4 6308.2 6332.3 6410.0 5203.0 5758.6 6295.5 6295.7 6324.3 5134.5 5486.0 6134.5 6204.0 6253.4 5230.0 6329.4 6332.5 6340.7 6354.5 5068.8 5130.7 6137.0 6172.3 6174.8 21:58:15 INFO - PID 1644 | 21:58:15 INFO - PID 1644 | [#5] yui.html Cycles:180 Average:19853.94 Median:24995.88 stddev:26830.00 (107.3%) stddev-sans-first:26866.82 21:58:15 INFO - PID 1644 | Values: 727.2 861.4 863.4 863.4 873.2 16221.8 16314.8 16674.7 16682.9 16724.7 15124.3 15409.5 15611.0 15629.9 15635.0 978.1 983.2 995.0 995.1 996.9 983.4 991.5 992.1 994.3 998.8 1044.5 1048.6 1049.2 1049.2 1049.5 1047.4 1049.6 1051.2 1051.3 1060.9 772.3 772.9 774.0 774.5 782.7 16587.4 16871.4 17045.0 17159.7 17453.5 16904.0 16950.7 17262.1 17302.8 17498.5 13935.2 14042.6 14112.4 14150.8 14222.4 3499.8 3519.5 3519.5 3521.1 3524.0 76245.0 84018.2 118827.0 121544.2 123224.4 9546.7 10377.4 11082.9 11225.6 13325.1 23884.4 24661.0 24814.8 24967.9 25023.9 7962.7 7963.7 8062.5 8074.8 8083.5 3914.7 3977.4 3977.6 3979.8 3984.4 4206.8 4213.5 4252.7 4279.0 4284.3 937.5 953.3 956.1 959.9 961.6 643.3 643.8 648.6 654.4 679.8 10859.1 11190.5 11615.1 11624.9 11757.6 630.0 645.7 651.0 672.0 685.2 5222.2 5233.3 5286.7 5307.8 5350.8 92447.6 94434.5 95314.0 96617.5 109052.0 62585.1 79472.0 81962.6 82183.0 85143.6 57453.4 63187.0 72786.6 73596.5 74385.6 11700.2 11914.9 12372.7 12462.1 12564.7 32899.0 38284.6 38701.0 39005.2 39612.0 51173.0 51404.0 51751.5 51806.7 51914.2 13266.0 15124.7 15507.4 15889.6 16107.4 16157.7 16406.7 16458.8 16532.2 16639.6 16268.7 16538.7 16545.0 16619.3 16746.7 15751.3 15909.1 16201.8 16350.8 16380.0 15757.8 15805.5 16033.8 16196.9 16300.4 9812.4 11393.4 11528.6 11701.9 11711.4 11149.2 12482.4 12570.0 12635.2 12700.7 21:58:15 INFO - PID 1644 | -------- Summary: end -------- 21:58:15 INFO - PID 1644 | 21:58:16 INFO - TEST-INFO | 1644: exit 0 21:58:16 INFO - TEST-OK | dromaeo_css | took 1118173ms 21:58:16 INFO - TEST-START | kraken 21:58:16 INFO - operating with platform_type : mac_ 21:58:16 INFO - Initialising browser for kraken test... 21:58:16 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpLE228D/profile http://localhost:49240/getInfo.html) 21:58:19 INFO - PID 1655 | __metrics Screen width/height:1600/1200 21:58:19 INFO - PID 1655 | colorDepth:24 21:58:19 INFO - PID 1655 | Browser inner width/height: 1024/691 21:58:19 INFO - PID 1655 | __metrics 21:58:19 INFO - PID 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:58:19 INFO - PID 1655 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:58:20 INFO - TEST-INFO | 1655: exit 0 21:58:20 INFO - Browser initialized. 21:58:20 INFO - Running cycle 1/1 for kraken test... 21:58:20 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpLE228D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:58:32 INFO - PID 1656 | RSS: Main: 239763456 21:58:32 INFO - PID 1656 | 21:58:34 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 21:58:35 INFO - PID 1656 | RSS: Main: 308285440 21:58:35 INFO - PID 1656 | 21:58:38 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 21:58:39 INFO - PID 1656 | RSS: Main: 291971072 21:58:39 INFO - PID 1656 | 21:58:42 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 21:58:43 INFO - PID 1656 | RSS: Main: 381734912 21:58:43 INFO - PID 1656 | 21:58:45 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 21:58:45 INFO - PID 1656 | RSS: Main: 286060544 21:58:45 INFO - PID 1656 | 21:58:47 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:58:48 INFO - PID 1656 | RSS: Main: 288505856 21:58:48 INFO - PID 1656 | 21:58:51 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 21:58:51 INFO - PID 1656 | RSS: Main: 351670272 21:58:51 INFO - PID 1656 | 21:58:54 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 21:58:55 INFO - PID 1656 | RSS: Main: 370196480 21:58:55 INFO - PID 1656 | 21:58:58 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 21:58:58 INFO - PID 1656 | RSS: Main: 380223488 21:58:58 INFO - PID 1656 | 21:59:00 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:59:00 INFO - PID 1656 | RSS: Main: 328790016 21:59:00 INFO - PID 1656 | 21:59:02 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:59:03 INFO - PID 1656 | RSS: Main: 332288000 21:59:03 INFO - PID 1656 | 21:59:05 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:59:05 INFO - PID 1656 | RSS: Main: 333905920 21:59:05 INFO - PID 1656 | 21:59:08 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:59:09 INFO - PID 1656 | RSS: Main: 350945280 21:59:09 INFO - PID 1656 | 21:59:12 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:59:13 INFO - PID 1656 | RSS: Main: 343633920 21:59:13 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 21:59:15 INFO - PID 1656 | RSS: Main: 386826240 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | __start_tp_report 21:59:15 INFO - PID 1656 | _x_x_mozilla_page_load 21:59:15 INFO - PID 1656 | _x_x_mozilla_page_load_details 21:59:15 INFO - PID 1656 | |i|pagename|runs| 21:59:15 INFO - PID 1656 | |0;ai-astar;111;111;109;110;110;112;110;110;109;108 21:59:15 INFO - PID 1656 | |1;audio-beat-detection;141;97;125;136;131;143;139;124;134;108 21:59:15 INFO - PID 1656 | |2;audio-dft;186;182;184;183;184;184;184;184;187;180 21:59:15 INFO - PID 1656 | |3;audio-fft;80;61;61;86;60;63;57;60;83;59 21:59:15 INFO - PID 1656 | |4;audio-oscillator;92;67;84;70;68;71;86;73;101;115 21:59:15 INFO - PID 1656 | |5;imaging-gaussian-blur;90;89;89;88;89;90;90;89;90;90 21:59:15 INFO - PID 1656 | |6;imaging-darkroom;96;97;96;96;96;95;96;97;96;96 21:59:15 INFO - PID 1656 | |7;imaging-desaturate;86;85;84;83;85;85;83;84;87;84 21:59:15 INFO - PID 1656 | |8;json-parse-financial;49;48;48;48;49;49;48;49;49;52 21:59:15 INFO - PID 1656 | |9;json-stringify-tinderbox;53;56;58;66;67;60;57;57;57;58 21:59:15 INFO - PID 1656 | |10;stanford-crypto-aes;81;82;82;83;93;90;82;80;80;81 21:59:15 INFO - PID 1656 | |11;stanford-crypto-ccm;138;135;138;136;135;136;151;149;187;135 21:59:15 INFO - PID 1656 | |12;stanford-crypto-pbkdf2;185;183;236;232;183;190;189;180;235;182 21:59:15 INFO - PID 1656 | |13;stanford-crypto-sha256-iterative;70;67;67;67;68;68;68;67;74;92 21:59:15 INFO - PID 1656 | __end_tp_report 21:59:15 INFO - PID 1656 | __start_cc_report 21:59:15 INFO - PID 1656 | _x_x_mozilla_cycle_collect,2171 21:59:15 INFO - PID 1656 | __end_cc_report 21:59:15 INFO - PID 1656 | __startTimestamp1494219555508__endTimestamp 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | ------- Summary: start ------- 21:59:15 INFO - PID 1656 | Number of tests: 14 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | [#0] ai-astar Cycles:10 Average:110.00 Median:110.00 stddev:1.15 (1.0%) stddev-sans-first:1.17 21:59:15 INFO - PID 1656 | Values: 111.0 111.0 109.0 110.0 110.0 112.0 110.0 110.0 109.0 108.0 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | [#1] audio-beat-detection Cycles:10 Average:127.80 Median:137.50 stddev:14.94 (10.9%) stddev-sans-first:15.07 21:59:15 INFO - PID 1656 | Values: 141.0 97.0 125.0 136.0 131.0 143.0 139.0 124.0 134.0 108.0 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:1.93 (1.1%) stddev-sans-first:1.88 21:59:15 INFO - PID 1656 | Values: 186.0 182.0 184.0 183.0 184.0 184.0 184.0 184.0 187.0 180.0 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | [#3] audio-fft Cycles:10 Average:67.00 Median:62.00 stddev:11.23 (18.1%) stddev-sans-first:10.89 21:59:15 INFO - PID 1656 | Values: 80.0 61.0 61.0 86.0 60.0 63.0 57.0 60.0 83.0 59.0 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | [#4] audio-oscillator Cycles:10 Average:82.70 Median:72.00 stddev:16.10 (22.4%) stddev-sans-first:16.72 21:59:15 INFO - PID 1656 | Values: 92.0 67.0 84.0 70.0 68.0 71.0 86.0 73.0 101.0 115.0 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:89.40 Median:90.00 stddev:0.70 (0.8%) stddev-sans-first:0.71 21:59:15 INFO - PID 1656 | Values: 90.0 89.0 89.0 88.0 89.0 90.0 90.0 89.0 90.0 90.0 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 21:59:15 INFO - PID 1656 | Values: 96.0 97.0 96.0 96.0 96.0 95.0 96.0 97.0 96.0 96.0 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.26 (1.5%) stddev-sans-first:1.24 21:59:15 INFO - PID 1656 | Values: 86.0 85.0 84.0 83.0 85.0 85.0 83.0 84.0 87.0 84.0 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | [#8] json-parse-financial Cycles:10 Average:48.90 Median:49.00 stddev:1.20 (2.4%) stddev-sans-first:1.27 21:59:15 INFO - PID 1656 | Values: 49.0 48.0 48.0 48.0 49.0 49.0 48.0 49.0 49.0 52.0 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:58.00 stddev:4.38 (7.6%) stddev-sans-first:4.10 21:59:15 INFO - PID 1656 | Values: 53.0 56.0 58.0 66.0 67.0 60.0 57.0 57.0 57.0 58.0 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:82.00 stddev:4.43 (5.4%) stddev-sans-first:4.61 21:59:15 INFO - PID 1656 | Values: 81.0 82.0 82.0 83.0 93.0 90.0 82.0 80.0 80.0 81.0 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:144.00 Median:138.00 stddev:16.21 (11.7%) stddev-sans-first:17.05 21:59:15 INFO - PID 1656 | Values: 138.0 135.0 138.0 136.0 135.0 136.0 151.0 149.0 187.0 135.0 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.50 Median:189.50 stddev:24.25 (12.8%) stddev-sans-first:25.14 21:59:15 INFO - PID 1656 | Values: 185.0 183.0 236.0 232.0 183.0 190.0 189.0 180.0 235.0 182.0 21:59:15 INFO - PID 1656 | 21:59:15 INFO - PID 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:68.00 stddev:7.76 (11.4%) stddev-sans-first:8.22 21:59:15 INFO - PID 1656 | Values: 70.0 67.0 67.0 67.0 68.0 68.0 68.0 67.0 74.0 92.0 21:59:15 INFO - PID 1656 | -------- Summary: end -------- 21:59:15 INFO - PID 1656 | 21:59:16 INFO - TEST-INFO | 1656: exit 0 21:59:16 INFO - TEST-OK | kraken | took 60241ms 21:59:16 INFO - SUITE-END | took 1178s 21:59:17 INFO - Completed test suite (00:19:38) 21:59:17 INFO - javascript benchmark 21:59:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7608.323081673273, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2656.5749480083186, 3499.912502187445, 3502.12446888278, 3503.7722548034358, 3542.0790594445452, 5022.673526220798, 5835.0663893777, 5976.671283079429, 6088.421599948003, 6132.908006379902, 5900.852478688035, 5925.437083477073, 5936.465718085371, 5941.5246780257585, 5942.1978032965535, 5126.461721519243, 5152.484751524837, 5154.69071855688, 5158.458361872005, 5203.2975548301065, 5309.389420216667, 5315.867103322409, 5319.840404787843, 5319.893602127955, 5329.653572517774, 4414.0, 5214.139666954967, 5301.125314323132, 5310.70791106489, 5329.653572517794, 5263.86840328991, 5270.604704647148, 5282.128448805981, 5290.523852853262, 5293.179557168645, 5146.794128234867, 5230.163173892159, 5239.947600523984, 5245.423003469654, 5248.239005344242, 6057.818265452043, 6062.727177277033, 6078.756849726005, 6081.148639190517, 6119.357467465941, 6359.8092057238355, 6379.521535884781, 6384.680765961683, 6402.0, 6417.2299324081405, 6297.716602752887, 6351.237851457809, 6358.936410635881, 6377.71300291488, 6380.234371875405, 5541.27963364764, 6072.726727297194, 6100.420460056288, 6102.0541816018585, 6124.38756124384, 6459.128017717642, 6466.321036291121, 6628.734850606018, 6646.767363142363, 6655.134832471747, 6323.399277068671, 6327.430531252117, 6329.113924050636, 6331.018692102732, 6341.904871426932, 6324.146240257506, 6458.160439142881, 6461.353864613501, 6463.095166676669, 6485.935140648533, 5287.3126493556365, 6292.590981586182, 6326.2092238470195, 6338.049292606146, 6372.203474565679, 6380.202474690664, 6387.329330420332, 6392.232932048184, 6401.64790936503, 6410.967945160244, 4240.1307731914885, 5048.974755126201, 5203.661761985459, 5209.244659524424, 5212.270282160501, 4551.613112885442, 4593.724376537419, 4617.1227466781875, 4618.145643056046, 4706.0, 6066.726997285133, 6118.0, 6120.294761999619, 6141.355157708465, 6144.8771024579255, 5386.91919621206, 6471.255805582324, 6471.417572418505, 6484.383983521558, 6486.156799616114, 6018.102021442119, 6032.336442991267, 6062.484688801501, 6066.575339726265, 6080.179175811209, 5573.749181286852, 5636.689982051026, 5709.486146246858, 5712.171735098387, 5725.51333136688, 6395.968020159869, 6402.519811014193, 6573.671316434064, 6664.700088496126, 6678.499112566674, 6434.549581529435, 6451.645159516272, 6458.321876203119, 6458.709358078804, 6476.6114033158165, 6653.733850646114, 6655.866882662416, 6662.700178491883, 6670.0, 6686.665666716742, 6267.5299352549655, 6311.0217916223055, 6343.0485427186295, 6355.269144048493, 6361.745530178741, 5947.3457919627945, 6067.847109049267, 6298.212723409574, 6447.516436267392, 6471.352864713491, 6268.0, 6268.655223962726, 6279.654618995999, 6304.7162877671535, 6316.431521163117, 6301.40136687014, 6304.338044505261, 6308.842278943063, 6310.337414571588, 6316.684165791784, 6175.0, 6223.0, 6244.406781355764, 6269.31037585856, 6297.149884765498, 6290.811275661737, 6302.495800336053, 6331.176946996861, 6337.493000560036, 6347.079673447417, 6251.218597675291, 6277.435030847063, 6290.99344104941, 6293.96853015732, 6296.33888441707], "value": 5867.448968019187, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.9574873381963, 1078.7896360209756, 1101.9696583694251, 1103.1119948441506, 1234.7900856854335, 33267.0, 34960.65039349606, 34996.30007399851, 35053.82473087634, 35140.82429587848, 35111.297774044506, 35503.822480887626, 35572.93281201561, 35590.57637694489, 35611.82194089026, 5837.153612726173, 5908.734106965197, 7477.813054673622, 7630.580318082503, 8038.598070096501, 8055.677772889107, 8165.020197576299, 8165.6733730651, 8176.672933082669, 8183.877241841377, 8125.9842519685035, 8169.79575510614, 8182.386321025917, 8204.261616454518, 8218.342532597379, 5791.21818554494, 5874.1776151338845, 8057.35541156706, 8138.918610813876, 8167.79580510489, 8026.959865200637, 8092.757217283491, 8100.918990810134, 8109.75670729879, 8149.714759983432, 34777.0, 35079.82460087709, 35085.82457087698, 35107.82446087779, 35289.0, 3525.823708814549, 3556.199855032623, 3557.2529768748595, 3558.430651095812, 3558.6975107115927, 28479.57280640792, 28550.571741423897, 28615.57076643852, 28625.57061644077, 28632.856835715895, 3049.7255247027874, 3080.7843450958444, 3118.656947735747, 3132.6710695377114, 3134.7335476484527, 285429.0, 293395.5330223357, 293719.0, 294043.52978235186, 295684.0, 36423.4536481953, 37542.43686344707, 37554.624453755656, 37667.62332376696, 46713.299300510516, 46426.83932901704, 46591.0, 46599.30101048487, 46633.0, 46653.76673116613, 13467.912473566614, 13566.0, 13572.389242484112, 13575.0, 13589.728205435837, 10906.67279981586, 11085.334879907232, 11091.279066860629, 11092.611758588411, 11096.778064438666, 11692.298462092302, 11696.356700381391, 11707.473163707653, 11732.94133529327, 11857.16999810005, 4941.209406494943, 4945.23348880924, 4946.876328091755, 4951.207806750903, 4961.82633607822, 966.7679756858304, 983.8376667849868, 985.9605615775432, 993.3940296419179, 1019.5564929255797, 23480.64779028316, 23602.527949440915, 23627.173048943205, 23657.40856478602, 23774.286771396884, 916.5921165081608, 1005.2912696549062, 1014.0062738516295, 1069.989300106989, 1127.3686735428023, 11267.15496337778, 11851.644450666494, 11972.161948663685, 12059.276443413422, 12093.0, 298466.0, 298654.0, 300051.0, 301290.0, 301562.0, 49294.01411971812, 49368.0, 49378.50621493811, 49391.50608493941, 49391.753041234566, 1372.7302245423143, 1446.1901335252082, 1456.6940942402002, 1502.451605164129, 1502.7295086884246, 22705.659415108457, 22979.88510057439, 23251.534969300857, 23324.3002709919, 23338.416539585967, 31508.054758357284, 36069.91790246297, 36130.916072517866, 36138.8193059033, 36186.45720314145, 262912.0, 263935.0, 264115.0, 264521.0, 264599.0, 25347.493050139263, 26155.215343539723, 30973.0, 32275.677243227266, 32473.350532988734, 40352.596474034886, 40881.79559102185, 40985.0, 41034.0, 41038.9740256496, 27184.864075679496, 27435.86282068577, 29030.564541531472, 29156.416871662874, 29543.681456562408, 28285.43429131447, 28572.57141142843, 29056.0, 29400.7059929398, 29462.41075178527, 27436.58845117285, 34569.82715086408, 34636.65363346334, 34653.0, 34767.30465390729, 27728.0, 28040.0, 28645.14064578066, 29129.0, 29436.0, 18396.540086497946, 22803.429914252276, 23092.4226894329, 23242.418939526648, 23248.302550923498], "value": 16363.806370051387, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.37998353522283, 429.4117059628306, 437.91460665170297, 439.5318985280677, 450.98421555245574, 18782.06089695515, 20620.587588248225, 21034.894825525887, 21322.57354852902, 21326.573468530638, 20694.48263793406, 22750.77249227507, 22830.429239269026, 23257.953392097355, 23516.412089697766, 5145.511176438233, 5858.677772722509, 6086.026235802273, 6135.539834512407, 6147.4159954804445, 5737.741801618917, 6028.577999540012, 6149.108379285001, 6153.0, 6161.507079433634, 5822.621529600584, 6083.513318934474, 6106.694665266719, 6107.847303817418, 6128.57100003, 5902.88194236115, 6032.939670603281, 6039.486643635296, 6053.394660533933, 6074.119252708332, 5289.338832645919, 5366.570674346043, 5368.32895888014, 5370.489803468664, 5407.729613519308, 28178.71821281802, 28312.858435707894, 28412.863485460555, 28529.429411411656, 28683.71316286852, 2147.7744836792226, 2221.111555377862, 2221.389117992549, 2221.8444708870384, 2224.5773303072365, 17538.649227015514, 17644.853084549464, 17725.822741772547, 18347.99086050267, 18559.907200464047, 2099.7795231500627, 2117.2589593642256, 2154.569086182754, 2162.38372063961, 2162.816160626349, 165725.0, 167265.0, 168338.1583092089, 168918.0, 169395.0, 9829.508524573886, 12176.512939482498, 12315.19951203169, 12344.691382715324, 12565.623031309075, 16530.58673533147, 16539.338426463048, 16567.585810354598, 16573.917130414513, 16579.59595858594, 8749.650013999497, 8988.955055224682, 9008.63965441388, 9034.277257819358, 9119.908800912039, 8518.446300990416, 8547.871781923277, 8552.657893684307, 8742.912570874338, 8746.781330466789, 7587.696492140363, 7748.57382843949, 7765.0, 7780.377569794403, 7785.766427007199, 2125.4473836802545, 2136.305700647265, 2139.465133716571, 2144.5067634444167, 2284.6230371988436, 883.6112110671413, 953.8759961204998, 956.3974695941512, 965.2422848064236, 983.5721461164417, 19677.245185674015, 20249.392518224184, 20752.962351882346, 20805.0, 20823.79176208249, 969.520087556664, 973.9269554783419, 1006.5772375602264, 1006.9597216111273, 1008.818412685709, 9708.514574271258, 10012.599496020222, 10156.949215253877, 10589.0, 10614.416207108683, 541650.0, 545666.0, 547943.0, 559448.0, 562243.0, 117255.0, 118598.0, 122327.0, 123317.38341308237, 123738.0, 1139.2537887683582, 1156.7686462707325, 1171.1333613126226, 1172.876847930972, 1177.6584790410684, 13479.528216512375, 17178.96926184433, 17556.50769684591, 17901.19444624969, 18048.548786280448, 46734.53265467302, 51086.489135108175, 51408.74295628498, 52077.69805754886, 52152.739236303576, 49965.75017124891, 64385.35614643981, 65885.0234492966, 67053.0, 67537.31156720925, 7264.200937896932, 8003.799905002422, 8180.754577362689, 8403.915960840313, 8437.34223719551, 12748.553800616934, 12780.041496887585, 13029.739405211652, 13063.150895191782, 13070.411831467796, 9403.482808445327, 9819.950900245452, 10310.123639490726, 11168.776624467302, 11387.975082242638, 10421.374717516974, 10429.374237545771, 10444.529996150039, 10740.409277489813, 10822.729431764268, 11175.209007684973, 11500.137489688474, 11576.88423115758, 11581.378606995197, 11603.477843496894, 8347.916520834957, 8355.83288334242, 8517.48895066298, 8591.31269498426, 8606.612702428281, 11198.272112312674, 11254.21220514556, 11376.0, 12093.818592721293, 12165.452554634885], "value": 10124.363182585665, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1168.0888906652804, 1364.972700545989, 1407.7677183264761, 1424.6936908564658, 1436.561848636166, 9496.430214187145, 10842.457877106142, 11594.072474202065, 11605.419729013547, 11651.883481165185, 11136.276142050781, 11217.775644487105, 11238.887611123886, 11251.987321141096, 11286.322820630769, 2472.5920223163157, 2515.861627610481, 2517.1315896015844, 2519.3197836584113, 2519.7858182054547, 2094.581083783242, 2167.5448155887284, 2304.850184737995, 2366.715994080713, 2387.1525608409024, 2490.9003639854473, 2521.5461216980934, 2522.7729504344607, 2523.14213167523, 2524.911628093017, 2460.458699086198, 2488.776010159085, 2490.256820225849, 2492.028109037477, 2500.2999160235163, 2424.599941009731, 2444.633305004246, 2450.803935685141, 2451.2523680277686, 2456.4595788926586, 11239.943800281028, 11498.540058397653, 11503.712407189803, 11505.884941150565, 11548.422578871023, 6403.103565500834, 6617.669116544202, 6622.1722284714415, 6624.80125596228, 6637.834054148685, 4025.5571887092397, 4151.97924010381, 4270.957290427087, 4327.091310824699, 4332.263515202391, 1252.7431876465303, 1282.9743405131844, 1293.1917551530294, 1304.4716889659703, 1307.8822905938512, 16253.268602912898, 16278.0, 16284.75572866408, 16303.918480407641, 16330.75503867443, 5579.6652200868075, 5715.142728590745, 5956.255468066492, 5979.282486101695, 5993.430624090704, 13541.796873046913, 13563.457461701619, 13564.118332308368, 13582.524611638546, 13593.252371119486, 6358.809235722936, 6668.333166683293, 6668.83327916806, 6668.933310666928, 6674.0, 6022.5483088768515, 6133.509319254449, 6139.140520327158, 6146.631202127886, 6158.692065396713, 3758.3986562149926, 4129.69027322952, 4282.993496528314, 4471.754053527087, 4581.335706322631, 1398.9370478328499, 1414.5261337452068, 1418.1987177244823, 1440.0855456785016, 1442.1419255542935, 711.839836036898, 774.4307933668749, 787.6376866641409, 791.0744429018063, 803.883436901646, 6553.508486863504, 6622.973439116945, 6671.466282697373, 6709.563878347892, 6783.932160678429, 915.7985243246475, 924.8011677489372, 933.355984370782, 950.805084957582, 959.4243453927587, 4122.670186385082, 4150.315197992298, 4158.209940111369, 4275.187714334266, 4290.506591741927, 14441.133531988115, 14666.340014699364, 14939.0, 14945.626359340886, 14973.251337433085, 14171.503997360043, 14840.851591484163, 14996.550103496913, 15106.697866042838, 15129.924350378178, 7847.0, 8105.82465542493, 8547.487150770849, 8687.087855775178, 8728.869066964, 5983.252093488314, 6478.611283323015, 6684.866302674017, 6709.93290067093, 6737.25890152073, 11846.940765296118, 13123.278219697626, 13220.603381898558, 13333.799992999919, 13386.464541418234, 10514.94850514973, 12347.259164450163, 12808.719128087117, 13389.933050334686, 13432.932835335761, 7152.213256541672, 8209.055958564602, 8387.245147936716, 9020.0, 9256.768580785265, 10640.946795265974, 10715.303505272132, 10737.463126843782, 10749.301295415773, 10754.085902698358, 7740.883886741591, 8629.266512346521, 8786.0, 8955.328350373618, 8989.0, 8691.826163476822, 8765.60554775024, 8785.0, 8862.955685221532, 8915.974662913588, 7807.726729564438, 8058.428606421691, 8101.554414507023, 8160.673573057011, 8212.753617391489, 5780.132980053026, 5912.674802885883, 5942.584019118794, 5968.343482217039, 6143.293521245114, 5767.769289228384, 6073.969630151821, 6155.630662160284, 6168.753249869955, 6220.720067596879], "value": 5334.805264494572, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.8991462219823, 390.90227443139213, 398.1499498570551, 400.38740726688167, 401.3598310694441, 5444.863878403042, 5873.7650493980245, 5900.586958912878, 5942.316633587139, 5986.371430999737, 4705.411823522059, 4706.952475673076, 5261.631685781997, 5623.212750214963, 5765.6828874411995, 4465.9330110048295, 4486.1700585391645, 4487.865364039084, 4495.213337665912, 4509.053098849229, 3551.591566969793, 3741.906452338686, 4413.911721765563, 4447.977760111195, 4492.707973981697, 3639.5632524097146, 3775.5846856845724, 3906.0430194602363, 4384.8026838792175, 4440.666949978765, 4433.1577000369825, 4447.155040542319, 4449.755263460508, 4452.0, 4454.487733910593, 3949.545802232751, 3955.4462375267485, 3966.7421617594764, 3977.687363170176, 3984.8406063757416, 5368.597355198376, 5394.541463975567, 5622.550195984311, 5719.513841323493, 5722.885542289173, 4604.309353596954, 5313.362396512443, 5343.8510720195345, 5356.946430535684, 5368.194770784375, 6254.34329395416, 6266.655333956631, 6270.432391902024, 6273.435390814803, 6289.811305660792, 4193.1404062167485, 4346.413234213373, 4472.284434490466, 4520.4575450946095, 4524.932126018112, 5075.365579302587, 5934.821955341304, 6358.713857876361, 6540.215174179081, 6556.623338980429, 4054.675625949917, 4568.725876447424, 4572.702774319659, 4575.986565910988, 4595.149897269016, 4139.19285739284, 4665.300204969281, 4805.207140821725, 4834.226523756182, 4848.587870031087, 5295.364556253275, 5423.403425623178, 5444.5916556258435, 5461.399246082928, 5471.2887324647545, 5385.703786291713, 5771.759071799584, 5827.475527202488, 6027.246594175728, 6365.331640177715, 1545.1501674079211, 1655.6854197702398, 1678.7565802958502, 1683.5791052236941, 1692.7884014498188, 1073.9636251017648, 1144.7710457908452, 1176.2413243458093, 1180.864200616923, 1193.498730533178, 5194.844154675366, 5392.299001129828, 5504.2018907258225, 5795.94204057954, 5870.383609720918, 5413.621046526705, 5799.420057994251, 6207.975684012178, 6560.704768285439, 6591.637459939653, 5254.738862673007, 5343.866403339947, 5429.755660995264, 5793.797217097382, 5805.506531944748, 2578.8839502222445, 3278.1969916545804, 3417.5898892133105, 3422.8801991930163, 3428.211511352353, 6005.189299444606, 6442.581232219891, 6686.063951046857, 6691.431228345645, 6772.796816095525, 6659.933400665931, 6697.531172817953, 6704.765333213314, 6705.027770973182, 6709.161354830646, 5346.866328341823, 6414.5189110815945, 6594.604323740591, 6666.600013997018, 6675.599464032173, 6197.318294987457, 6280.403361680633, 6285.43431091204, 6397.520185986163, 6530.510211734044, 6422.197225346832, 6426.967865160645, 6444.355564443706, 6451.0, 6464.22429308486, 5731.369549349485, 6564.409203171737, 6666.40129765457, 6682.766103186365, 6726.260111387841, 4322.956770432255, 4410.911781764411, 4580.923482981563, 4815.831445899376, 4817.927731084106, 5656.86862627468, 5996.610220335664, 6544.3782840630065, 6569.343065693392, 6570.014497825365, 5427.158790387496, 5464.398916119303, 6308.243010838729, 6332.321934687219, 6410.0, 5202.97398513005, 5758.568107391879, 6295.527835412271, 6295.716692748929, 6324.304326523987, 5134.460881607527, 5486.039943009878, 6134.478569321657, 6204.0, 6253.37466253371, 5230.038735474197, 6329.367063293634, 6332.493400527855, 6340.682965851597, 6354.4916406686425, 5068.7972481101815, 5130.692158470503, 6136.9873970794315, 6172.290186628596, 6174.753009879554], "value": 4630.077542338847, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.2436665867498, 861.4271509446219, 863.3913091270655, 863.4430792139075, 873.1704880363654, 16221.83778162218, 16314.83685163148, 16674.666506669873, 16682.916585417086, 16724.66550668986, 15124.319405626746, 15409.46066887659, 15611.0, 15629.921850390732, 15634.983726057799, 978.1343510992787, 983.2134292565954, 995.0248756218909, 995.144176008632, 996.9252306077037, 983.4050399508302, 991.5438898106844, 992.0724122945034, 994.2543092680489, 998.7652901568125, 1044.5404022230193, 1048.6172547020321, 1049.218332342406, 1049.2445439283742, 1049.4542837724416, 1047.4343854318658, 1049.559185142242, 1051.2483574244452, 1051.279873286797, 1060.8779990301098, 772.3049255669943, 772.8995230619984, 773.957432341221, 774.465618723085, 782.6751897962339, 16587.419440319652, 16871.409500667418, 17044.97730136196, 17159.656806863917, 17453.47639570815, 16903.98576085439, 16950.660986780196, 17262.136893155417, 17302.826971730374, 17498.475045748648, 13935.233562154077, 14042.578722638336, 14112.435502579885, 14150.787738183935, 14222.431102755878, 3499.7900125992523, 3519.5072689823446, 3519.5072689823446, 3521.0845180253064, 3523.982380088083, 76244.99999999945, 84018.15981840226, 118827.0, 121544.1768373475, 123224.38387808093, 9546.713598592054, 10377.429241391796, 11082.944585277022, 11225.550977960953, 13325.06724529293, 23884.40288992789, 24661.01355945742, 24814.751852481244, 24967.875160624444, 25023.87488062548, 7962.7213047543055, 7963.681452741941, 8062.516249025077, 8074.798130046678, 8083.514989100555, 3914.725969182187, 3977.3835055566433, 3977.602239776057, 3979.820908059144, 3984.4222587724616, 4206.810693518799, 4213.536510983789, 4252.659787217015, 4279.037216626234, 4284.293091639907, 937.4515908193783, 953.2516974175239, 956.0678338619929, 959.9040095990345, 961.6153538584622, 643.3020173112226, 643.7811144210991, 648.6302807399832, 654.3816093791321, 679.7824696097201, 10859.13126949842, 11190.496427660775, 11615.128865335133, 11624.94187529057, 11757.588484403004, 629.9794333180186, 645.7029766307458, 650.9902351464732, 672.0154972964631, 685.2154283345542, 5222.21666749983, 5233.319668443154, 5286.682799032071, 5307.840764777063, 5350.759215835296, 92447.61328580076, 94434.52782736042, 95314.0, 96617.5169124164, 109052.0, 62585.122446326684, 79472.0, 81962.59018704869, 82183.0, 85143.57428212819, 57453.42546574481, 63187.0, 72786.63606682039, 73596.52806943939, 74385.62807185929, 11700.239484433485, 11914.880851191723, 12372.690682733004, 12462.065345098972, 12564.685882853002, 32899.0, 38284.617153828105, 38701.0, 39005.219895602495, 39612.0, 51173.0, 51403.97192056213, 51751.48248517467, 51806.74096629493, 51914.22128668149, 13266.0, 15124.697506050037, 15507.379704812132, 15889.602759930633, 16107.436239732022, 16157.67684646324, 16406.67186656284, 16458.75311870299, 16532.173391330143, 16639.584010399838, 16268.67462650764, 16538.669226615642, 16545.0, 16619.3352265908, 16746.665066698355, 15751.291191896164, 15909.124998125215, 16201.837981620032, 16350.754738678692, 16379.986903208313, 15757.842421576097, 15805.52583422499, 16033.839661603235, 16196.919015404848, 16300.42948496797, 9812.362196457207, 11393.373364465035, 11528.596499122827, 11701.88298117042, 11711.414429278673, 11149.163812713916, 12482.438290277141, 12569.994400448126, 12635.1787133836, 12700.682482938], "value": 8077.845583063453, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1446.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 109.0, 110.0, 110.0, 112.0, 110.0, 110.0, 109.0, 108.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 97.0, 125.0, 136.0, 131.0, 143.0, 139.0, 124.0, 134.0, 108.0], "value": 127.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 184.0, 183.0, 184.0, 184.0, 184.0, 184.0, 187.0, 180.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 61.0, 61.0, 86.0, 60.0, 63.0, 57.0, 60.0, 83.0, 59.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 67.0, 84.0, 70.0, 68.0, 71.0, 86.0, 73.0, 101.0, 115.0], "value": 82.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 89.0, 88.0, 89.0, 90.0, 90.0, 89.0, 90.0, 90.0], "value": 89.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 96.0, 95.0, 96.0, 97.0, 96.0, 96.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 83.0, 85.0, 85.0, 83.0, 84.0, 87.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 48.0, 48.0, 49.0, 49.0, 48.0, 49.0, 49.0, 52.0], "value": 48.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 56.0, 58.0, 66.0, 67.0, 60.0, 57.0, 57.0, 57.0, 58.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 82.0, 83.0, 93.0, 90.0, 82.0, 80.0, 80.0, 81.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 138.0, 136.0, 135.0, 136.0, 151.0, 149.0, 187.0, 135.0], "value": 144.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 236.0, 232.0, 183.0, 190.0, 189.0, 180.0, 235.0, 182.0], "value": 199.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 67.0, 67.0, 68.0, 68.0, 68.0, 67.0, 74.0, 92.0], "value": 70.8, "unit": "score"}]}]} 21:59:17 INFO - Return code: 0 21:59:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:59:17 INFO - # TBPL SUCCESS # 21:59:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:59:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:59:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:59:17 INFO - 2017-05-07 21:59:17.314 screenresolution[1661:9739] starting screenresolution argv=screenresolution get 21:59:17 INFO - 2017-05-07 21:59:17.317 screenresolution[1661:9739] Display 0: 1600x1200x32@60 21:59:17 INFO - 2017-05-07 21:59:17.322 screenresolution[1662:9742] starting screenresolution argv=screenresolution list 21:59:17 INFO - Available Modes on Display 0 21:59:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:59:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:59:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:59:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:59:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:59:17 INFO - Intel Iris: 21:59:17 INFO - Chipset Model: Intel Iris 21:59:17 INFO - Type: GPU 21:59:17 INFO - Bus: Built-In 21:59:17 INFO - VRAM (Dynamic, Max): 1536 MB 21:59:17 INFO - Vendor: Intel (0x8086) 21:59:17 INFO - Device ID: 0x0a2e 21:59:17 INFO - Revision ID: 0x0009 21:59:17 INFO - Displays: 21:59:17 INFO - Display: 21:59:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:59:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:59:17 INFO - Display Serial Number: 1600x1200 60 21:59:17 INFO - Main Display: Yes 21:59:17 INFO - Mirror: Off 21:59:17 INFO - Online: Yes 21:59:17 INFO - Rotation: Supported 21:59:17 INFO - Return code: 0 21:59:17 INFO - Running post-action listener: _resource_record_post_action 21:59:17 INFO - [mozharness: 2017-05-08 04:59:17.558291Z] Finished run-tests step (success) 21:59:17 INFO - Running post-run listener: _resource_record_post_run 21:59:17 INFO - Total resource usage - Wall time: 1199s; CPU: 53.0%; Read bytes: 37971456; Write bytes: 530520064; Read time: 378; Write time: 1795 21:59:17 INFO - TinderboxPrint: CPU usage
53.2% 21:59:17 INFO - TinderboxPrint: I/O read bytes / time
37,971,456 / 378 21:59:17 INFO - TinderboxPrint: I/O write bytes / time
530,520,064 / 1,795 21:59:17 INFO - TinderboxPrint: CPU idle
2,243.0 (46.8%) 21:59:17 INFO - TinderboxPrint: CPU system
954.3 (19.9%) 21:59:17 INFO - TinderboxPrint: CPU user
1,600.3 (33.4%) 21:59:17 INFO - TinderboxPrint: Swap in / out
246,394,880 / 0 21:59:17 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207856128; Write bytes: 204781056; Read time: 16498; Write time: 397 21:59:17 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 34358784; Write bytes: 318513664; Read time: 349; Write time: 1373 21:59:18 INFO - Running post-run listener: _upload_blobber_files 21:59:18 INFO - Blob upload gear active. 21:59:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:59:18 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 21:59:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:59:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:59:18 INFO - (blobuploader) - INFO - Open directory for files ... 21:59:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:59:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:59:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:59:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:59:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:59:19 INFO - (blobuploader) - INFO - Done attempting. 21:59:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:59:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:59:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:59:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:59:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:59:20 INFO - (blobuploader) - INFO - Done attempting. 21:59:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:59:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:59:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:59:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:59:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:59:21 INFO - (blobuploader) - INFO - Done attempting. 21:59:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:59:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:59:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:59:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:59:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:59:23 INFO - (blobuploader) - INFO - Done attempting. 21:59:23 INFO - (blobuploader) - INFO - Iteration through files over. 21:59:23 INFO - Return code: 0 21:59:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:59:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:59:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a9c9785bef4b9f0761367f6f1969240bcd324c7b27168886341041f0e3f75f21aff23b9ba226aae353eadbd1303dfc11dd39d2fbe5669dda4fad5ba40ff30ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f7ec6192fa39e5cc29ff1c2b5ce4db3ddbc3f992bf221694e21d84028f2470d57cf1b265b5217f01d6bb184aa28142d7d125478884f7b0ad44fcf4ca9c6e35b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/548330766b6bb3f99760a1c7e634611ee73f20bda9f665df22c7671343b870a06117ec39281aff5935d578a96eea36bce2fedf1bb1b78b3e10fd782793fad884", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ae36aee393da2aab96f1c01b0315bccfa38541bbea197d74865e8ee086bfb80bdb5ca8c463673c66dea2e4af685737b56498788356eebae0712931377815f5d"} 21:59:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:59:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:59:23 INFO - Contents: 21:59:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a9c9785bef4b9f0761367f6f1969240bcd324c7b27168886341041f0e3f75f21aff23b9ba226aae353eadbd1303dfc11dd39d2fbe5669dda4fad5ba40ff30ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f7ec6192fa39e5cc29ff1c2b5ce4db3ddbc3f992bf221694e21d84028f2470d57cf1b265b5217f01d6bb184aa28142d7d125478884f7b0ad44fcf4ca9c6e35b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/548330766b6bb3f99760a1c7e634611ee73f20bda9f665df22c7671343b870a06117ec39281aff5935d578a96eea36bce2fedf1bb1b78b3e10fd782793fad884", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ae36aee393da2aab96f1c01b0315bccfa38541bbea197d74865e8ee086bfb80bdb5ca8c463673c66dea2e4af685737b56498788356eebae0712931377815f5d"} 21:59:23 INFO - Running post-run listener: copy_logs_to_upload_dir 21:59:23 INFO - Copying logs to upload dir... 21:59:23 INFO - mkdir: /builds/slave/test/build/upload/logs 21:59:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.913832 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-07 21:59:23.792003) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-07 21:59:23.795628) ========= 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.vFNJdw3uDM/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.rvKdYTRg9U/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a9c9785bef4b9f0761367f6f1969240bcd324c7b27168886341041f0e3f75f21aff23b9ba226aae353eadbd1303dfc11dd39d2fbe5669dda4fad5ba40ff30ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f7ec6192fa39e5cc29ff1c2b5ce4db3ddbc3f992bf221694e21d84028f2470d57cf1b265b5217f01d6bb184aa28142d7d125478884f7b0ad44fcf4ca9c6e35b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/548330766b6bb3f99760a1c7e634611ee73f20bda9f665df22c7671343b870a06117ec39281aff5935d578a96eea36bce2fedf1bb1b78b3e10fd782793fad884", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ae36aee393da2aab96f1c01b0315bccfa38541bbea197d74865e8ee086bfb80bdb5ca8c463673c66dea2e4af685737b56498788356eebae0712931377815f5d"} build_url:https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011238 build_url: 'https://queue.taskcluster.net/v1/task/RAwyJyWET9WwR3gWBlluCA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a9c9785bef4b9f0761367f6f1969240bcd324c7b27168886341041f0e3f75f21aff23b9ba226aae353eadbd1303dfc11dd39d2fbe5669dda4fad5ba40ff30ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f7ec6192fa39e5cc29ff1c2b5ce4db3ddbc3f992bf221694e21d84028f2470d57cf1b265b5217f01d6bb184aa28142d7d125478884f7b0ad44fcf4ca9c6e35b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/548330766b6bb3f99760a1c7e634611ee73f20bda9f665df22c7671343b870a06117ec39281aff5935d578a96eea36bce2fedf1bb1b78b3e10fd782793fad884", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ae36aee393da2aab96f1c01b0315bccfa38541bbea197d74865e8ee086bfb80bdb5ca8c463673c66dea2e4af685737b56498788356eebae0712931377815f5d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-07 21:59:23.831714) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 21:59:23.832081) ========= 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.vFNJdw3uDM/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.rvKdYTRg9U/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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.005053 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 21:59:23.881219) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-07 21:59:23.881580) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-07 21:59:23.881918) ========= ========= Total master_lag: 0.19 =========