builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0245 starttime: 1493890617.4 results: success (0) buildid: 20170504015521 builduid: 73f4e7edd7934f48985290e55dabd21d revision: a8d597ee6dd58306e62e55a07c4ab20958726d6a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:57.402553) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:57.403028) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:57.403340) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:57.430199) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:57.430517) ========= 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.dPznMgGyoE/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.NniU67c2Ka/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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-04 02:36:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.9M=0.001s 2017-05-04 02:36:57 (17.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.110092 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:57.565908) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:57.566288) ========= 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.dPznMgGyoE/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.NniU67c2Ka/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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.027091 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:57.616416) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:57.616997) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a8d597ee6dd58306e62e55a07c4ab20958726d6a --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 a8d597ee6dd58306e62e55a07c4ab20958726d6a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dPznMgGyoE/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.NniU67c2Ka/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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-04 02:36:57,722 truncating revision to first 12 chars 2017-05-04 02:36:57,723 Setting DEBUG logging. 2017-05-04 02:36:57,723 attempt 1/10 2017-05-04 02:36:57,723 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a8d597ee6dd5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-04 02:36:58,030 unpacking tar archive at: mozilla-central-a8d597ee6dd5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.585436 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:58.226517) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:58.226836) ========= script_repo_revision: a8d597ee6dd58306e62e55a07c4ab20958726d6a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:58.227296) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:58.227570) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:58.248345) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-05-04 02:36:58.248697) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dPznMgGyoE/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.NniU67c2Ka/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:36:58 INFO - MultiFileLogger online at 20170504 02:36:58 in /builds/slave/test 02:36:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:36:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:36:58 INFO - {'append_to_log': False, 02:36:58 INFO - 'base_work_dir': '/builds/slave/test', 02:36:58 INFO - 'blob_upload_branch': 'Firefox', 02:36:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:36:58 INFO - 'branch': 'Firefox', 02:36:58 INFO - 'buildbot_json_path': 'buildprops.json', 02:36:58 INFO - 'config_files': ('talos/mac_config.py',), 02:36:58 INFO - 'default_actions': ('clobber', 02:36:58 INFO - 'read-buildbot-config', 02:36:58 INFO - 'download-and-extract', 02:36:58 INFO - 'populate-webroot', 02:36:58 INFO - 'create-virtualenv', 02:36:58 INFO - 'install', 02:36:58 INFO - 'run-tests'), 02:36:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:36:58 INFO - 'download_minidump_stackwalk': True, 02:36:58 INFO - 'download_symbols': 'ondemand', 02:36:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:36:58 INFO - 'tooltool.py': '/tools/tooltool.py', 02:36:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:36:58 INFO - '/tools/misc-python/virtualenv.py')}, 02:36:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:36:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:36:58 INFO - 'gecko_profile': False, 02:36:58 INFO - 'gecko_profile_interval': 0, 02:36:58 INFO - 'installer_path': 'installer.exe', 02:36:58 INFO - 'log_level': 'info', 02:36:58 INFO - 'log_name': 'talos', 02:36:58 INFO - 'log_to_console': True, 02:36:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:36:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:36:58 INFO - 'opt_config_files': (), 02:36:58 INFO - 'pip_index': False, 02:36:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:36:58 INFO - 'cmd': ('bash', 02:36:58 INFO - '-c', 02:36:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:36:58 INFO - 'enabled': True, 02:36:58 INFO - 'halt_on_failure': False, 02:36:58 INFO - 'name': 'check_screen_resolution'},), 02:36:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:36:58 INFO - 'cmd': ('bash', 02:36:58 INFO - '-c', 02:36:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:36:58 INFO - 'enabled': True, 02:36:58 INFO - 'halt_on_failure': False, 02:36:58 INFO - 'name': 'check_screen_resolution'},), 02:36:58 INFO - 'run_cmd_checks_enabled': True, 02:36:58 INFO - 'suite': 'dromaeojs-e10s', 02:36:58 INFO - 'system_bits': '32', 02:36:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:36:58 INFO - 'title': 't-yosemite-r7-0245', 02:36:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:36:58 INFO - 'use_talos_json': True, 02:36:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:36:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:36:58 INFO - 'work_dir': 'build'} 02:36:58 INFO - [mozharness: 2017-05-04 09:36:58.461700Z] Running clobber step. 02:36:58 INFO - Running pre-action listener: _resource_record_pre_action 02:36:58 INFO - Running main action method: clobber 02:36:58 INFO - rmtree: /builds/slave/test/build 02:36:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:37:00 INFO - Running post-action listener: _resource_record_post_action 02:37:00 INFO - [mozharness: 2017-05-04 09:37:00.918441Z] Finished clobber step (success) 02:37:00 INFO - [mozharness: 2017-05-04 09:37:00.918570Z] Running read-buildbot-config step. 02:37:00 INFO - Running pre-action listener: _resource_record_pre_action 02:37:00 INFO - Running main action method: read_buildbot_config 02:37:00 INFO - Using buildbot properties: 02:37:00 INFO - { 02:37:00 INFO - "project": "", 02:37:00 INFO - "product": "firefox", 02:37:00 INFO - "script_repo_revision": "production", 02:37:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 02:37:00 INFO - "repository": "", 02:37:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 02:37:00 INFO - "buildid": "20170504015521", 02:37:00 INFO - "pgo_build": "False", 02:37:00 INFO - "basedir": "/builds/slave/test", 02:37:00 INFO - "buildnumber": 64, 02:37:00 INFO - "slavename": "t-yosemite-r7-0245", 02:37:00 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:37:00 INFO - "platform": "yosemite_r7", 02:37:00 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:37:00 INFO - "branch": "mozilla-central", 02:37:00 INFO - "repo_path": "mozilla-central", 02:37:00 INFO - "revision": "a8d597ee6dd58306e62e55a07c4ab20958726d6a", 02:37:00 INFO - "stage_platform": "macosx64", 02:37:00 INFO - "builduid": "73f4e7edd7934f48985290e55dabd21d", 02:37:00 INFO - "slavebuilddir": "test" 02:37:00 INFO - } 02:37:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 02:37:00 INFO - Running post-action listener: _resource_record_post_action 02:37:00 INFO - [mozharness: 2017-05-04 09:37:00.921935Z] Finished read-buildbot-config step (success) 02:37:00 INFO - [mozharness: 2017-05-04 09:37:00.922065Z] Running download-and-extract step. 02:37:00 INFO - Running pre-action listener: _resource_record_pre_action 02:37:00 INFO - Running main action method: download_and_extract 02:37:00 INFO - mkdir: /builds/slave/test/build/tests 02:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:00 INFO - trying https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 02:37:00 INFO - Downloading https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/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 02:37:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/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 02:37:01 INFO - Downloaded 1325 bytes. 02:37:01 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:37:01 INFO - Using the following test package requirements: 02:37:01 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:37:01 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 02:37:01 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:37:01 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:37:01 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 02:37:01 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:37:01 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 02:37:01 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:37:01 INFO - u'jsshell-mac.zip'], 02:37:01 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:37:01 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 02:37:01 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:37:01 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:37:01 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 02:37:01 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:37:01 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 02:37:01 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:37:01 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 02:37:01 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:37:01 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 02:37:01 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:37:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:37:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:37:01 INFO - Fetch https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:37:03 INFO - Content-Length response header: 52700574 02:37:03 INFO - Bytes received: 52700574 02:37:20 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 02:37:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:37:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:37:20 INFO - Fetch https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:37:21 INFO - Content-Length response header: 52700574 02:37:21 INFO - Bytes received: 52700574 02:37:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 02:37:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:37:37 INFO - Fetch https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 02:37:38 INFO - Content-Length response header: 14408809 02:37:38 INFO - Bytes received: 14408809 02:37:38 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')]} 02:37:38 INFO - trying https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:37:38 INFO - Downloading https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 02:37:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/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 02:37:40 INFO - Downloaded 70880481 bytes. 02:37:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:37:40 INFO - mkdir: /builds/slave/test/properties 02:37:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:37:40 INFO - Writing to file /builds/slave/test/properties/build_url 02:37:40 INFO - Contents: 02:37:40 INFO - build_url:https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:37:41 INFO - Running post-action listener: _resource_record_post_action 02:37:41 INFO - Running post-action listener: set_extra_try_arguments 02:37:41 INFO - [mozharness: 2017-05-04 09:37:41.845339Z] Finished download-and-extract step (success) 02:37:41 INFO - [mozharness: 2017-05-04 09:37:41.845501Z] Running populate-webroot step. 02:37:41 INFO - Running pre-action listener: _resource_record_pre_action 02:37:41 INFO - Running main action method: populate_webroot 02:37:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:37:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:37:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:37:41 INFO - '--tppagecycles', 02:37:41 INFO - '7'], 02:37:41 INFO - 'tests': ['tsvgm']}}, 02:37:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:37:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:37:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:37:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:37:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:37:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:37:41 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:37:41 INFO - 'talos_options': ['--disable-e10s'], 02:37:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:37:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:37:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:37:41 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:37:41 INFO - 'talos_options': ['--disable-e10s'], 02:37:41 INFO - 'tests': ['damp', 'tps']}, 02:37:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:37:41 INFO - 'tests': ['damp', 'tps']}, 02:37:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:37:41 INFO - 'tests': ['dromaeo_dom']}, 02:37:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:37:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:37:41 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:37:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:37:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:37:41 INFO - 'tests': ['a11yr', 02:37:41 INFO - 'ts_paint', 02:37:41 INFO - 'tpaint', 02:37:41 INFO - 'sessionrestore', 02:37:41 INFO - 'sessionrestore_no_auto_restore', 02:37:41 INFO - 'tabpaint']}, 02:37:41 INFO - 'other-e10s': {'tests': ['a11yr', 02:37:41 INFO - 'ts_paint', 02:37:41 INFO - 'tpaint', 02:37:41 INFO - 'sessionrestore', 02:37:41 INFO - 'sessionrestore_no_auto_restore', 02:37:41 INFO - 'tabpaint']}, 02:37:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:37:41 INFO - 'ts_paint', 02:37:41 INFO - 'tpaint', 02:37:41 INFO - 'sessionrestore', 02:37:41 INFO - 'sessionrestore_no_auto_restore', 02:37:41 INFO - 'tabpaint']}, 02:37:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:37:41 INFO - 'ts_paint', 02:37:41 INFO - 'tpaint', 02:37:41 INFO - 'sessionrestore', 02:37:41 INFO - 'sessionrestore_no_auto_restore', 02:37:41 INFO - 'tabpaint']}, 02:37:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:37:41 INFO - 'tests': ['a11yr', 02:37:41 INFO - 'ts_paint', 02:37:41 INFO - 'tpaint', 02:37:41 INFO - 'sessionrestore', 02:37:41 INFO - 'sessionrestore_no_auto_restore', 02:37:41 INFO - 'tabpaint']}, 02:37:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:37:41 INFO - 'tests': ['a11yr', 02:37:41 INFO - 'ts_paint', 02:37:41 INFO - 'tpaint', 02:37:41 INFO - 'sessionrestore', 02:37:41 INFO - 'sessionrestore_no_auto_restore', 02:37:41 INFO - 'tabpaint']}, 02:37:41 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 02:37:41 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 02:37:41 INFO - 'bloom_basic_ref']}, 02:37:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:37:41 INFO - 'tests': ['tsvgx', 02:37:41 INFO - 'tsvgr_opacity', 02:37:41 INFO - 'tart', 02:37:41 INFO - 'tscrollx', 02:37:41 INFO - 'cart', 02:37:41 INFO - 'tsvg_static']}, 02:37:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:37:41 INFO - 'tsvgr_opacity', 02:37:41 INFO - 'tart', 02:37:41 INFO - 'tscrollx', 02:37:41 INFO - 'cart', 02:37:41 INFO - 'tsvg_static']}, 02:37:41 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:37:41 INFO - 'talos_options': ['--disable-e10s'], 02:37:41 INFO - 'tests': ['tp5o']}, 02:37:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:37:41 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:37:41 INFO - 'talos_options': ['--disable-e10s', 02:37:41 INFO - '--xperf_path', 02:37:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:37:41 INFO - 'tests': ['tp5n']}, 02:37:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:37:41 INFO - 'talos_options': ['--xperf_path', 02:37:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:37:41 INFO - 'tests': ['tp5n']}}, 02:37:41 INFO - 'talos.zip': {'path': '', 02:37:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:37:41 INFO - Running post-action listener: _resource_record_post_action 02:37:41 INFO - [mozharness: 2017-05-04 09:37:41.864578Z] Finished populate-webroot step (success) 02:37:41 INFO - [mozharness: 2017-05-04 09:37:41.864707Z] Running create-virtualenv step. 02:37:41 INFO - Running pre-action listener: _resource_record_pre_action 02:37:41 INFO - Running main action method: create_virtualenv 02:37:41 INFO - Creating virtualenv /builds/slave/test/build/venv 02:37:41 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 02:37:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:37:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:37:41 INFO - Using real prefix '/tools/python27' 02:37:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:37:42 INFO - Installing distribute.............................................................................................................................................................................................done. 02:37:45 INFO - Installing pip.................done. 02:37:45 INFO - Return code: 0 02:37:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:37:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:37:45 INFO - Reading from file tmpfile_stdout 02:37:45 INFO - Output received: 02:37:45 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) 02:37:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1082c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86f24985b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080a5d30>, '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 0x107fcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10809abe8>, '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.dPznMgGyoE/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.NniU67c2Ka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:37:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:37:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:37:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dPznMgGyoE/Render', 02:37:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:45 INFO - 'HOME': '/Users/cltbld', 02:37:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:45 INFO - 'LOGNAME': 'cltbld', 02:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:45 INFO - 'MOZ_NO_REMOTE': '1', 02:37:45 INFO - 'NO_EM_RESTART': '1', 02:37:45 INFO - 'PAGER': '/bin/cat', 02:37:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:45 INFO - 'PWD': '/builds/slave/test', 02:37:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:45 INFO - 'SHELL': '/bin/bash', 02:37:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NniU67c2Ka/Listeners', 02:37:45 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:37:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:45 INFO - 'USER': 'cltbld', 02:37:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:45 INFO - 'XPC_FLAGS': '0x0', 02:37:45 INFO - 'XPC_SERVICE_NAME': '0', 02:37:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:46 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 02:37:46 INFO - Cleaning up... 02:37:46 INFO - Return code: 0 02:37:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:37:46 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')]} 02:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:46 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')]} 02:37:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:46 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 0x1082c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86f24985b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080a5d30>, '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 0x107fcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10809abe8>, '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.dPznMgGyoE/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.NniU67c2Ka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:37:46 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 02:37:46 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 02:37:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dPznMgGyoE/Render', 02:37:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:46 INFO - 'HOME': '/Users/cltbld', 02:37:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:46 INFO - 'LOGNAME': 'cltbld', 02:37:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:46 INFO - 'MOZ_NO_REMOTE': '1', 02:37:46 INFO - 'NO_EM_RESTART': '1', 02:37:46 INFO - 'PAGER': '/bin/cat', 02:37:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:46 INFO - 'PWD': '/builds/slave/test', 02:37:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:46 INFO - 'SHELL': '/bin/bash', 02:37:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NniU67c2Ka/Listeners', 02:37:46 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:37:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:46 INFO - 'USER': 'cltbld', 02:37:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:46 INFO - 'XPC_FLAGS': '0x0', 02:37:46 INFO - 'XPC_SERVICE_NAME': '0', 02:37:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:46 INFO - Downloading/unpacking psutil>=3.1.1 02:37:46 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 02:37:46 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 02:37:46 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 02:37:46 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 02:37:46 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 02:37:46 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 02:37:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:37:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:37:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:37:48 INFO - Installing collected packages: psutil 02:37:48 INFO - Running setup.py install for psutil 02:37:48 INFO - building 'psutil._psutil_osx' extension 02:37:48 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 02:37:49 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 02:37:49 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 02:37:49 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 02:37:49 INFO - building 'psutil._psutil_posix' extension 02:37:49 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 02:37:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:37:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:37:49 INFO - ^ 02:37:49 INFO - 1 warning generated. 02:37:49 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 02:37:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:37:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:37:49 INFO - Successfully installed psutil 02:37:49 INFO - Cleaning up... 02:37:49 INFO - Return code: 0 02:37:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:49 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 0x1082c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86f24985b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080a5d30>, '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 0x107fcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10809abe8>, '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.dPznMgGyoE/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.NniU67c2Ka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:37:49 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 02:37:49 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 02:37:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dPznMgGyoE/Render', 02:37:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:49 INFO - 'HOME': '/Users/cltbld', 02:37:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:49 INFO - 'LOGNAME': 'cltbld', 02:37:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:49 INFO - 'MOZ_NO_REMOTE': '1', 02:37:49 INFO - 'NO_EM_RESTART': '1', 02:37:49 INFO - 'PAGER': '/bin/cat', 02:37:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:49 INFO - 'PWD': '/builds/slave/test', 02:37:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:49 INFO - 'SHELL': '/bin/bash', 02:37:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NniU67c2Ka/Listeners', 02:37:49 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:37:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:49 INFO - 'USER': 'cltbld', 02:37:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:49 INFO - 'XPC_FLAGS': '0x0', 02:37:49 INFO - 'XPC_SERVICE_NAME': '0', 02:37:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:37:50 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 02:37:50 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 02:37:50 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 02:37:50 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 02:37:50 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 02:37:50 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 02:37:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:37:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:37:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:37:51 INFO - Installing collected packages: mozsystemmonitor 02:37:51 INFO - Running setup.py install for mozsystemmonitor 02:37:51 INFO - Successfully installed mozsystemmonitor 02:37:51 INFO - Cleaning up... 02:37:51 INFO - Return code: 0 02:37:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:37:51 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')]} 02:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:51 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')]} 02:37:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:51 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 0x1082c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86f24985b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080a5d30>, '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 0x107fcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10809abe8>, '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.dPznMgGyoE/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.NniU67c2Ka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:37:51 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 02:37:51 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 02:37:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dPznMgGyoE/Render', 02:37:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:51 INFO - 'HOME': '/Users/cltbld', 02:37:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:51 INFO - 'LOGNAME': 'cltbld', 02:37:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:51 INFO - 'MOZ_NO_REMOTE': '1', 02:37:51 INFO - 'NO_EM_RESTART': '1', 02:37:51 INFO - 'PAGER': '/bin/cat', 02:37:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:51 INFO - 'PWD': '/builds/slave/test', 02:37:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:51 INFO - 'SHELL': '/bin/bash', 02:37:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NniU67c2Ka/Listeners', 02:37:51 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:37:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:51 INFO - 'USER': 'cltbld', 02:37:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:51 INFO - 'XPC_FLAGS': '0x0', 02:37:51 INFO - 'XPC_SERVICE_NAME': '0', 02:37:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:51 INFO - Downloading/unpacking jsonschema==2.5.1 02:37:51 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 02:37:51 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 02:37:51 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 02:37:51 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 02:37:51 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 02:37:51 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 02:37:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:37:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:37:53 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 02:37:53 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 02:37:53 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 02:37:53 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 02:37:53 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 02:37:53 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 02:37:53 INFO - Downloading functools32-3.2.3-2.tar.gz 02:37:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:37:53 INFO - warning: no files found matching '*.txt' 02:37:53 INFO - no previously-included directories found matching 'build' 02:37:53 INFO - no previously-included directories found matching 'dist' 02:37:53 INFO - no previously-included directories found matching '.git*' 02:37:53 INFO - Installing collected packages: jsonschema, functools32 02:37:53 INFO - Running setup.py install for functools32 02:37:53 INFO - warning: no files found matching '*.txt' 02:37:53 INFO - no previously-included directories found matching 'build' 02:37:53 INFO - no previously-included directories found matching 'dist' 02:37:53 INFO - no previously-included directories found matching '.git*' 02:37:53 INFO - Successfully installed jsonschema functools32 02:37:53 INFO - Cleaning up... 02:37:53 INFO - Return code: 0 02:37:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:54 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')]} 02:37:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:54 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 0x1082c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86f24985b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080a5d30>, '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 0x107fcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10809abe8>, '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.dPznMgGyoE/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.NniU67c2Ka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:37:54 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 02:37:54 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 02:37:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dPznMgGyoE/Render', 02:37:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:54 INFO - 'HOME': '/Users/cltbld', 02:37:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:54 INFO - 'LOGNAME': 'cltbld', 02:37:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:54 INFO - 'MOZ_NO_REMOTE': '1', 02:37:54 INFO - 'NO_EM_RESTART': '1', 02:37:54 INFO - 'PAGER': '/bin/cat', 02:37:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:54 INFO - 'PWD': '/builds/slave/test', 02:37:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:54 INFO - 'SHELL': '/bin/bash', 02:37:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NniU67c2Ka/Listeners', 02:37:54 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:37:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:54 INFO - 'USER': 'cltbld', 02:37:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:54 INFO - 'XPC_FLAGS': '0x0', 02:37:54 INFO - 'XPC_SERVICE_NAME': '0', 02:37:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:37:54 INFO - Cleaning up... 02:37:54 INFO - Return code: 0 02:37:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:37:54 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')]} 02:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:54 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')]} 02:37:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37: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', '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 0x1082c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86f24985b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080a5d30>, '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 0x107fcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10809abe8>, '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.dPznMgGyoE/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.NniU67c2Ka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:37: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:37: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 blobuploader==1.2.4 02:37:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dPznMgGyoE/Render', 02:37:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:55 INFO - 'HOME': '/Users/cltbld', 02:37:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:55 INFO - 'LOGNAME': 'cltbld', 02:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:55 INFO - 'MOZ_NO_REMOTE': '1', 02:37:55 INFO - 'NO_EM_RESTART': '1', 02:37:55 INFO - 'PAGER': '/bin/cat', 02:37:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:55 INFO - 'PWD': '/builds/slave/test', 02:37:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:55 INFO - 'SHELL': '/bin/bash', 02:37:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NniU67c2Ka/Listeners', 02:37:55 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:37:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:55 INFO - 'USER': 'cltbld', 02:37:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:55 INFO - 'XPC_FLAGS': '0x0', 02:37:55 INFO - 'XPC_SERVICE_NAME': '0', 02:37:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:55 INFO - Downloading/unpacking blobuploader==1.2.4 02:37: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 02:37: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 02:37: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 02:37: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 02:37: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 02:37: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 02:37:57 INFO - Downloading blobuploader-1.2.4.tar.gz 02:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:37:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:37:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:57 INFO - Downloading docopt-0.6.1.tar.gz 02:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:37:58 INFO - Installing collected packages: blobuploader, requests, docopt 02:37:58 INFO - Running setup.py install for blobuploader 02:37:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:37:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:37:58 INFO - Running setup.py install for requests 02:37:58 INFO - Running setup.py install for docopt 02:37:58 INFO - Successfully installed blobuploader requests docopt 02:37:58 INFO - Cleaning up... 02:37:58 INFO - Return code: 0 02:37:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:37:58 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')]} 02:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:58 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')]} 02:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:58 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 0x1082c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86f24985b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080a5d30>, '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 0x107fcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10809abe8>, '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.dPznMgGyoE/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.NniU67c2Ka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:37:58 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 02:37:58 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 02:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dPznMgGyoE/Render', 02:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:58 INFO - 'HOME': '/Users/cltbld', 02:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:58 INFO - 'LOGNAME': 'cltbld', 02:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:58 INFO - 'MOZ_NO_REMOTE': '1', 02:37:58 INFO - 'NO_EM_RESTART': '1', 02:37:58 INFO - 'PAGER': '/bin/cat', 02:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:58 INFO - 'PWD': '/builds/slave/test', 02:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:58 INFO - 'SHELL': '/bin/bash', 02:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NniU67c2Ka/Listeners', 02:37:58 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:58 INFO - 'USER': 'cltbld', 02:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:58 INFO - 'XPC_FLAGS': '0x0', 02:37:58 INFO - 'XPC_SERVICE_NAME': '0', 02:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:37:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-6YFo_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:37:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-ZJaLZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:37:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-OHqDZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:37:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-jlIpG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:37:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-nfn2OV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:37:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-FhXYvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:37:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-e2H6YH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:37:59 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-TWRtZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:38:00 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-vxTERD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:38:00 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-r9iNDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:38:00 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-a2fTmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:38:00 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-N9O1pG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:38:00 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-0KBbn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:38:00 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-FJI2QL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:38:00 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-cPnYv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:38:00 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-fHKOTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:38:00 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-Yo0DtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:38:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:38:00 INFO - Running setup.py install for manifestparser 02:38:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:38:01 INFO - Running setup.py install for mozcrash 02:38:01 INFO - Running setup.py install for mozdebug 02:38:01 INFO - Running setup.py install for mozdevice 02:38:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:38:01 INFO - Running setup.py install for mozfile 02:38:01 INFO - Running setup.py install for mozhttpd 02:38:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:38:01 INFO - Running setup.py install for mozinfo 02:38:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:38:02 INFO - Running setup.py install for mozInstall 02:38:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:38:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:38:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:38:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:38:02 INFO - Running setup.py install for mozleak 02:38:02 INFO - Running setup.py install for mozlog 02:38:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:38:02 INFO - Running setup.py install for moznetwork 02:38:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:38:02 INFO - Running setup.py install for mozprocess 02:38:02 INFO - Running setup.py install for mozprofile 02:38:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:38:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:38:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:38:02 INFO - Running setup.py install for mozrunner 02:38:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:38:03 INFO - Running setup.py install for mozscreenshot 02:38:03 INFO - Running setup.py install for moztest 02:38:03 INFO - Running setup.py install for mozversion 02:38:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:38:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:38:03 INFO - Cleaning up... 02:38:03 INFO - Return code: 0 02:38:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:38:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:38:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:38:03 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 0x1082c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86f24985b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080a5d30>, '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 0x107fcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10809abe8>, '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.dPznMgGyoE/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.NniU67c2Ka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:38:03 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 02:38:03 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 02:38:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dPznMgGyoE/Render', 02:38:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:38:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:38:03 INFO - 'HOME': '/Users/cltbld', 02:38:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:38:03 INFO - 'LOGNAME': 'cltbld', 02:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:38:03 INFO - 'MOZ_NO_REMOTE': '1', 02:38:03 INFO - 'NO_EM_RESTART': '1', 02:38:03 INFO - 'PAGER': '/bin/cat', 02:38:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:38:03 INFO - 'PWD': '/builds/slave/test', 02:38:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:38:03 INFO - 'SHELL': '/bin/bash', 02:38:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NniU67c2Ka/Listeners', 02:38:03 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:38:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:38:03 INFO - 'USER': 'cltbld', 02:38:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:38:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:38:03 INFO - 'XPC_FLAGS': '0x0', 02:38:03 INFO - 'XPC_SERVICE_NAME': '0', 02:38:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:38:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:38:03 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-iRf1ek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:38:03 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)) 02:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:38:03 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-_JE1Q3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:38:04 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)) 02:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:38:04 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-UZQ3xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:38:04 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)) 02:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:38:04 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-1cqdub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:38:04 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)) 02:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:38:04 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-66sAtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:38:04 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)) 02:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:38:04 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-DEzeVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:38:04 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)) 02:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:38:04 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-JmipFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:38:04 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)) 02:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:38:04 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-rpxzhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:38:04 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)) 02:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:38:04 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-ogS6dx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:38:04 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)) 02:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:38:04 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-IGEN3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:38:04 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)) 02:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:38:04 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-YspB9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:38:04 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)) 02:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:38:05 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-2Zvn0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:38:05 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)) 02:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:38:05 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-n0RWO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:38:05 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)) 02:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:38:05 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-aslWPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:38:05 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)) 02:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:38:05 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-z1giyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:38:05 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)) 02:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:38:05 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-JQ0kVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:38:05 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)) 02:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:38:05 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-xwJAKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:38:05 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)) 02:38:05 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)) 02:38:05 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)) 02:38:05 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)) 02:38:05 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)) 02:38:05 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)) 02:38:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:38: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 02:38: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 02:38: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 02:38: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 02:38: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 02:38: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 02:38:07 INFO - Downloading blessings-1.6.tar.gz 02:38:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:38:07 INFO - Installing collected packages: blessings 02:38:07 INFO - Running setup.py install for blessings 02:38:07 INFO - Successfully installed blessings 02:38:07 INFO - Cleaning up... 02:38:07 INFO - Return code: 0 02:38:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:38:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:38:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:38:07 INFO - Reading from file tmpfile_stdout 02:38:07 INFO - Current package versions: 02:38:07 INFO - blessings == 1.6 02:38:07 INFO - blobuploader == 1.2.4 02:38:07 INFO - docopt == 0.6.1 02:38:07 INFO - functools32 == 3.2.3-2 02:38:07 INFO - jsonschema == 2.5.1 02:38:07 INFO - manifestparser == 1.1 02:38:07 INFO - mozInstall == 1.12 02:38:07 INFO - mozcrash == 1.0 02:38:07 INFO - mozdebug == 0.1 02:38:07 INFO - mozdevice == 0.50 02:38:07 INFO - mozfile == 1.2 02:38:07 INFO - mozhttpd == 0.7 02:38:07 INFO - mozinfo == 0.9 02:38:07 INFO - mozleak == 0.1 02:38:07 INFO - mozlog == 3.4 02:38:07 INFO - moznetwork == 0.27 02:38:07 INFO - mozprocess == 0.25 02:38:07 INFO - mozprofile == 0.28 02:38:07 INFO - mozrunner == 6.13 02:38:07 INFO - mozscreenshot == 0.1 02:38:07 INFO - mozsystemmonitor == 0.3 02:38:07 INFO - moztest == 0.8 02:38:07 INFO - mozversion == 1.4 02:38:07 INFO - psutil == 3.1.1 02:38:07 INFO - requests == 1.2.3 02:38:07 INFO - wsgiref == 0.1.2 02:38:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:38:07 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')]} 02:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:38:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:38:07 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')]} 02:38:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:38:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:38:07 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 0x1082c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86f24985b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080a5d30>, '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 0x107fcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10809abe8>, '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.dPznMgGyoE/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.NniU67c2Ka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:38:07 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 02:38:07 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 02:38:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dPznMgGyoE/Render', 02:38:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:38:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:38:07 INFO - 'HOME': '/Users/cltbld', 02:38:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:38:07 INFO - 'LOGNAME': 'cltbld', 02:38:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:38:07 INFO - 'MOZ_NO_REMOTE': '1', 02:38:07 INFO - 'NO_EM_RESTART': '1', 02:38:07 INFO - 'PAGER': '/bin/cat', 02:38:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:38:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:38:07 INFO - 'PWD': '/builds/slave/test', 02:38:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:38:07 INFO - 'SHELL': '/bin/bash', 02:38:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NniU67c2Ka/Listeners', 02:38:07 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:38:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:38:07 INFO - 'USER': 'cltbld', 02:38:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:38:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:38:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:38:07 INFO - 'XPC_FLAGS': '0x0', 02:38:07 INFO - 'XPC_SERVICE_NAME': '0', 02:38:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:38:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:38:07 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)) 02:38:07 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)) 02:38:07 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)) 02:38:07 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)) 02:38:07 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)) 02:38:07 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)) 02:38:07 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)) 02:38:07 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)) 02:38:07 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)) 02:38:07 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)) 02:38:07 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)) 02:38:07 INFO - Cleaning up... 02:38:07 INFO - Return code: 0 02:38:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:38:07 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')]} 02:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:38:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:38:07 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')]} 02:38:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:38:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:38:07 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 0x1082c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86f24985b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080a5d30>, '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 0x107fcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10809abe8>, '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.dPznMgGyoE/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.NniU67c2Ka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 02:38:07 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 02:38:07 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 02:38:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dPznMgGyoE/Render', 02:38:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:38:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:38:07 INFO - 'HOME': '/Users/cltbld', 02:38:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:38:07 INFO - 'LOGNAME': 'cltbld', 02:38:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:38:07 INFO - 'MOZ_NO_REMOTE': '1', 02:38:07 INFO - 'NO_EM_RESTART': '1', 02:38:07 INFO - 'PAGER': '/bin/cat', 02:38:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:38:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:38:07 INFO - 'PWD': '/builds/slave/test', 02:38:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:38:07 INFO - 'SHELL': '/bin/bash', 02:38:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NniU67c2Ka/Listeners', 02:38:07 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:38:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:38:07 INFO - 'USER': 'cltbld', 02:38:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:38:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:38:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:38:07 INFO - 'XPC_FLAGS': '0x0', 02:38:07 INFO - 'XPC_SERVICE_NAME': '0', 02:38:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:38:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:38:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:38:08 INFO - Cleaning up... 02:38:08 INFO - Return code: 0 02:38:08 INFO - Running post-action listener: _resource_record_post_action 02:38:08 INFO - Running post-action listener: _start_resource_monitoring 02:38:08 INFO - Starting resource monitoring. 02:38:08 INFO - [mozharness: 2017-05-04 09:38:08.235974Z] Finished create-virtualenv step (success) 02:38:08 INFO - [mozharness: 2017-05-04 09:38:08.236933Z] Running install step. 02:38:08 INFO - Running pre-action listener: _resource_record_pre_action 02:38:08 INFO - Running main action method: install 02:38:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:38:08 INFO - Reading from file tmpfile_stdout 02:38:08 INFO - Detecting whether we're running mozinstall >=1.0... 02:38:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:38:08 INFO - Reading from file tmpfile_stdout 02:38:08 INFO - Output received: 02:38:08 INFO - Usage: mozinstall [options] installer 02:38:08 INFO - Options: 02:38:08 INFO - -h, --help show this help message and exit 02:38:08 INFO - -d DEST, --destination=DEST 02:38:08 INFO - Directory to install application into. [default: 02:38:08 INFO - "/builds/slave/test"] 02:38:08 INFO - --app=APP Application being installed. [default: firefox] 02:38:08 INFO - mkdir: /builds/slave/test/build/application 02:38:08 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'] 02:38:08 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 02:38:28 INFO - Reading from file tmpfile_stdout 02:38:28 INFO - Output received: 02:38:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:38:28 INFO - Running post-action listener: _resource_record_post_action 02:38:28 INFO - [mozharness: 2017-05-04 09:38:28.491258Z] Finished install step (success) 02:38:28 INFO - [mozharness: 2017-05-04 09:38:28.491396Z] Running run-tests step. 02:38:28 INFO - Running pre-action listener: _resource_record_pre_action 02:38:28 INFO - Running main action method: run_tests 02:38:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:38:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:38:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:38:28 INFO - 2017-05-04 02:38:28.505 screenresolution[1622:7408] starting screenresolution argv=screenresolution get 02:38:28 INFO - 2017-05-04 02:38:28.508 screenresolution[1622:7408] Display 0: 1600x1200x32@60 02:38:28 INFO - 2017-05-04 02:38:28.514 screenresolution[1623:7412] starting screenresolution argv=screenresolution list 02:38:28 INFO - Available Modes on Display 0 02:38:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:38:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:38:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:38:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:38:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:38:28 INFO - Intel Iris: 02:38:28 INFO - Chipset Model: Intel Iris 02:38:28 INFO - Type: GPU 02:38:28 INFO - Bus: Built-In 02:38:28 INFO - VRAM (Dynamic, Max): 1536 MB 02:38:28 INFO - Vendor: Intel (0x8086) 02:38:28 INFO - Device ID: 0x0a2e 02:38:28 INFO - Revision ID: 0x0009 02:38:28 INFO - Displays: 02:38:28 INFO - Display: 02:38:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:38:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:38:28 INFO - Display Serial Number: 1600x1200 60 02:38:28 INFO - Main Display: Yes 02:38:28 INFO - Mirror: Off 02:38:28 INFO - Online: Yes 02:38:28 INFO - Rotation: Supported 02:38:28 INFO - Return code: 0 02:38:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:38:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:38:28 INFO - Python 2.7.3 02:38:28 INFO - Return code: 0 02:38:28 INFO - grabbing minidump binary from tooltool 02:38:28 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')]} 02:38:28 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 0x1080a5d30>, '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 0x107fcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10809abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:38:28 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 02:38:28 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 02:38:28 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 02:38:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:38:28 INFO - Return code: 0 02:38:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:38:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:38:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:38:28 INFO - ENV: RUST_BACKTRACE is now 1 02:38:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:38:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:38:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0245', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:38:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0245 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:38:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dPznMgGyoE/Render', 02:38:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:38:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:38:28 INFO - 'HOME': '/Users/cltbld', 02:38:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:38:28 INFO - 'LOGNAME': 'cltbld', 02:38:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:38:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:38:28 INFO - 'MOZ_NO_REMOTE': '1', 02:38:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:38:28 INFO - 'NO_EM_RESTART': '1', 02:38:28 INFO - 'PAGER': '/bin/cat', 02:38:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:38:28 INFO - 'PWD': '/builds/slave/test', 02:38:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:38:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:38:28 INFO - 'RUST_BACKTRACE': '1', 02:38:28 INFO - 'SHELL': '/bin/bash', 02:38:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NniU67c2Ka/Listeners', 02:38:28 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 02:38:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:38:28 INFO - 'USER': 'cltbld', 02:38:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:38:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:38:28 INFO - 'XPC_FLAGS': '0x0', 02:38:28 INFO - 'XPC_SERVICE_NAME': '0', 02:38:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:38:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0245', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:38:29 INFO - mozversion application_buildid: 20170504015521 02:38:29 INFO - mozversion application_changeset: a8d597ee6dd58306e62e55a07c4ab20958726d6a 02:38:29 INFO - mozversion application_display_name: Nightly 02:38:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:38:29 INFO - mozversion application_name: Firefox 02:38:29 INFO - mozversion application_remotingname: firefox 02:38:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:38:29 INFO - mozversion application_vendor: Mozilla 02:38:29 INFO - mozversion application_version: 55.0a1 02:38:29 INFO - mozversion platform_buildid: 20170504015521 02:38:29 INFO - mozversion platform_changeset: a8d597ee6dd58306e62e55a07c4ab20958726d6a 02:38:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:38:29 INFO - mozversion platform_version: 55.0a1 02:38:29 INFO - using testdate: 1493890709 02:38:29 INFO - actual date: 1493890709 02:38:29 INFO - starting webserver on 'localhost:49241' 02:38:29 INFO - SUITE-START | Running 2 tests 02:38:29 INFO - TEST-START | dromaeo_css 02:38:29 INFO - operating with platform_type : mac_ 02:38:29 INFO - Initialising browser for dromaeo_css test... 02:38:29 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpLy3s4S/profile http://localhost:49241/getInfo.html) 02:38:31 INFO - PID 1633 | 2017-05-04 02:38:31.945 plugin-container[1635:7595] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 02:38:31 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:38:31 INFO - PID 1633 | 2017-05-04 02:38:31.948 plugin-container[1635:7595] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b1b, name = 'com.apple.CFPasteboardClient' 02:38:31 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:38:31 INFO - PID 1633 | 2017-05-04 02:38:31.949 plugin-container[1635:7595] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:38:32 INFO - PID 1633 | __metrics Screen width/height:1600/1200 02:38:32 INFO - PID 1633 | colorDepth:24 02:38:32 INFO - PID 1633 | Browser inner width/height: 1024/691 02:38:32 INFO - PID 1633 | __metrics 02:38:32 INFO - PID 1633 | 2017-05-04 02:38:32.408 plugin-container[1636:7650] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 02:38:32 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:38:32 INFO - PID 1633 | 2017-05-04 02:38:32.410 plugin-container[1636:7650] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 02:38:32 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:38:32 INFO - PID 1633 | 2017-05-04 02:38:32.410 plugin-container[1636:7650] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:38:33 INFO - TEST-INFO | 1633: exit 0 02:38:33 INFO - Browser initialized. 02:38:33 INFO - Running cycle 1/1 for dromaeo_css test... 02:38:33 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpLy3s4S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:38:35 INFO - PID 1637 | 2017-05-04 02:38:35.867 plugin-container[1638:7761] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x922b, name = 'com.apple.tsm.portname' 02:38:35 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:38:35 INFO - PID 1637 | 2017-05-04 02:38:35.867 plugin-container[1638:7761] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 02:38:35 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:38:35 INFO - PID 1637 | 2017-05-04 02:38:35.867 plugin-container[1638:7761] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:38:36 INFO - PID 1637 | 2017-05-04 02:38:36.360 plugin-container[1639:7803] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 02:38:36 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:38:36 INFO - PID 1637 | 2017-05-04 02:38:36.360 plugin-container[1639:7803] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9413, name = 'com.apple.CFPasteboardClient' 02:38:36 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:38:36 INFO - PID 1637 | 2017-05-04 02:38:36.360 plugin-container[1639:7803] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:38:45 INFO - PID 1637 | RSS: Main: 233295872 02:38:45 INFO - PID 1637 | 02:41:49 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 02:41:50 INFO - PID 1637 | RSS: Main: 250163200 02:41:50 INFO - PID 1637 | 02:44:52 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 02:44:53 INFO - PID 1637 | RSS: Main: 255696896 02:44:53 INFO - PID 1637 | 02:47:54 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 02:47:55 INFO - PID 1637 | RSS: Main: 269529088 02:47:55 INFO - PID 1637 | 02:50:57 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 02:50:57 INFO - PID 1637 | RSS: Main: 262324224 02:50:57 INFO - PID 1637 | 02:54:00 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 02:54:01 INFO - PID 1637 | RSS: Main: 266051584 02:54:01 INFO - PID 1637 | 02:57:03 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 02:57:03 INFO - PID 1637 | RSS: Main: 264712192 02:57:03 INFO - PID 1637 | 02:57:03 INFO - PID 1637 | __start_tp_report 02:57:03 INFO - PID 1637 | _x_x_mozilla_page_load 02:57:03 INFO - PID 1637 | _x_x_mozilla_page_load_details 02:57:03 INFO - PID 1637 | |i|pagename|runs| 02:57:03 INFO - PID 1637 | |0;dojo.html;3017.3965206958615;3078.3997120561485;3594.8202589870507;3601.6938560222366;3654.8172591370426;5098.413682426522;5347.946520534793;6107.583301036475;6154.661493617849;6310.52671049672;6182.6908654567205;6190.75236990521;6201.162843016192;6320.083587879766;6320.905186422207;5287;5416.133418653017;5488.69812160331;5489.99999999999;5614.410486898878;4704.929426058595;5563.415841336662;5597.8600534986745;5605.467480589337;5672.092465205568;5344.198370244456;5435.320584926884;5482.2873026506695;5515.310586176728;5525.834224973257;5507.366652834935;5511.4213007634025;5531.944680553183;5575.1915972184215;5584.441555844423;5428.565714742851;5472.042392581282;5480.616356855022;5483.643563168382;5509.393966663663;6199.907001394983;6211.4409703126485;6299.748010079591;6309.779157729457;6316.905246421307;6427.132337134473;6526.477881769494;6604.93395066048;6696.933030669728;6770.69531871067;6801.863962720768;6816.977453381983;6825.726970921207;6836.1113055302985;6839.521233513656;6304.527160462938;6313.431791138773;6343.619382836999;6371.617702937839;6376.426121649074;6691.5650482718465;6808.659567021728;6836.350546698056;6884.965575172192;6893.379595836399;6328.6519241442165;6448;6516.283208847118;6526.60840349571;6641.601503909781;6723.5293529452465;6755.966220168868;6780.1524809398825;6788.151481064867;6794.45644348451;6676.566023208476;6694.397504224643;6703.664816759143;6704.363085506869;6727.831804204836;6421.261554921244;6509.479241660656;6577.375149360803;6682.097916781172;6693.397594216543;5292.735363231823;5324.760385782649;5349.384820745586;5360.437154098763;5361.08861493547;4581.954180458153;4716.103940251342;4721.8347357842995;4733.715977041388;4743.905121897543;6110.801781737226;6305.3379395163765;6340.873182536324;6366.64983425916;6390.297067322591;6754.5609535380045;6778.694958726869;6836.93163068363;6867.038614593961;6889.689963951534;6347.428731414195;6350.333215012357;6355.586886852339;6373;6376.171097757262;5874.73563689635;5946.494547963385;5961.5230781537375;5968.940310596925;5969.970150149222;6659.300773418819;6776.627285499148;6853.657317134023;6928.1339832520935;6933.2026816916705;6787.864242715217;6839.384455399038;6854.177498700188;6872.312768723088;6887.552309099892;6954.200266969363;6964.547304425196;6993.510454268356;7027.191872934678;7029.824254393681;6452.580582262138;6497.675116244074;6512.641804700787;6639.70121344531;6708.060871478095;6770.966145169243;6803.727850885909;6822.317768223138;6825.795226143224;6857.828554286183;6344.68276586178;6372.58578192416;6376.202974628171;6589;6599.307072757483;5362.65142765719;6500.057491663777;6500.219973603198;6507.414332710079;6513.3161018094315;5285.365756109291;6218;6247.531435142473;6369.490440764635;6467;6488.772892948724;6563.212414510289;6606.53754237218;6616.503762217757;6623.569467984566;6413.743450262124;6414.871702566016;6425.710843012169;6454.774082907075;6457.225132984072 02:57:03 INFO - PID 1637 | |1;ext.html;807.8142027333714;827.9751607451775;1017.7150397888585;1078.6224821312542;1108.0304733358307;36354.09114772132;37039.70361037364;37089.25821483569;37125.81437092814;37154.699585514514;30564.541531876985;36860.81569592148;37177.62822371775;37446;37538.686145984895;7686.730964416232;7796.6491507882;8207.712730054449;8389.748307550753;8464.365172612048;8444.197801208875;8551.957240213822;8566.100559441262;8589.09814469481;8595.914040859605;8556.358273129541;8594.957025214866;8612.09572994836;8624.051354351017;8643.351748618847;8537.060923298432;8544.914550854473;8555.786105347384;8561.999999999969;8565.614547345385;8291.378146639026;8325.66697332106;8334.83330333396;8337.499750014958;8339.791505212357;37282.81358593189;37454.43818342727;37460.25079498422;37501.24997500062;37687.62312376869;3646.507721457596;3652.068722475778;3693.1874987502706;3693.2244228712;3694.4273637586216;30796.69203307983;30929.536056959165;31017.689823101708;31019.844900775577;31273.905413310426;3145.2765863851214;3174.714275715197;3195.328980914011;3199.168216263788;3203.7597180211346;297803;299373;300920;301506.49246753845;301933.49033254915;38719.225615488096;40011.79994100069;47375.52624473711;48260.27609585859;49423;50127;50376.496235037914;50436.234731784214;50448.49551504511;50487.24269135966;14403.279836008158;14406.42374305016;14563.980521363403;14564.41742330316;14584.48954286595;9468.57391417388;9770.169535589555;11759.412029398496;11791.823122653168;11909.166358355005;10952.726181845517;13529.66175845592;13575.66060848467;13691.726165476635;13780.862191377957;4955.380577427822;5007.749612519359;5477.123660214346;5964.612300200473;6027.126066720389;933.2529892481691;1006.3911333643041;1031.865857438528;1062.3201151263197;1072.6138590107555;23651.526969460516;24455.77721113937;24622.384440388774;24695;24752.381190470383;1145.8281257811395;1146.7305183281921;1177.2995067934564;1182.645206438065;1188.2039033847343;12076.033917286595;12567.120301578985;12633.620991370273;12652.367381631066;12739.42672579736;320836;321877;322351;322385;324080;53490;53612.731936340846;53639.73180134074;53643.46356536385;53793.73103134459;1514.9242537873063;1533.9156346401055;1564.3038847712887;1574.2522301906731;1578.729123055928;20057.997100145225;22257.21942244649;22266.332010039725;24173.033078676657;24511.89696463628;38457.615423845404;38528.80735596416;38590.42114368343;38611.648592299134;38724;262451;263924.80375981505;264525;265291;265733;29455.11634650964;29888;33088.50367244445;34975.65024349724;35327.76352827639;34328.45521951568;38459.42310865395;41646.95882602838;44176.77911610421;44302.77848610736;24775.752242477345;30639.00381763321;30640.774369024988;31046.75812967456;31142.909998149957;29543.704562954095;29574.112776616737;30110.69889301079;30316.5452518208;30442.39115217639;36556.26887462183;36594.8170259147;36683.81658091692;36715.44926826046;36821.63178368289;28566;30185.245368865955;30796;31083.533746993362;31212.531812022382;23197.536049279257;24268.271951841474;24463.633045503975;24475.38811529726;24566.877165614056 02:57:03 INFO - PID 1637 | |2;jquery.html;376.95959152738436;427.0384880851295;431.25177816488394;447.33347312504367;456.45681638849743;19534.804651953495;20698.793012069877;21839;22057.779422205815;22081;21489;22102.668459973112;22136.003879825406;22249;22677;5211.687298762067;6330.272018717837;6341.207349081365;6350.206224221973;6351.26960399553;5140.768665410047;5948.702564871739;6214.285357183937;6262.436380725734;6264.091706702526;6035.728392222339;6208.2239720034995;6223.626582405071;6239.438450539451;6240.781572644958;6127.754889804402;6203.410675985774;6214.627122372672;6226.3462336454695;6243.906341404882;5447.291852059247;5458.4902366642245;5535.086710692731;5539.889202215933;5560.332760068777;27417.45165096687;27774.444511109665;27863.86068069667;27895;29165.854170729224;2158.8596741211772;2222.6443768997046;2235.7764223577674;2235.888205589714;2238.429200553848;15409.30658120376;15556.988795728355;17863.374781882703;17983.460496185136;18541;2156.353094071788;2160.168335190947;2199.670049492573;2204.3386983904766;2206.5255969966374;163914.18042909828;169645;171826;172096;174727;12724.936375318064;12789.10476266671;12822.294773787531;13030.892374148303;13139.60581182566;16708.91645541789;16737;16745.665086698198;16776.245068971926;16834.494965151065;7730.497517661334;8968.730938071869;9277.58250878712;9303.674371396968;9423.764405889908;8886.644534218558;8905.821883562292;8920.420172688884;9053;9105.77235569116;7238.0952380952385;7705.22947705225;8042;8170.673173073011;8225.629846656795;1776.3338747969512;2057.434205593474;2186.5517568898654;2202.966955495669;2337.4623836517358;862.4264863865585;922.9630814767469;955.8470644696954;971.577363846729;1005.2510879394897;21539.89230053871;21572.844400396432;22057.669134962987;22569.425384865088;22601.30914905292;827.7765003449035;990.2869933647762;1020.3724709303725;1032.0969151992008;1076.1713480619878;9368.016358282419;10623.04392604654;11214.10287177024;11215.495302711397;11266.43667816606;474285;540973.5902640923;541531;545262;552860;122312.16531752031;128154;132762.33618832036;136759;138634;1020.8877023527406;1107.9612213572486;1209.3832145605804;1212.1817773003188;1223.4861358229564;21921.890390547946;22813.08747650142;23232.883835580713;23252.814493391354;23329;48642.75678621584;58641.774702390074;58702.706486467294;58934.41065589289;59678.70160649169;75612;77621.22378776367;83975.580122099;86412;86525.56737216274;12807.743845123232;12871.485140594272;13179.868201317864;13228.801567976681;13607.523736669169;14311.713765724418;14335.56993290203;14536;14697.41210351574;14708.191049492381;11971.760564788481;12104.152709310263;12184.268943863397;12251.387430628254;12256.509739610316;10987.505562249558;11360.488778004845;11501.412182258924;11805.645830625095;12095;11603.709907252387;12112.333821639895;12376.81434778497;12400.689982750504;12448.439820207932;12189.329586872453;12839.614811555668;13253.602391928258;13298.135621184592;13551.390187441786;11936.940315298369;12219.938900305442;12227.327496987751;12326.691832704255;12364.319962402154 02:57:03 INFO - PID 1637 | |3;mootools.html;1377.7037936843576;1418.3333833098447;1430.0418719457969;1437.8202724659418;1453.9345729442175;11241.55033798648;11692.707682307946;11710.473028713706;11726.003289720362;11924.403779810997;9779.559919803609;11638.941805290982;11717.589884354049;11785.528578856836;11825.113116516253;2524.242727181843;2563.3591602099477;2563.6923569171727;2566.345581876619;2567.050191429174;2478.31846242283;2536.2391282615235;2537.8350407223566;2538.0482319130324;2538.0989748639154;2493.6010238361864;2511.9623205651837;2529.4941011797623;2538.82228244023;2543.5167318209574;2480.776730094291;2497.7876880465183;2522.3946252899264;2530.671012768347;2534.4804315115357;2396.4727759892794;2405.2543711449507;2406.855588664686;2471.4686342436285;2527.78513826325;11775.116866235066;11851.466683999155;11855;11861;11881.524739010429;6631.701573429207;6831.385175334194;6870.931290687079;6872.175338959307;6921.031055652213;3901.3757798752063;3985.003749062734;4053.756774593534;4056.188762247533;4420.513743488214;1259.250745806244;1263.8546567144804;1288.3429450979975;1300.8959283257316;1301.8502872169727;16245.91877040619;16649;16675.583110422216;16771.329146834112;16835.326586936386;5600.803971861047;5682;5808.767649294065;5813.186153938452;5875.882482350415;10906.836397454044;11749.060075193964;11855.762884742257;11872.703182420335;11884.465199066062;6649.867002659919;6652.467802575782;6652.534322597372;6658.234303055211;6683.331666833375;5810.854728631818;6092.75628974836;6145.938540614537;6271.184745982993;6286.245650521967;3616.8010759408485;4208.936865947013;4382.496012958551;4396.60430561251;4445.466544014739;1143.6797696644953;1202.837616921722;1240.6464157715009;1278.418319664569;1280.4878048780563;806.4153488720748;809.4495742894889;830.6428235858518;839.3536976528161;861.5778268648443;5472.686555226796;5817.825465236049;6198.318184999646;6201.286852012076;6436.292007879129;591.54155529465;591.7189335065896;611.7277811373896;624.1916717850395;624.2727222785495;3837.0431080149324;4006.2988976928923;4068.8372465101656;4294.634956028773;4403.713758605681;14630.926845365704;14641.194734289717;14916.477923272634;14922.701545969237;14968.326425310886;14439.205843678488;15150.848491515164;15163.241837908061;15260.389584416498;15286.312115954808;7246.5289756165685;7623.31390174898;7650.234976502416;8206.753797386087;8585.527795971282;5204.427512973494;5802.564807639359;6948.166220053626;7158.141023077056;7170.462215333767;12980.480780768663;13014.934925325691;13034.348282585643;13284.402201901132;13498.460061597427;13845.861541384456;13869.583912482642;13882.722345553235;14013.159210447406;14235.217063061631;8152.755417337489;8254.257116859511;8294.460860044077;8294.543800091053;8299.004119505698;10535.578576857146;10640.84038739435;10669.57321707123;10746.408947507962;10780.598522192066;9090.681826136319;9137.242617261178;9140.405873618194;9189.356745027784;9239.491827949527;7060.611666358194;8832.602532885907;8945.955270223607;8971.147740964598;9107.681231156877;6995.615241161785;8212.384071194565;8329.625166867385;8356.498610083172;8358.498490090615;6027.427394397525;6071.392860713893;6183.134361189527;6197.473214776614;6272.5922815016875;5611.242482264842;5996.560825401785;6177.19696439478;6196.783112591038;6344.397282258178 02:57:03 INFO - PID 1637 | |4;prototype.html;337.65221821523824;398.62130975573206;400.2854903996367;404.39744780277357;414.0848724319254;5833.649981001137;6405.3914878086625;6407.0458510154385;6426.742930282789;6450.290468048517;5789.018655392476;6011.9779637461625;6136;6238.908191066556;6254.7177828545155;4098.692598055143;4165.979170104153;4411.294192929132;4428.689991700566;4733.337332773414;4707.799511124658;4751;4754.429468463772;4759.738214398207;4767.237242041274;4609.631229501649;4655.86032419028;4658.883527911812;4688.718676879398;4700.858974230761;4228.7462752234815;4506.414166158411;4681.104152818114;4688.203005489058;4713.53880297109;4144.44050053245;4172.332426811696;4181.184668989548;4192.874213773592;4195.328747400432;4416.492103408117;5597.3003374578175;5600.691961942132;6026.2165918430765;6144.539159563006;5409.404965453797;5438.293021907166;5446.428125046892;5448.318960129984;5466.316710411199;5864.501517371029;6217.5336849735995;6235.812925612239;6329.715162817684;6545.770898018546;3720.609336019706;3732.962670373289;4396.494403143615;4451.2655411857;4471.754053527055;6612.512184758439;6848.623325717083;7093.964530177368;7182.676779544933;7208.567485950807;3685.2997930393676;3914.412838074312;4430.047539778899;4462.598366146997;4500.302453119772;4562.384078149474;4570.954290457119;4575.267957126844;4576.039031803359;4588.701734387254;3960.8415663373144;4472.686911916199;4549.476810166807;4719.764011799396;5451.618386712971;5955.702214889238;6773.796786096425;6778.966105169541;6789.524733268722;6796.4562834973085;1542.9691406171814;1545.2737213509633;1561.8281988981203;1562.2110834028742;1568.2315665323908;927.6057675487891;1014.1886490807325;1021.3871676993892;1028.8971102889652;1031.2059714020163;5557.3331200256225;6334.619922804646;6351.428371446592;6353.428191462698;6386.2975072741965;6254.74981000764;6635.303293154246;6910.965445172843;6943.236244013256;7010.334018268256;6352.460040896483;6353.269374021954;6368.395002474757;6381.851266771932;6407.807765767035;3283.326917981808;3621;3635.5092062571216;3640;3650.525431693916;6829;6974.895376569356;7125.42996560274;7140.179629046497;7193.496455248187;6227.069608421404;6264.968675156595;7137.038943386367;7145.92854071463;7165.244515093836;5459.945400545944;7114.750983715545;7124.402176900632;7158.964205178941;7244.311790380011;5735.852829434047;5935.732892019783;6637.336266373517;6755;7126.536775109808;6535.3137920517665;6823.317668233137;6833.965830170818;6904.896426553505;6947.68735406918;6675.766348177791;7146.213916469288;7307.561546307238;7318.974563989678;7328.193898671036;3329.600447946165;4490.393796837385;4493.213687604722;4510.797014134438;4524.5249248828395;6530.412262896172;6998.580085194904;7036;7056.717731290691;7080.610566418896;5613.691246981455;6757.195763757985;6765.221999470124;6831.326325050394;6838.14523184602;5339.439358867263;5634.4365563443325;6682.465402767864;6713;6826.419754320938;6622.205335359788;6627.635480048643;6632.2372927114;6658.467322614082;6702.553567947566;5627;6522.380373864476;6731.528792984444;6794.694238759366;6886.965565172142;6561.835954101186;6573.80278591643;6593.977933420328;6603.80188594343;6623.304553021863 02:57:03 INFO - PID 1637 | |5;yui.html;770.5030255485212;828.593988945417;841.9452735572187;861.6682577207775;863.9136086391358;16506;16901.323947042103;16955.237014334354;18580.349687760896;19209;17926.28294868207;18279.908600457013;18484.537886552844;18597.628047439077;18870.811291887076;982.6904525074615;988.5205675247499;1001.5543083327922;1003.7239759066242;1004.4023805835516;984.1241295247236;999.6900960702169;1004.372267332917;1008.4352762453013;1009.0514915978993;1056.1867362131154;1056.3661802918225;1058.6982709927672;1059.1897198643044;1059.8834128245887;1041.052642095697;1050.6952983634776;1053.9209559283047;1055.3509591601187;1058.5871510111062;746.6005686957452;756.2890882570421;767.808047988003;767.9900930276668;768.8154842837735;16597.087160206185;17069.317227310894;17197.484075477754;17225;17246.568835779206;12781;17182.140892955427;17206.655866882593;17238.82761172385;17271.3955011574;13666.794998075036;13699.45202191911;13933.930330348283;14034.228117453536;14038.227897465635;3183.8885639002756;3397.0997685613333;3460.1176699941607;3487.2851065531504;3487.511748355232;98805.99999999929;100808.49595752047;127480;128898;129434;13382.531611393555;13567.1859688419;13660.180389176681;13704.314784260747;13716.0398772085;25006.624900626506;25939;25944.221673349828;26094;26319.605205921926;7210.67551960163;7229.457790665616;7436.587048460775;7741.264579864904;8066.15305392937;3454.5336379588935;3940.7044471664167;4014.6988975826343;4015.7188996769946;4025.5370632377067;3278.852451639634;4332.805023773938;4421.314696222091;4442.666799990014;4447.3773671685985;730.8867125595541;890.875277461156;893.6648756716231;896.2292428511475;900.8288425199191;517.6557589203213;576.7491141353524;662.7117204016268;680.1260380411148;702.8945658151318;11154.832677509843;12027.21823081497;12068.456919438646;12171.269723816422;12318.876811231952;654.7904670505487;657.4181849063613;662.6156829039146;676.577139287945;689.8827199376118;5465.982502860395;5486.835394938158;5609.551235901118;5641.12562552805;5681.829545113653;108674.45662771794;109703;110048.89951100547;110115;113446;98306.5084674572;101092;101181;101756;102482.54344824818;63097.58316917864;87298;87715.56142219248;88335.55832220797;88370.55814721013;10599.304111342148;10727.141828653534;11016.559337626406;11240.662780116609;11271.436428178722;42866.57133428626;43038;43038.92402689958;43648.56351436445;43651.34522982221;55635;56303;56385.71807141102;56632.15051774309;56935.43064569301;12116.636500904988;14016.719665606426;14533.85466145325;14582.927085364505;14658.63353416173;15479;15706.842931570995;15853.890227684413;15942.521724348288;16033.278502467183;11808.881911180779;13224;14347.56957291241;14778.77831832502;14912.478063268167;14156.929215353857;14513.346899389342;14544.85455145435;14643.633909152357;14662.560123196321;13898.791518127035;14218.933579981749;14312.785308220593;14342.067765595202;14489;10499.212559057949;10546.314489558154;10672.199585031309;10830.566777329133;10876;11731.824022639497;11786.60603924572;11831.46758395857;11932.582359617372;11966.880331196577 02:57:03 INFO - PID 1637 | __end_tp_report 02:57:03 INFO - PID 1637 | __start_cc_report 02:57:03 INFO - PID 1637 | _x_x_mozilla_cycle_collect,415 02:57:03 INFO - PID 1637 | __end_cc_report 02:57:03 INFO - PID 1637 | __startTimestamp1493891823312__endTimestamp 02:57:03 INFO - PID 1637 | 02:57:03 INFO - PID 1637 | ------- Summary: start ------- 02:57:03 INFO - PID 1637 | Number of tests: 6 02:57:03 INFO - PID 1637 | 02:57:03 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6169.29 Median:6376.43 stddev:763.65 (12.0%) stddev-sans-first:724.86 02:57:03 INFO - PID 1637 | Values: 3017.4 3078.4 3594.8 3601.7 3654.8 5098.4 5347.9 6107.6 6154.7 6310.5 6182.7 6190.8 6201.2 6320.1 6320.9 5287.0 5416.1 5488.7 5490.0 5614.4 4704.9 5563.4 5597.9 5605.5 5672.1 5344.2 5435.3 5482.3 5515.3 5525.8 5507.4 5511.4 5531.9 5575.2 5584.4 5428.6 5472.0 5480.6 5483.6 5509.4 6199.9 6211.4 6299.7 6309.8 6316.9 6427.1 6526.5 6604.9 6696.9 6770.7 6801.9 6817.0 6825.7 6836.1 6839.5 6304.5 6313.4 6343.6 6371.6 6376.4 6691.6 6808.7 6836.4 6885.0 6893.4 6328.7 6448.0 6516.3 6526.6 6641.6 6723.5 6756.0 6780.2 6788.2 6794.5 6676.6 6694.4 6703.7 6704.4 6727.8 6421.3 6509.5 6577.4 6682.1 6693.4 5292.7 5324.8 5349.4 5360.4 5361.1 4582.0 4716.1 4721.8 4733.7 4743.9 6110.8 6305.3 6340.9 6366.6 6390.3 6754.6 6778.7 6836.9 6867.0 6889.7 6347.4 6350.3 6355.6 6373.0 6376.2 5874.7 5946.5 5961.5 5968.9 5970.0 6659.3 6776.6 6853.7 6928.1 6933.2 6787.9 6839.4 6854.2 6872.3 6887.6 6954.2 6964.5 6993.5 7027.2 7029.8 6452.6 6497.7 6512.6 6639.7 6708.1 6771.0 6803.7 6822.3 6825.8 6857.8 6344.7 6372.6 6376.2 6589.0 6599.3 5362.7 6500.1 6500.2 6507.4 6513.3 5285.4 6218.0 6247.5 6369.5 6467.0 6488.8 6563.2 6606.5 6616.5 6623.6 6413.7 6414.9 6425.7 6454.8 6457.2 02:57:03 INFO - PID 1637 | 02:57:03 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:44363.65 Median:322368.00 stddev:77788.42 (24.1%) stddev-sans-first:77937.90 02:57:03 INFO - PID 1637 | Values: 807.8 828.0 1017.7 1078.6 1108.0 36354.1 37039.7 37089.3 37125.8 37154.7 30564.5 36860.8 37177.6 37446.0 37538.7 7686.7 7796.6 8207.7 8389.7 8464.4 8444.2 8552.0 8566.1 8589.1 8595.9 8556.4 8595.0 8612.1 8624.1 8643.4 8537.1 8544.9 8555.8 8562.0 8565.6 8291.4 8325.7 8334.8 8337.5 8339.8 37282.8 37454.4 37460.3 37501.2 37687.6 3646.5 3652.1 3693.2 3693.2 3694.4 30796.7 30929.5 31017.7 31019.8 31273.9 3145.3 3174.7 3195.3 3199.2 3203.8 297803.0 299373.0 300920.0 301506.5 301933.5 38719.2 40011.8 47375.5 48260.3 49423.0 50127.0 50376.5 50436.2 50448.5 50487.2 14403.3 14406.4 14564.0 14564.4 14584.5 9468.6 9770.2 11759.4 11791.8 11909.2 10952.7 13529.7 13575.7 13691.7 13780.9 4955.4 5007.7 5477.1 5964.6 6027.1 933.3 1006.4 1031.9 1062.3 1072.6 23651.5 24455.8 24622.4 24695.0 24752.4 1145.8 1146.7 1177.3 1182.6 1188.2 12076.0 12567.1 12633.6 12652.4 12739.4 320836.0 321877.0 322351.0 322385.0 324080.0 53490.0 53612.7 53639.7 53643.5 53793.7 1514.9 1533.9 1564.3 1574.3 1578.7 20058.0 22257.2 22266.3 24173.0 24511.9 38457.6 38528.8 38590.4 38611.6 38724.0 262451.0 263924.8 264525.0 265291.0 265733.0 29455.1 29888.0 33088.5 34975.7 35327.8 34328.5 38459.4 41647.0 44176.8 44302.8 24775.8 30639.0 30640.8 31046.8 31142.9 29543.7 29574.1 30110.7 30316.5 30442.4 36556.3 36594.8 36683.8 36715.4 36821.6 28566.0 30185.2 30796.0 31083.5 31212.5 23197.5 24268.3 24463.6 24475.4 24566.9 02:57:03 INFO - PID 1637 | 02:57:03 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:36126.09 Median:12132.15 stddev:91142.26 (751.2%) stddev-sans-first:91358.42 02:57:03 INFO - PID 1637 | Values: 377.0 427.0 431.3 447.3 456.5 19534.8 20698.8 21839.0 22057.8 22081.0 21489.0 22102.7 22136.0 22249.0 22677.0 5211.7 6330.3 6341.2 6350.2 6351.3 5140.8 5948.7 6214.3 6262.4 6264.1 6035.7 6208.2 6223.6 6239.4 6240.8 6127.8 6203.4 6214.6 6226.3 6243.9 5447.3 5458.5 5535.1 5539.9 5560.3 27417.5 27774.4 27863.9 27895.0 29165.9 2158.9 2222.6 2235.8 2235.9 2238.4 15409.3 15557.0 17863.4 17983.5 18541.0 2156.4 2160.2 2199.7 2204.3 2206.5 163914.2 169645.0 171826.0 172096.0 174727.0 12724.9 12789.1 12822.3 13030.9 13139.6 16708.9 16737.0 16745.7 16776.2 16834.5 7730.5 8968.7 9277.6 9303.7 9423.8 8886.6 8905.8 8920.4 9053.0 9105.8 7238.1 7705.2 8042.0 8170.7 8225.6 1776.3 2057.4 2186.6 2203.0 2337.5 862.4 923.0 955.8 971.6 1005.3 21539.9 21572.8 22057.7 22569.4 22601.3 827.8 990.3 1020.4 1032.1 1076.2 9368.0 10623.0 11214.1 11215.5 11266.4 474285.0 540973.6 541531.0 545262.0 552860.0 122312.2 128154.0 132762.3 136759.0 138634.0 1020.9 1108.0 1209.4 1212.2 1223.5 21921.9 22813.1 23232.9 23252.8 23329.0 48642.8 58641.8 58702.7 58934.4 59678.7 75612.0 77621.2 83975.6 86412.0 86525.6 12807.7 12871.5 13179.9 13228.8 13607.5 14311.7 14335.6 14536.0 14697.4 14708.2 11971.8 12104.2 12184.3 12251.4 12256.5 10987.5 11360.5 11501.4 11805.6 12095.0 11603.7 12112.3 12376.8 12400.7 12448.4 12189.3 12839.6 13253.6 13298.1 13551.4 11936.9 12219.9 12227.3 12326.7 12364.3 02:57:03 INFO - PID 1637 | 02:57:03 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:7031.94 Median:3869.21 stddev:4500.36 (116.3%) stddev-sans-first:4492.93 02:57:03 INFO - PID 1637 | Values: 1377.7 1418.3 1430.0 1437.8 1453.9 11241.6 11692.7 11710.5 11726.0 11924.4 9779.6 11638.9 11717.6 11785.5 11825.1 2524.2 2563.4 2563.7 2566.3 2567.1 2478.3 2536.2 2537.8 2538.0 2538.1 2493.6 2512.0 2529.5 2538.8 2543.5 2480.8 2497.8 2522.4 2530.7 2534.5 2396.5 2405.3 2406.9 2471.5 2527.8 11775.1 11851.5 11855.0 11861.0 11881.5 6631.7 6831.4 6870.9 6872.2 6921.0 3901.4 3985.0 4053.8 4056.2 4420.5 1259.3 1263.9 1288.3 1300.9 1301.9 16245.9 16649.0 16675.6 16771.3 16835.3 5600.8 5682.0 5808.8 5813.2 5875.9 10906.8 11749.1 11855.8 11872.7 11884.5 6649.9 6652.5 6652.5 6658.2 6683.3 5810.9 6092.8 6145.9 6271.2 6286.2 3616.8 4208.9 4382.5 4396.6 4445.5 1143.7 1202.8 1240.6 1278.4 1280.5 806.4 809.4 830.6 839.4 861.6 5472.7 5817.8 6198.3 6201.3 6436.3 591.5 591.7 611.7 624.2 624.3 3837.0 4006.3 4068.8 4294.6 4403.7 14630.9 14641.2 14916.5 14922.7 14968.3 14439.2 15150.8 15163.2 15260.4 15286.3 7246.5 7623.3 7650.2 8206.8 8585.5 5204.4 5802.6 6948.2 7158.1 7170.5 12980.5 13014.9 13034.3 13284.4 13498.5 13845.9 13869.6 13882.7 14013.2 14235.2 8152.8 8254.3 8294.5 8294.5 8299.0 10535.6 10640.8 10669.6 10746.4 10780.6 9090.7 9137.2 9140.4 9189.4 9239.5 7060.6 8832.6 8946.0 8971.1 9107.7 6995.6 8212.4 8329.6 8356.5 8358.5 6027.4 6071.4 6183.1 6197.5 6272.6 5611.2 5996.6 6177.2 6196.8 6344.4 02:57:03 INFO - PID 1637 | 02:57:03 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5388.31 Median:6081.11 stddev:1726.90 (28.4%) stddev-sans-first:1689.63 02:57:03 INFO - PID 1637 | Values: 337.7 398.6 400.3 404.4 414.1 5833.6 6405.4 6407.0 6426.7 6450.3 5789.0 6012.0 6136.0 6238.9 6254.7 4098.7 4166.0 4411.3 4428.7 4733.3 4707.8 4751.0 4754.4 4759.7 4767.2 4609.6 4655.9 4658.9 4688.7 4700.9 4228.7 4506.4 4681.1 4688.2 4713.5 4144.4 4172.3 4181.2 4192.9 4195.3 4416.5 5597.3 5600.7 6026.2 6144.5 5409.4 5438.3 5446.4 5448.3 5466.3 5864.5 6217.5 6235.8 6329.7 6545.8 3720.6 3733.0 4396.5 4451.3 4471.8 6612.5 6848.6 7094.0 7182.7 7208.6 3685.3 3914.4 4430.0 4462.6 4500.3 4562.4 4571.0 4575.3 4576.0 4588.7 3960.8 4472.7 4549.5 4719.8 5451.6 5955.7 6773.8 6779.0 6789.5 6796.5 1543.0 1545.3 1561.8 1562.2 1568.2 927.6 1014.2 1021.4 1028.9 1031.2 5557.3 6334.6 6351.4 6353.4 6386.3 6254.7 6635.3 6911.0 6943.2 7010.3 6352.5 6353.3 6368.4 6381.9 6407.8 3283.3 3621.0 3635.5 3640.0 3650.5 6829.0 6974.9 7125.4 7140.2 7193.5 6227.1 6265.0 7137.0 7145.9 7165.2 5459.9 7114.8 7124.4 7159.0 7244.3 5735.9 5935.7 6637.3 6755.0 7126.5 6535.3 6823.3 6834.0 6904.9 6947.7 6675.8 7146.2 7307.6 7319.0 7328.2 3329.6 4490.4 4493.2 4510.8 4524.5 6530.4 6998.6 7036.0 7056.7 7080.6 5613.7 6757.2 6765.2 6831.3 6838.1 5339.4 5634.4 6682.5 6713.0 6826.4 6622.2 6627.6 6632.2 6658.5 6702.6 5627.0 6522.4 6731.5 6794.7 6887.0 6561.8 6573.8 6594.0 6603.8 6623.3 02:57:03 INFO - PID 1637 | 02:57:03 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:21780.72 Median:17189.81 stddev:31364.06 (182.5%) stddev-sans-first:31412.37 02:57:03 INFO - PID 1637 | Values: 770.5 828.6 841.9 861.7 863.9 16506.0 16901.3 16955.2 18580.3 19209.0 17926.3 18279.9 18484.5 18597.6 18870.8 982.7 988.5 1001.6 1003.7 1004.4 984.1 999.7 1004.4 1008.4 1009.1 1056.2 1056.4 1058.7 1059.2 1059.9 1041.1 1050.7 1053.9 1055.4 1058.6 746.6 756.3 767.8 768.0 768.8 16597.1 17069.3 17197.5 17225.0 17246.6 12781.0 17182.1 17206.7 17238.8 17271.4 13666.8 13699.5 13933.9 14034.2 14038.2 3183.9 3397.1 3460.1 3487.3 3487.5 98806.0 100808.5 127480.0 128898.0 129434.0 13382.5 13567.2 13660.2 13704.3 13716.0 25006.6 25939.0 25944.2 26094.0 26319.6 7210.7 7229.5 7436.6 7741.3 8066.2 3454.5 3940.7 4014.7 4015.7 4025.5 3278.9 4332.8 4421.3 4442.7 4447.4 730.9 890.9 893.7 896.2 900.8 517.7 576.7 662.7 680.1 702.9 11154.8 12027.2 12068.5 12171.3 12318.9 654.8 657.4 662.6 676.6 689.9 5466.0 5486.8 5609.6 5641.1 5681.8 108674.5 109703.0 110048.9 110115.0 113446.0 98306.5 101092.0 101181.0 101756.0 102482.5 63097.6 87298.0 87715.6 88335.6 88370.6 10599.3 10727.1 11016.6 11240.7 11271.4 42866.6 43038.0 43038.9 43648.6 43651.3 55635.0 56303.0 56385.7 56632.2 56935.4 12116.6 14016.7 14533.9 14582.9 14658.6 15479.0 15706.8 15853.9 15942.5 16033.3 11808.9 13224.0 14347.6 14778.8 14912.5 14156.9 14513.3 14544.9 14643.6 14662.6 13898.8 14218.9 14312.8 14342.1 14489.0 10499.2 10546.3 10672.2 10830.6 10876.0 11731.8 11786.6 11831.5 11932.6 11966.9 02:57:03 INFO - PID 1637 | -------- Summary: end -------- 02:57:03 INFO - PID 1637 | 02:57:03 INFO - TEST-INFO | 1637: exit 0 02:57:03 INFO - TEST-OK | dromaeo_css | took 1114822ms 02:57:03 INFO - TEST-START | kraken 02:57:03 INFO - operating with platform_type : mac_ 02:57:03 INFO - Initialising browser for kraken test... 02:57:03 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpiNgvFW/profile http://localhost:49241/getInfo.html) 02:57:06 INFO - PID 1650 | 2017-05-04 02:57:06.525 plugin-container[1651:10154] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 02:57:06 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:57:06 INFO - PID 1650 | 2017-05-04 02:57:06.530 plugin-container[1651:10154] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.CFPasteboardClient' 02:57:06 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:57:06 INFO - PID 1650 | 2017-05-04 02:57:06.530 plugin-container[1651:10154] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:57:06 INFO - PID 1650 | __metrics Screen width/height:1600/1200 02:57:06 INFO - PID 1650 | colorDepth:24 02:57:06 INFO - PID 1650 | Browser inner width/height: 1024/691 02:57:06 INFO - PID 1650 | __metrics 02:57:07 INFO - TEST-INFO | 1650: exit 0 02:57:07 INFO - Browser initialized. 02:57:07 INFO - Running cycle 1/1 for kraken test... 02:57:07 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpiNgvFW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:57:10 INFO - PID 1653 | 2017-05-04 02:57:10.099 plugin-container[1654:10310] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 02:57:10 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:57:10 INFO - PID 1653 | 2017-05-04 02:57:10.100 plugin-container[1654:10310] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a03, name = 'com.apple.CFPasteboardClient' 02:57:10 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:57:10 INFO - PID 1653 | 2017-05-04 02:57:10.100 plugin-container[1654:10310] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:57:10 INFO - PID 1653 | 2017-05-04 02:57:10.599 plugin-container[1655:10356] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x902b, name = 'com.apple.tsm.portname' 02:57:10 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:57:10 INFO - PID 1653 | 2017-05-04 02:57:10.600 plugin-container[1655:10356] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 02:57:10 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:57:10 INFO - PID 1653 | 2017-05-04 02:57:10.600 plugin-container[1655:10356] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:57:19 INFO - PID 1653 | RSS: Main: 233865216 02:57:19 INFO - PID 1653 | 02:57:22 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 02:57:23 INFO - PID 1653 | RSS: Main: 246890496 02:57:23 INFO - PID 1653 | 02:57:26 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 02:57:26 INFO - PID 1653 | RSS: Main: 255041536 02:57:26 INFO - PID 1653 | 02:57:30 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 02:57:30 INFO - PID 1653 | RSS: Main: 257609728 02:57:30 INFO - PID 1653 | 02:57:32 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 02:57:33 INFO - PID 1653 | RSS: Main: 258584576 02:57:33 INFO - PID 1653 | 02:57:35 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:57:35 INFO - PID 1653 | RSS: Main: 259051520 02:57:35 INFO - PID 1653 | 02:57:38 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 02:57:39 INFO - PID 1653 | RSS: Main: 264859648 02:57:39 INFO - PID 1653 | 02:57:42 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 02:57:43 INFO - PID 1653 | RSS: Main: 267976704 02:57:43 INFO - PID 1653 | 02:57:46 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 02:57:46 INFO - PID 1653 | RSS: Main: 267268096 02:57:46 INFO - PID 1653 | 02:57:48 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:57:48 INFO - PID 1653 | RSS: Main: 266891264 02:57:48 INFO - PID 1653 | 02:57:50 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:57:51 INFO - PID 1653 | RSS: Main: 266747904 02:57:51 INFO - PID 1653 | 02:57:53 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:57:53 INFO - PID 1653 | RSS: Main: 266182656 02:57:53 INFO - PID 1653 | 02:57:56 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:57:57 INFO - PID 1653 | RSS: Main: 266252288 02:57:57 INFO - PID 1653 | 02:58:00 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:58:01 INFO - PID 1653 | RSS: Main: 266616832 02:58:01 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 02:58:03 INFO - PID 1653 | RSS: Main: 268382208 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | __start_tp_report 02:58:03 INFO - PID 1653 | _x_x_mozilla_page_load 02:58:03 INFO - PID 1653 | _x_x_mozilla_page_load_details 02:58:03 INFO - PID 1653 | |i|pagename|runs| 02:58:03 INFO - PID 1653 | |0;ai-astar;102;117;116;115;116;116;116;116;117;117 02:58:03 INFO - PID 1653 | |1;audio-beat-detection;165;145;119;151;110;111;111;94;149;144 02:58:03 INFO - PID 1653 | |2;audio-dft;201;220;194;211;215;185;201;264;250;277 02:58:03 INFO - PID 1653 | |3;audio-fft;83;59;65;111;78;57;57;48;77;65 02:58:03 INFO - PID 1653 | |4;audio-oscillator;88;71;80;71;71;69;85;84;79;90 02:58:03 INFO - PID 1653 | |5;imaging-gaussian-blur;89;90;89;89;90;89;93;89;92;127 02:58:03 INFO - PID 1653 | |6;imaging-darkroom;106;98;98;97;96;97;98;107;153;117 02:58:03 INFO - PID 1653 | |7;imaging-desaturate;94;93;91;92;92;90;93;93;94;91 02:58:03 INFO - PID 1653 | |8;json-parse-financial;51;50;48;52;49;52;52;50;50;51 02:58:03 INFO - PID 1653 | |9;json-stringify-tinderbox;57;60;68;58;64;63;54;57;55;57 02:58:03 INFO - PID 1653 | |10;stanford-crypto-aes;82;82;86;83;83;84;79;85;87;83 02:58:03 INFO - PID 1653 | |11;stanford-crypto-ccm;136;141;140;149;157;133;144;135;206;161 02:58:03 INFO - PID 1653 | |12;stanford-crypto-pbkdf2;186;188;229;185;181;183;188;182;188;183 02:58:03 INFO - PID 1653 | |13;stanford-crypto-sha256-iterative;72;71;71;67;70;67;68;69;65;70 02:58:03 INFO - PID 1653 | __end_tp_report 02:58:03 INFO - PID 1653 | __start_cc_report 02:58:03 INFO - PID 1653 | _x_x_mozilla_cycle_collect,962 02:58:03 INFO - PID 1653 | __end_cc_report 02:58:03 INFO - PID 1653 | __startTimestamp1493891883259__endTimestamp 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | ------- Summary: start ------- 02:58:03 INFO - PID 1653 | Number of tests: 14 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | [#0] ai-astar Cycles:10 Average:114.80 Median:116.00 stddev:4.54 (3.9%) stddev-sans-first:0.67 02:58:03 INFO - PID 1653 | Values: 102.0 117.0 116.0 115.0 116.0 116.0 116.0 116.0 117.0 117.0 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:147.00 stddev:23.54 (16.0%) stddev-sans-first:21.27 02:58:03 INFO - PID 1653 | Values: 165.0 145.0 119.0 151.0 110.0 111.0 111.0 94.0 149.0 144.0 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | [#2] audio-dft Cycles:10 Average:221.80 Median:217.50 stddev:31.24 (14.4%) stddev-sans-first:32.21 02:58:03 INFO - PID 1653 | Values: 201.0 220.0 194.0 211.0 215.0 185.0 201.0 264.0 250.0 277.0 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | [#3] audio-fft Cycles:10 Average:70.00 Median:65.00 stddev:18.12 (27.9%) stddev-sans-first:18.60 02:58:03 INFO - PID 1653 | Values: 83.0 59.0 65.0 111.0 78.0 57.0 57.0 48.0 77.0 65.0 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:82.00 stddev:7.86 (9.6%) stddev-sans-first:7.60 02:58:03 INFO - PID 1653 | Values: 88.0 71.0 80.0 71.0 71.0 69.0 85.0 84.0 79.0 90.0 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:93.70 Median:89.50 stddev:11.79 (13.2%) stddev-sans-first:12.38 02:58:03 INFO - PID 1653 | Values: 89.0 90.0 89.0 89.0 90.0 89.0 93.0 89.0 92.0 127.0 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | [#6] imaging-darkroom Cycles:10 Average:106.70 Median:97.00 stddev:17.58 (18.1%) stddev-sans-first:18.64 02:58:03 INFO - PID 1653 | Values: 106.0 98.0 98.0 97.0 96.0 97.0 98.0 107.0 153.0 117.0 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | [#7] imaging-desaturate Cycles:10 Average:92.30 Median:93.00 stddev:1.34 (1.4%) stddev-sans-first:1.27 02:58:03 INFO - PID 1653 | Values: 94.0 93.0 91.0 92.0 92.0 90.0 93.0 93.0 94.0 91.0 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:51.00 stddev:1.35 (2.7%) stddev-sans-first:1.42 02:58:03 INFO - PID 1653 | Values: 51.0 50.0 48.0 52.0 49.0 52.0 52.0 50.0 50.0 51.0 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.00 stddev:4.42 (7.5%) stddev-sans-first:4.61 02:58:03 INFO - PID 1653 | Values: 57.0 60.0 68.0 58.0 64.0 63.0 54.0 57.0 55.0 57.0 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:83.50 stddev:2.27 (2.7%) stddev-sans-first:2.35 02:58:03 INFO - PID 1653 | Values: 82.0 82.0 86.0 83.0 83.0 84.0 79.0 85.0 87.0 83.0 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:150.20 Median:146.50 stddev:21.69 (14.8%) stddev-sans-first:22.39 02:58:03 INFO - PID 1653 | Values: 136.0 141.0 140.0 149.0 157.0 133.0 144.0 135.0 206.0 161.0 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.30 Median:187.00 stddev:14.19 (7.6%) stddev-sans-first:15.00 02:58:03 INFO - PID 1653 | Values: 186.0 188.0 229.0 185.0 181.0 183.0 188.0 182.0 188.0 183.0 02:58:03 INFO - PID 1653 | 02:58:03 INFO - PID 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:70.00 stddev:2.21 (3.2%) stddev-sans-first:2.06 02:58:03 INFO - PID 1653 | Values: 72.0 71.0 71.0 67.0 70.0 67.0 68.0 69.0 65.0 70.0 02:58:03 INFO - PID 1653 | -------- Summary: end -------- 02:58:03 INFO - PID 1653 | 02:58:04 INFO - TEST-INFO | 1653: exit 0 02:58:04 INFO - TEST-OK | kraken | took 60411ms 02:58:04 INFO - SUITE-END | took 1175s 02:58:04 INFO - Completed test suite (00:19:35) 02:58:04 INFO - javascript benchmark 02:58:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7886.244362789166, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3017.3965206958615, 3078.3997120561485, 3594.8202589870507, 3601.6938560222366, 3654.8172591370426, 5098.413682426522, 5347.946520534793, 6107.583301036475, 6154.661493617849, 6310.52671049672, 6182.6908654567205, 6190.75236990521, 6201.162843016192, 6320.083587879766, 6320.905186422207, 5287.0, 5416.133418653017, 5488.69812160331, 5489.99999999999, 5614.410486898878, 4704.929426058595, 5563.415841336662, 5597.8600534986745, 5605.467480589337, 5672.092465205568, 5344.198370244456, 5435.320584926884, 5482.2873026506695, 5515.310586176728, 5525.834224973257, 5507.366652834935, 5511.4213007634025, 5531.944680553183, 5575.1915972184215, 5584.441555844423, 5428.565714742851, 5472.042392581282, 5480.616356855022, 5483.643563168382, 5509.393966663663, 6199.907001394983, 6211.4409703126485, 6299.748010079591, 6309.779157729457, 6316.905246421307, 6427.132337134473, 6526.477881769494, 6604.93395066048, 6696.933030669728, 6770.69531871067, 6801.863962720768, 6816.977453381983, 6825.726970921207, 6836.1113055302985, 6839.521233513656, 6304.527160462938, 6313.431791138773, 6343.619382836999, 6371.617702937839, 6376.426121649074, 6691.5650482718465, 6808.659567021728, 6836.350546698056, 6884.965575172192, 6893.379595836399, 6328.6519241442165, 6448.0, 6516.283208847118, 6526.60840349571, 6641.601503909781, 6723.5293529452465, 6755.966220168868, 6780.1524809398825, 6788.151481064867, 6794.45644348451, 6676.566023208476, 6694.397504224643, 6703.664816759143, 6704.363085506869, 6727.831804204836, 6421.261554921244, 6509.479241660656, 6577.375149360803, 6682.097916781172, 6693.397594216543, 5292.735363231823, 5324.760385782649, 5349.384820745586, 5360.437154098763, 5361.08861493547, 4581.954180458153, 4716.103940251342, 4721.8347357842995, 4733.715977041388, 4743.905121897543, 6110.801781737226, 6305.3379395163765, 6340.873182536324, 6366.64983425916, 6390.297067322591, 6754.5609535380045, 6778.694958726869, 6836.93163068363, 6867.038614593961, 6889.689963951534, 6347.428731414195, 6350.333215012357, 6355.586886852339, 6373.0, 6376.171097757262, 5874.73563689635, 5946.494547963385, 5961.5230781537375, 5968.940310596925, 5969.970150149222, 6659.300773418819, 6776.627285499148, 6853.657317134023, 6928.1339832520935, 6933.2026816916705, 6787.864242715217, 6839.384455399038, 6854.177498700188, 6872.312768723088, 6887.552309099892, 6954.200266969363, 6964.547304425196, 6993.510454268356, 7027.191872934678, 7029.824254393681, 6452.580582262138, 6497.675116244074, 6512.641804700787, 6639.70121344531, 6708.060871478095, 6770.966145169243, 6803.727850885909, 6822.317768223138, 6825.795226143224, 6857.828554286183, 6344.68276586178, 6372.58578192416, 6376.202974628171, 6589.0, 6599.307072757483, 5362.65142765719, 6500.057491663777, 6500.219973603198, 6507.414332710079, 6513.3161018094315, 5285.365756109291, 6218.0, 6247.531435142473, 6369.490440764635, 6467.0, 6488.772892948724, 6563.212414510289, 6606.53754237218, 6616.503762217757, 6623.569467984566, 6413.743450262124, 6414.871702566016, 6425.710843012169, 6454.774082907075, 6457.225132984072], "value": 6115.206796740157, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [807.8142027333714, 827.9751607451775, 1017.7150397888585, 1078.6224821312542, 1108.0304733358307, 36354.09114772132, 37039.70361037364, 37089.25821483569, 37125.81437092814, 37154.699585514514, 30564.541531876985, 36860.81569592148, 37177.62822371775, 37446.0, 37538.686145984895, 7686.730964416232, 7796.6491507882, 8207.712730054449, 8389.748307550753, 8464.365172612048, 8444.197801208875, 8551.957240213822, 8566.100559441262, 8589.09814469481, 8595.914040859605, 8556.358273129541, 8594.957025214866, 8612.09572994836, 8624.051354351017, 8643.351748618847, 8537.060923298432, 8544.914550854473, 8555.786105347384, 8561.999999999969, 8565.614547345385, 8291.378146639026, 8325.66697332106, 8334.83330333396, 8337.499750014958, 8339.791505212357, 37282.81358593189, 37454.43818342727, 37460.25079498422, 37501.24997500062, 37687.62312376869, 3646.507721457596, 3652.068722475778, 3693.1874987502706, 3693.2244228712, 3694.4273637586216, 30796.69203307983, 30929.536056959165, 31017.689823101708, 31019.844900775577, 31273.905413310426, 3145.2765863851214, 3174.714275715197, 3195.328980914011, 3199.168216263788, 3203.7597180211346, 297803.0, 299373.0, 300920.0, 301506.49246753845, 301933.49033254915, 38719.225615488096, 40011.79994100069, 47375.52624473711, 48260.27609585859, 49423.0, 50127.0, 50376.496235037914, 50436.234731784214, 50448.49551504511, 50487.24269135966, 14403.279836008158, 14406.42374305016, 14563.980521363403, 14564.41742330316, 14584.48954286595, 9468.57391417388, 9770.169535589555, 11759.412029398496, 11791.823122653168, 11909.166358355005, 10952.726181845517, 13529.66175845592, 13575.66060848467, 13691.726165476635, 13780.862191377957, 4955.380577427822, 5007.749612519359, 5477.123660214346, 5964.612300200473, 6027.126066720389, 933.2529892481691, 1006.3911333643041, 1031.865857438528, 1062.3201151263197, 1072.6138590107555, 23651.526969460516, 24455.77721113937, 24622.384440388774, 24695.0, 24752.381190470383, 1145.8281257811395, 1146.7305183281921, 1177.2995067934564, 1182.645206438065, 1188.2039033847343, 12076.033917286595, 12567.120301578985, 12633.620991370273, 12652.367381631066, 12739.42672579736, 320836.0, 321877.0, 322351.0, 322385.0, 324080.0, 53490.0, 53612.731936340846, 53639.73180134074, 53643.46356536385, 53793.73103134459, 1514.9242537873063, 1533.9156346401055, 1564.3038847712887, 1574.2522301906731, 1578.729123055928, 20057.997100145225, 22257.21942244649, 22266.332010039725, 24173.033078676657, 24511.89696463628, 38457.615423845404, 38528.80735596416, 38590.42114368343, 38611.648592299134, 38724.0, 262451.0, 263924.80375981505, 264525.0, 265291.0, 265733.0, 29455.11634650964, 29888.0, 33088.50367244445, 34975.65024349724, 35327.76352827639, 34328.45521951568, 38459.42310865395, 41646.95882602838, 44176.77911610421, 44302.77848610736, 24775.752242477345, 30639.00381763321, 30640.774369024988, 31046.75812967456, 31142.909998149957, 29543.704562954095, 29574.112776616737, 30110.69889301079, 30316.5452518208, 30442.39115217639, 36556.26887462183, 36594.8170259147, 36683.81658091692, 36715.44926826046, 36821.63178368289, 28566.0, 30185.245368865955, 30796.0, 31083.533746993362, 31212.531812022382, 23197.536049279257, 24268.271951841474, 24463.633045503975, 24475.38811529726, 24566.877165614056], "value": 17351.407828170148, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.95959152738436, 427.0384880851295, 431.25177816488394, 447.33347312504367, 456.45681638849743, 19534.804651953495, 20698.793012069877, 21839.0, 22057.779422205815, 22081.0, 21489.0, 22102.668459973112, 22136.003879825406, 22249.0, 22677.0, 5211.687298762067, 6330.272018717837, 6341.207349081365, 6350.206224221973, 6351.26960399553, 5140.768665410047, 5948.702564871739, 6214.285357183937, 6262.436380725734, 6264.091706702526, 6035.728392222339, 6208.2239720034995, 6223.626582405071, 6239.438450539451, 6240.781572644958, 6127.754889804402, 6203.410675985774, 6214.627122372672, 6226.3462336454695, 6243.906341404882, 5447.291852059247, 5458.4902366642245, 5535.086710692731, 5539.889202215933, 5560.332760068777, 27417.45165096687, 27774.444511109665, 27863.86068069667, 27895.0, 29165.854170729224, 2158.8596741211772, 2222.6443768997046, 2235.7764223577674, 2235.888205589714, 2238.429200553848, 15409.30658120376, 15556.988795728355, 17863.374781882703, 17983.460496185136, 18541.0, 2156.353094071788, 2160.168335190947, 2199.670049492573, 2204.3386983904766, 2206.5255969966374, 163914.18042909828, 169645.0, 171826.0, 172096.0, 174727.0, 12724.936375318064, 12789.10476266671, 12822.294773787531, 13030.892374148303, 13139.60581182566, 16708.91645541789, 16737.0, 16745.665086698198, 16776.245068971926, 16834.494965151065, 7730.497517661334, 8968.730938071869, 9277.58250878712, 9303.674371396968, 9423.764405889908, 8886.644534218558, 8905.821883562292, 8920.420172688884, 9053.0, 9105.77235569116, 7238.0952380952385, 7705.22947705225, 8042.0, 8170.673173073011, 8225.629846656795, 1776.3338747969512, 2057.434205593474, 2186.5517568898654, 2202.966955495669, 2337.4623836517358, 862.4264863865585, 922.9630814767469, 955.8470644696954, 971.577363846729, 1005.2510879394897, 21539.89230053871, 21572.844400396432, 22057.669134962987, 22569.425384865088, 22601.30914905292, 827.7765003449035, 990.2869933647762, 1020.3724709303725, 1032.0969151992008, 1076.1713480619878, 9368.016358282419, 10623.04392604654, 11214.10287177024, 11215.495302711397, 11266.43667816606, 474285.0, 540973.5902640923, 541531.0, 545262.0, 552860.0, 122312.16531752031, 128154.0, 132762.33618832036, 136759.0, 138634.0, 1020.8877023527406, 1107.9612213572486, 1209.3832145605804, 1212.1817773003188, 1223.4861358229564, 21921.890390547946, 22813.08747650142, 23232.883835580713, 23252.814493391354, 23329.0, 48642.75678621584, 58641.774702390074, 58702.706486467294, 58934.41065589289, 59678.70160649169, 75612.0, 77621.22378776367, 83975.580122099, 86412.0, 86525.56737216274, 12807.743845123232, 12871.485140594272, 13179.868201317864, 13228.801567976681, 13607.523736669169, 14311.713765724418, 14335.56993290203, 14536.0, 14697.41210351574, 14708.191049492381, 11971.760564788481, 12104.152709310263, 12184.268943863397, 12251.387430628254, 12256.509739610316, 10987.505562249558, 11360.488778004845, 11501.412182258924, 11805.645830625095, 12095.0, 11603.709907252387, 12112.333821639895, 12376.81434778497, 12400.689982750504, 12448.439820207932, 12189.329586872453, 12839.614811555668, 13253.602391928258, 13298.135621184592, 13551.390187441786, 11936.940315298369, 12219.938900305442, 12227.327496987751, 12326.691832704255, 12364.319962402154], "value": 10830.525073880679, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.7037936843576, 1418.3333833098447, 1430.0418719457969, 1437.8202724659418, 1453.9345729442175, 11241.55033798648, 11692.707682307946, 11710.473028713706, 11726.003289720362, 11924.403779810997, 9779.559919803609, 11638.941805290982, 11717.589884354049, 11785.528578856836, 11825.113116516253, 2524.242727181843, 2563.3591602099477, 2563.6923569171727, 2566.345581876619, 2567.050191429174, 2478.31846242283, 2536.2391282615235, 2537.8350407223566, 2538.0482319130324, 2538.0989748639154, 2493.6010238361864, 2511.9623205651837, 2529.4941011797623, 2538.82228244023, 2543.5167318209574, 2480.776730094291, 2497.7876880465183, 2522.3946252899264, 2530.671012768347, 2534.4804315115357, 2396.4727759892794, 2405.2543711449507, 2406.855588664686, 2471.4686342436285, 2527.78513826325, 11775.116866235066, 11851.466683999155, 11855.0, 11861.0, 11881.524739010429, 6631.701573429207, 6831.385175334194, 6870.931290687079, 6872.175338959307, 6921.031055652213, 3901.3757798752063, 3985.003749062734, 4053.756774593534, 4056.188762247533, 4420.513743488214, 1259.250745806244, 1263.8546567144804, 1288.3429450979975, 1300.8959283257316, 1301.8502872169727, 16245.91877040619, 16649.0, 16675.583110422216, 16771.329146834112, 16835.326586936386, 5600.803971861047, 5682.0, 5808.767649294065, 5813.186153938452, 5875.882482350415, 10906.836397454044, 11749.060075193964, 11855.762884742257, 11872.703182420335, 11884.465199066062, 6649.867002659919, 6652.467802575782, 6652.534322597372, 6658.234303055211, 6683.331666833375, 5810.854728631818, 6092.75628974836, 6145.938540614537, 6271.184745982993, 6286.245650521967, 3616.8010759408485, 4208.936865947013, 4382.496012958551, 4396.60430561251, 4445.466544014739, 1143.6797696644953, 1202.837616921722, 1240.6464157715009, 1278.418319664569, 1280.4878048780563, 806.4153488720748, 809.4495742894889, 830.6428235858518, 839.3536976528161, 861.5778268648443, 5472.686555226796, 5817.825465236049, 6198.318184999646, 6201.286852012076, 6436.292007879129, 591.54155529465, 591.7189335065896, 611.7277811373896, 624.1916717850395, 624.2727222785495, 3837.0431080149324, 4006.2988976928923, 4068.8372465101656, 4294.634956028773, 4403.713758605681, 14630.926845365704, 14641.194734289717, 14916.477923272634, 14922.701545969237, 14968.326425310886, 14439.205843678488, 15150.848491515164, 15163.241837908061, 15260.389584416498, 15286.312115954808, 7246.5289756165685, 7623.31390174898, 7650.234976502416, 8206.753797386087, 8585.527795971282, 5204.427512973494, 5802.564807639359, 6948.166220053626, 7158.141023077056, 7170.462215333767, 12980.480780768663, 13014.934925325691, 13034.348282585643, 13284.402201901132, 13498.460061597427, 13845.861541384456, 13869.583912482642, 13882.722345553235, 14013.159210447406, 14235.217063061631, 8152.755417337489, 8254.257116859511, 8294.460860044077, 8294.543800091053, 8299.004119505698, 10535.578576857146, 10640.84038739435, 10669.57321707123, 10746.408947507962, 10780.598522192066, 9090.681826136319, 9137.242617261178, 9140.405873618194, 9189.356745027784, 9239.491827949527, 7060.611666358194, 8832.602532885907, 8945.955270223607, 8971.147740964598, 9107.681231156877, 6995.615241161785, 8212.384071194565, 8329.625166867385, 8356.498610083172, 8358.498490090615, 6027.427394397525, 6071.392860713893, 6183.134361189527, 6197.473214776614, 6272.5922815016875, 5611.242482264842, 5996.560825401785, 6177.19696439478, 6196.783112591038, 6344.397282258178], "value": 5278.585154905545, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.65221821523824, 398.62130975573206, 400.2854903996367, 404.39744780277357, 414.0848724319254, 5833.649981001137, 6405.3914878086625, 6407.0458510154385, 6426.742930282789, 6450.290468048517, 5789.018655392476, 6011.9779637461625, 6136.0, 6238.908191066556, 6254.7177828545155, 4098.692598055143, 4165.979170104153, 4411.294192929132, 4428.689991700566, 4733.337332773414, 4707.799511124658, 4751.0, 4754.429468463772, 4759.738214398207, 4767.237242041274, 4609.631229501649, 4655.86032419028, 4658.883527911812, 4688.718676879398, 4700.858974230761, 4228.7462752234815, 4506.414166158411, 4681.104152818114, 4688.203005489058, 4713.53880297109, 4144.44050053245, 4172.332426811696, 4181.184668989548, 4192.874213773592, 4195.328747400432, 4416.492103408117, 5597.3003374578175, 5600.691961942132, 6026.2165918430765, 6144.539159563006, 5409.404965453797, 5438.293021907166, 5446.428125046892, 5448.318960129984, 5466.316710411199, 5864.501517371029, 6217.5336849735995, 6235.812925612239, 6329.715162817684, 6545.770898018546, 3720.609336019706, 3732.962670373289, 4396.494403143615, 4451.2655411857, 4471.754053527055, 6612.512184758439, 6848.623325717083, 7093.964530177368, 7182.676779544933, 7208.567485950807, 3685.2997930393676, 3914.412838074312, 4430.047539778899, 4462.598366146997, 4500.302453119772, 4562.384078149474, 4570.954290457119, 4575.267957126844, 4576.039031803359, 4588.701734387254, 3960.8415663373144, 4472.686911916199, 4549.476810166807, 4719.764011799396, 5451.618386712971, 5955.702214889238, 6773.796786096425, 6778.966105169541, 6789.524733268722, 6796.4562834973085, 1542.9691406171814, 1545.2737213509633, 1561.8281988981203, 1562.2110834028742, 1568.2315665323908, 927.6057675487891, 1014.1886490807325, 1021.3871676993892, 1028.8971102889652, 1031.2059714020163, 5557.3331200256225, 6334.619922804646, 6351.428371446592, 6353.428191462698, 6386.2975072741965, 6254.74981000764, 6635.303293154246, 6910.965445172843, 6943.236244013256, 7010.334018268256, 6352.460040896483, 6353.269374021954, 6368.395002474757, 6381.851266771932, 6407.807765767035, 3283.326917981808, 3621.0, 3635.5092062571216, 3640.0, 3650.525431693916, 6829.0, 6974.895376569356, 7125.42996560274, 7140.179629046497, 7193.496455248187, 6227.069608421404, 6264.968675156595, 7137.038943386367, 7145.92854071463, 7165.244515093836, 5459.945400545944, 7114.750983715545, 7124.402176900632, 7158.964205178941, 7244.311790380011, 5735.852829434047, 5935.732892019783, 6637.336266373517, 6755.0, 7126.536775109808, 6535.3137920517665, 6823.317668233137, 6833.965830170818, 6904.896426553505, 6947.68735406918, 6675.766348177791, 7146.213916469288, 7307.561546307238, 7318.974563989678, 7328.193898671036, 3329.600447946165, 4490.393796837385, 4493.213687604722, 4510.797014134438, 4524.5249248828395, 6530.412262896172, 6998.580085194904, 7036.0, 7056.717731290691, 7080.610566418896, 5613.691246981455, 6757.195763757985, 6765.221999470124, 6831.326325050394, 6838.14523184602, 5339.439358867263, 5634.4365563443325, 6682.465402767864, 6713.0, 6826.419754320938, 6622.205335359788, 6627.635480048643, 6632.2372927114, 6658.467322614082, 6702.553567947566, 5627.0, 6522.380373864476, 6731.528792984444, 6794.694238759366, 6886.965565172142, 6561.835954101186, 6573.80278591643, 6593.977933420328, 6603.80188594343, 6623.304553021863], "value": 4845.850014919378, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [770.5030255485212, 828.593988945417, 841.9452735572187, 861.6682577207775, 863.9136086391358, 16506.0, 16901.323947042103, 16955.237014334354, 18580.349687760896, 19209.0, 17926.28294868207, 18279.908600457013, 18484.537886552844, 18597.628047439077, 18870.811291887076, 982.6904525074615, 988.5205675247499, 1001.5543083327922, 1003.7239759066242, 1004.4023805835516, 984.1241295247236, 999.6900960702169, 1004.372267332917, 1008.4352762453013, 1009.0514915978993, 1056.1867362131154, 1056.3661802918225, 1058.6982709927672, 1059.1897198643044, 1059.8834128245887, 1041.052642095697, 1050.6952983634776, 1053.9209559283047, 1055.3509591601187, 1058.5871510111062, 746.6005686957452, 756.2890882570421, 767.808047988003, 767.9900930276668, 768.8154842837735, 16597.087160206185, 17069.317227310894, 17197.484075477754, 17225.0, 17246.568835779206, 12781.0, 17182.140892955427, 17206.655866882593, 17238.82761172385, 17271.3955011574, 13666.794998075036, 13699.45202191911, 13933.930330348283, 14034.228117453536, 14038.227897465635, 3183.8885639002756, 3397.0997685613333, 3460.1176699941607, 3487.2851065531504, 3487.511748355232, 98805.99999999929, 100808.49595752047, 127480.0, 128898.0, 129434.0, 13382.531611393555, 13567.1859688419, 13660.180389176681, 13704.314784260747, 13716.0398772085, 25006.624900626506, 25939.0, 25944.221673349828, 26094.0, 26319.605205921926, 7210.67551960163, 7229.457790665616, 7436.587048460775, 7741.264579864904, 8066.15305392937, 3454.5336379588935, 3940.7044471664167, 4014.6988975826343, 4015.7188996769946, 4025.5370632377067, 3278.852451639634, 4332.805023773938, 4421.314696222091, 4442.666799990014, 4447.3773671685985, 730.8867125595541, 890.875277461156, 893.6648756716231, 896.2292428511475, 900.8288425199191, 517.6557589203213, 576.7491141353524, 662.7117204016268, 680.1260380411148, 702.8945658151318, 11154.832677509843, 12027.21823081497, 12068.456919438646, 12171.269723816422, 12318.876811231952, 654.7904670505487, 657.4181849063613, 662.6156829039146, 676.577139287945, 689.8827199376118, 5465.982502860395, 5486.835394938158, 5609.551235901118, 5641.12562552805, 5681.829545113653, 108674.45662771794, 109703.0, 110048.89951100547, 110115.0, 113446.0, 98306.5084674572, 101092.0, 101181.0, 101756.0, 102482.54344824818, 63097.58316917864, 87298.0, 87715.56142219248, 88335.55832220797, 88370.55814721013, 10599.304111342148, 10727.141828653534, 11016.559337626406, 11240.662780116609, 11271.436428178722, 42866.57133428626, 43038.0, 43038.92402689958, 43648.56351436445, 43651.34522982221, 55635.0, 56303.0, 56385.71807141102, 56632.15051774309, 56935.43064569301, 12116.636500904988, 14016.719665606426, 14533.85466145325, 14582.927085364505, 14658.63353416173, 15479.0, 15706.842931570995, 15853.890227684413, 15942.521724348288, 16033.278502467183, 11808.881911180779, 13224.0, 14347.56957291241, 14778.77831832502, 14912.478063268167, 14156.929215353857, 14513.346899389342, 14544.85455145435, 14643.633909152357, 14662.560123196321, 13898.791518127035, 14218.933579981749, 14312.785308220593, 14342.067765595202, 14489.0, 10499.212559057949, 10546.314489558154, 10672.199585031309, 10830.566777329133, 10876.0, 11731.824022639497, 11786.60603924572, 11831.46758395857, 11932.582359617372, 11966.880331196577], "value": 8182.910696845275, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1509.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 117.0, 116.0, 115.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 145.0, 119.0, 151.0, 110.0, 111.0, 111.0, 94.0, 149.0, 144.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 220.0, 194.0, 211.0, 215.0, 185.0, 201.0, 264.0, 250.0, 277.0], "value": 221.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 65.0, 111.0, 78.0, 57.0, 57.0, 48.0, 77.0, 65.0], "value": 70.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 80.0, 71.0, 71.0, 69.0, 85.0, 84.0, 79.0, 90.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 89.0, 89.0, 90.0, 89.0, 93.0, 89.0, 92.0, 127.0], "value": 93.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 98.0, 98.0, 97.0, 96.0, 97.0, 98.0, 107.0, 153.0, 117.0], "value": 106.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 91.0, 92.0, 92.0, 90.0, 93.0, 93.0, 94.0, 91.0], "value": 92.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 48.0, 52.0, 49.0, 52.0, 52.0, 50.0, 50.0, 51.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 68.0, 58.0, 64.0, 63.0, 54.0, 57.0, 55.0, 57.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 86.0, 83.0, 83.0, 84.0, 79.0, 85.0, 87.0, 83.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 141.0, 140.0, 149.0, 157.0, 133.0, 144.0, 135.0, 206.0, 161.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 188.0, 229.0, 185.0, 181.0, 183.0, 188.0, 182.0, 188.0, 183.0], "value": 189.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 71.0, 67.0, 70.0, 67.0, 68.0, 69.0, 65.0, 70.0], "value": 69.0, "unit": "score"}]}]} 02:58:04 INFO - Return code: 0 02:58:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:58:04 INFO - # TBPL SUCCESS # 02:58:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:58:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:58:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:58:04 INFO - 2017-05-04 02:58:04.598 screenresolution[1660:10747] starting screenresolution argv=screenresolution get 02:58:04 INFO - 2017-05-04 02:58:04.601 screenresolution[1660:10747] Display 0: 1600x1200x32@60 02:58:04 INFO - 2017-05-04 02:58:04.608 screenresolution[1661:10750] starting screenresolution argv=screenresolution list 02:58:04 INFO - Available Modes on Display 0 02:58:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:58:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:58:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:58:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:58:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:58:04 INFO - Intel Iris: 02:58:04 INFO - Chipset Model: Intel Iris 02:58:04 INFO - Type: GPU 02:58:04 INFO - Bus: Built-In 02:58:04 INFO - VRAM (Dynamic, Max): 1536 MB 02:58:04 INFO - Vendor: Intel (0x8086) 02:58:04 INFO - Device ID: 0x0a2e 02:58:04 INFO - Revision ID: 0x0009 02:58:04 INFO - Displays: 02:58:04 INFO - Display: 02:58:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:58:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:58:04 INFO - Display Serial Number: 1600x1200 60 02:58:04 INFO - Main Display: Yes 02:58:04 INFO - Mirror: Off 02:58:04 INFO - Online: Yes 02:58:04 INFO - Rotation: Supported 02:58:04 INFO - Return code: 0 02:58:04 INFO - Running post-action listener: _resource_record_post_action 02:58:04 INFO - [mozharness: 2017-05-04 09:58:04.848839Z] Finished run-tests step (success) 02:58:04 INFO - Running post-run listener: _resource_record_post_run 02:58:04 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 39484416; Write bytes: 511679488; Read time: 251; Write time: 1596 02:58:04 INFO - TinderboxPrint: CPU usage
54.0% 02:58:04 INFO - TinderboxPrint: I/O read bytes / time
39,484,416 / 251 02:58:04 INFO - TinderboxPrint: I/O write bytes / time
511,679,488 / 1,596 02:58:04 INFO - TinderboxPrint: CPU idle
2,199.8 (46.0%) 02:58:04 INFO - TinderboxPrint: CPU system
960.3 (20.1%) 02:58:04 INFO - TinderboxPrint: CPU user
1,625.6 (34.0%) 02:58:04 INFO - TinderboxPrint: Swap in / out
247,701,504 / 0 02:58:04 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205720064; Write bytes: 207087616; Read time: 15993; Write time: 432 02:58:04 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 35519488; Write bytes: 298447872; Read time: 226; Write time: 1138 02:58:05 INFO - Running post-run listener: _upload_blobber_files 02:58:05 INFO - Blob upload gear active. 02:58:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:58:05 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 02:58:05 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'] 02:58:05 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 02:58:05 INFO - (blobuploader) - INFO - Open directory for files ... 02:58:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:58:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:58:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:58:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:58:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:58:06 INFO - (blobuploader) - INFO - Done attempting. 02:58:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:58:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:58:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:58:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:58:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:58:07 INFO - (blobuploader) - INFO - Done attempting. 02:58:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:58:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:58:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:58:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:58:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:58:08 INFO - (blobuploader) - INFO - Done attempting. 02:58:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:58:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:58:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:58:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:58:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:58:10 INFO - (blobuploader) - INFO - Done attempting. 02:58:10 INFO - (blobuploader) - INFO - Iteration through files over. 02:58:10 INFO - Return code: 0 02:58:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:58:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:58:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/942190a560c7ba7801bd4e888b77b234f1752d0533dc9afb363cd3a1ebde6847e1a42aa1548dea83efcc3515224b6d7fbc7d0192a06810aaf045386f7a265cd4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea75e3bc1485c69979202eca32ebf2d1672634c01a8d23b7ad1e9c36a194984e9de6273bead64a8c3fe182c33d72bd57fd273a47dccdc34117c585310320c678", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b13b06ed74c16e10b68d246825c7d5fa8659f2d23c6c600e26c6e8be73d0b2339beaba1da86a714a5c6bcbdc280a7fbf4502c9552afec868676eb3080c136b01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a5ef95c105a75f378663aac3818d7f01bbe50af9ef06a0b979fa69f73b6f3975281713ae7e669dacc0557bf59ab1e236eeccceba6a7574568211bcebb253eef"} 02:58:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:58:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:58:10 INFO - Contents: 02:58:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/942190a560c7ba7801bd4e888b77b234f1752d0533dc9afb363cd3a1ebde6847e1a42aa1548dea83efcc3515224b6d7fbc7d0192a06810aaf045386f7a265cd4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea75e3bc1485c69979202eca32ebf2d1672634c01a8d23b7ad1e9c36a194984e9de6273bead64a8c3fe182c33d72bd57fd273a47dccdc34117c585310320c678", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b13b06ed74c16e10b68d246825c7d5fa8659f2d23c6c600e26c6e8be73d0b2339beaba1da86a714a5c6bcbdc280a7fbf4502c9552afec868676eb3080c136b01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a5ef95c105a75f378663aac3818d7f01bbe50af9ef06a0b979fa69f73b6f3975281713ae7e669dacc0557bf59ab1e236eeccceba6a7574568211bcebb253eef"} 02:58:10 INFO - Running post-run listener: copy_logs_to_upload_dir 02:58:10 INFO - Copying logs to upload dir... 02:58:10 INFO - mkdir: /builds/slave/test/build/upload/logs 02:58:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.756481 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-05-04 02:58:11.048388) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-04 02:58:11.053545) ========= 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.dPznMgGyoE/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.NniU67c2Ka/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/942190a560c7ba7801bd4e888b77b234f1752d0533dc9afb363cd3a1ebde6847e1a42aa1548dea83efcc3515224b6d7fbc7d0192a06810aaf045386f7a265cd4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea75e3bc1485c69979202eca32ebf2d1672634c01a8d23b7ad1e9c36a194984e9de6273bead64a8c3fe182c33d72bd57fd273a47dccdc34117c585310320c678", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b13b06ed74c16e10b68d246825c7d5fa8659f2d23c6c600e26c6e8be73d0b2339beaba1da86a714a5c6bcbdc280a7fbf4502c9552afec868676eb3080c136b01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a5ef95c105a75f378663aac3818d7f01bbe50af9ef06a0b979fa69f73b6f3975281713ae7e669dacc0557bf59ab1e236eeccceba6a7574568211bcebb253eef"} build_url:https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011372 build_url: 'https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/942190a560c7ba7801bd4e888b77b234f1752d0533dc9afb363cd3a1ebde6847e1a42aa1548dea83efcc3515224b6d7fbc7d0192a06810aaf045386f7a265cd4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea75e3bc1485c69979202eca32ebf2d1672634c01a8d23b7ad1e9c36a194984e9de6273bead64a8c3fe182c33d72bd57fd273a47dccdc34117c585310320c678", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b13b06ed74c16e10b68d246825c7d5fa8659f2d23c6c600e26c6e8be73d0b2339beaba1da86a714a5c6bcbdc280a7fbf4502c9552afec868676eb3080c136b01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a5ef95c105a75f378663aac3818d7f01bbe50af9ef06a0b979fa69f73b6f3975281713ae7e669dacc0557bf59ab1e236eeccceba6a7574568211bcebb253eef"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-04 02:58:11.088824) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 02:58:11.089181) ========= 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.dPznMgGyoE/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.NniU67c2Ka/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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.005166 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 02:58:11.137978) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-04 02:58:11.138299) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-04 02:58:11.138622) ========= ========= Total master_lag: 0.18 =========