builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0135 starttime: 1493272037.73 results: success (0) buildid: 20170426090525 builduid: 5a5b915244ad42ba8f6a9b27ba7a58db revision: ba912b0f3ea88b1ee2a06d2e26cf77bfcf5a3f3e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:17.728281) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:17.728726) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:17.729003) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:17.755178) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:17.755533) ========= 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.4ciypBurcv/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.FzW4zNZXrE/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-26 22:47:17-- 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% 8.03M=0.001s 2017-04-26 22:47:17 (8.03 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144646 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:17.924864) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:17.925174) ========= 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.4ciypBurcv/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.FzW4zNZXrE/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.021865 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:17.974502) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-26 22:47:17.974849) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev ba912b0f3ea88b1ee2a06d2e26cf77bfcf5a3f3e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev ba912b0f3ea88b1ee2a06d2e26cf77bfcf5a3f3e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4ciypBurcv/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.FzW4zNZXrE/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-26 22:47:18,057 truncating revision to first 12 chars 2017-04-26 22:47:18,057 Setting DEBUG logging. 2017-04-26 22:47:18,057 attempt 1/10 2017-04-26 22:47:18,057 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/ba912b0f3ea8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-26 22:47:19,340 unpacking tar archive at: pine-ba912b0f3ea8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.528941 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-26 22:47:19.530456) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:19.531008) ========= script_repo_revision: ba912b0f3ea88b1ee2a06d2e26cf77bfcf5a3f3e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:19.531738) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:19.532212) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:19.554228) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-04-26 22:47:19.554753) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4ciypBurcv/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.FzW4zNZXrE/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 22:47:19 INFO - MultiFileLogger online at 20170426 22:47:19 in /builds/slave/test 22:47:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 22:47:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:47:19 INFO - {'append_to_log': False, 22:47:19 INFO - 'base_work_dir': '/builds/slave/test', 22:47:19 INFO - 'blob_upload_branch': 'Pine', 22:47:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:47:19 INFO - 'branch': 'Pine', 22:47:19 INFO - 'buildbot_json_path': 'buildprops.json', 22:47:19 INFO - 'config_files': ('talos/mac_config.py',), 22:47:19 INFO - 'default_actions': ('clobber', 22:47:19 INFO - 'read-buildbot-config', 22:47:19 INFO - 'download-and-extract', 22:47:19 INFO - 'populate-webroot', 22:47:19 INFO - 'create-virtualenv', 22:47:19 INFO - 'install', 22:47:19 INFO - 'run-tests'), 22:47:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:47:19 INFO - 'download_minidump_stackwalk': True, 22:47:19 INFO - 'download_symbols': 'ondemand', 22:47:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:47:19 INFO - 'tooltool.py': '/tools/tooltool.py', 22:47:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:47:19 INFO - '/tools/misc-python/virtualenv.py')}, 22:47:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:47:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:47:19 INFO - 'gecko_profile': False, 22:47:19 INFO - 'gecko_profile_interval': 0, 22:47:19 INFO - 'installer_path': 'installer.exe', 22:47:19 INFO - 'log_level': 'info', 22:47:19 INFO - 'log_name': 'talos', 22:47:19 INFO - 'log_to_console': True, 22:47:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:47:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:47:19 INFO - 'opt_config_files': (), 22:47:19 INFO - 'pip_index': False, 22:47:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:47:19 INFO - 'cmd': ('bash', 22:47:19 INFO - '-c', 22:47:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:47:19 INFO - 'enabled': True, 22:47:19 INFO - 'halt_on_failure': False, 22:47:19 INFO - 'name': 'check_screen_resolution'},), 22:47:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:47:19 INFO - 'cmd': ('bash', 22:47:19 INFO - '-c', 22:47:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:47:19 INFO - 'enabled': True, 22:47:19 INFO - 'halt_on_failure': False, 22:47:19 INFO - 'name': 'check_screen_resolution'},), 22:47:19 INFO - 'run_cmd_checks_enabled': True, 22:47:19 INFO - 'suite': 'dromaeojs-e10s', 22:47:19 INFO - 'system_bits': '32', 22:47:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:47:19 INFO - 'title': 't-yosemite-r7-0135', 22:47:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:47:19 INFO - 'use_talos_json': True, 22:47:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:47:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:47:19 INFO - 'work_dir': 'build'} 22:47:19 INFO - [mozharness: 2017-04-27 05:47:19.768893Z] Running clobber step. 22:47:19 INFO - Running pre-action listener: _resource_record_pre_action 22:47:19 INFO - Running main action method: clobber 22:47:19 INFO - rmtree: /builds/slave/test/build 22:47:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:47:21 INFO - Running post-action listener: _resource_record_post_action 22:47:21 INFO - [mozharness: 2017-04-27 05:47:21.960179Z] Finished clobber step (success) 22:47:21 INFO - [mozharness: 2017-04-27 05:47:21.960433Z] Running read-buildbot-config step. 22:47:21 INFO - Running pre-action listener: _resource_record_pre_action 22:47:21 INFO - Running main action method: read_buildbot_config 22:47:21 INFO - Using buildbot properties: 22:47:21 INFO - { 22:47:21 INFO - "project": "", 22:47:21 INFO - "product": "firefox", 22:47:21 INFO - "script_repo_revision": "default", 22:47:21 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 22:47:21 INFO - "repository": "", 22:47:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 22:47:21 INFO - "buildid": "20170426090525", 22:47:21 INFO - "pgo_build": "False", 22:47:21 INFO - "basedir": "/builds/slave/test", 22:47:21 INFO - "buildnumber": 4, 22:47:21 INFO - "slavename": "t-yosemite-r7-0135", 22:47:21 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:47:21 INFO - "platform": "yosemite_r7", 22:47:21 INFO - "scheduler": "tests-pine-macosx64-talos", 22:47:21 INFO - "branch": "pine", 22:47:21 INFO - "repo_path": "projects/pine", 22:47:21 INFO - "revision": "ba912b0f3ea88b1ee2a06d2e26cf77bfcf5a3f3e", 22:47:21 INFO - "stage_platform": "macosx64", 22:47:21 INFO - "builduid": "5a5b915244ad42ba8f6a9b27ba7a58db", 22:47:21 INFO - "slavebuilddir": "test" 22:47:21 INFO - } 22:47:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 22:47:21 INFO - Running post-action listener: _resource_record_post_action 22:47:21 INFO - [mozharness: 2017-04-27 05:47:21.966212Z] Finished read-buildbot-config step (success) 22:47:21 INFO - [mozharness: 2017-04-27 05:47:21.966408Z] Running download-and-extract step. 22:47:21 INFO - Running pre-action listener: _resource_record_pre_action 22:47:21 INFO - Running main action method: download_and_extract 22:47:21 INFO - mkdir: /builds/slave/test/build/tests 22:47:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:21 INFO - trying https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 22:47:21 INFO - Downloading https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:47:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 22:47:23 INFO - Downloaded 1325 bytes. 22:47:23 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:47:23 INFO - Using the following test package requirements: 22:47:23 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:23 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 22:47:23 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:47:23 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:23 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 22:47:23 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:23 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 22:47:23 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:23 INFO - u'jsshell-mac.zip'], 22:47:23 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:23 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 22:47:23 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:47:23 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:23 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 22:47:23 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:23 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 22:47:23 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:23 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 22:47:23 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:23 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 22:47:23 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:47:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:47:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:47:23 INFO - Fetch https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:47:24 INFO - Content-Length response header: 52529807 22:47:24 INFO - Bytes received: 52529807 22:47:41 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:47:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:47:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:47:41 INFO - Fetch https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:47:43 INFO - Content-Length response header: 52529807 22:47:43 INFO - Bytes received: 52529807 22:47:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 22:47:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:47:58 INFO - Fetch https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 22:47:59 INFO - Content-Length response header: 14408809 22:47:59 INFO - Bytes received: 14408809 22:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:00 INFO - trying https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:48:00 INFO - Downloading https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 22:48:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 22:48:02 INFO - Downloaded 71195220 bytes. 22:48:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:48:02 INFO - mkdir: /builds/slave/test/properties 22:48:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:48:02 INFO - Writing to file /builds/slave/test/properties/build_url 22:48:02 INFO - Contents: 22:48:02 INFO - build_url:https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:48:02 INFO - Running post-action listener: _resource_record_post_action 22:48:02 INFO - Running post-action listener: set_extra_try_arguments 22:48:02 INFO - [mozharness: 2017-04-27 05:48:02.742254Z] Finished download-and-extract step (success) 22:48:02 INFO - [mozharness: 2017-04-27 05:48:02.742422Z] Running populate-webroot step. 22:48:02 INFO - Running pre-action listener: _resource_record_pre_action 22:48:02 INFO - Running main action method: populate_webroot 22:48:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:48:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:48:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:48:02 INFO - '--tppagecycles', 22:48:02 INFO - '7'], 22:48:02 INFO - 'tests': ['tsvgm']}}, 22:48:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:48:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:48:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:48:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:48:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:48:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:48:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:48:02 INFO - 'talos_options': ['--disable-e10s'], 22:48:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:48:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:48:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:48:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:48:02 INFO - 'talos_options': ['--disable-e10s'], 22:48:02 INFO - 'tests': ['damp', 'tps']}, 22:48:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:48:02 INFO - 'tests': ['damp', 'tps']}, 22:48:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:48:02 INFO - 'tests': ['dromaeo_dom']}, 22:48:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:48:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:48:02 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 22:48:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:48:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:48:02 INFO - 'tests': ['a11yr', 22:48:02 INFO - 'ts_paint', 22:48:02 INFO - 'tpaint', 22:48:02 INFO - 'sessionrestore', 22:48:02 INFO - 'sessionrestore_no_auto_restore', 22:48:02 INFO - 'tabpaint']}, 22:48:02 INFO - 'other-e10s': {'tests': ['a11yr', 22:48:02 INFO - 'ts_paint', 22:48:02 INFO - 'tpaint', 22:48:02 INFO - 'sessionrestore', 22:48:02 INFO - 'sessionrestore_no_auto_restore', 22:48:02 INFO - 'tabpaint']}, 22:48:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:48:02 INFO - 'ts_paint', 22:48:02 INFO - 'tpaint', 22:48:02 INFO - 'sessionrestore', 22:48:02 INFO - 'sessionrestore_no_auto_restore', 22:48:02 INFO - 'tabpaint']}, 22:48:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:48:02 INFO - 'ts_paint', 22:48:02 INFO - 'tpaint', 22:48:02 INFO - 'sessionrestore', 22:48:02 INFO - 'sessionrestore_no_auto_restore', 22:48:02 INFO - 'tabpaint']}, 22:48:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:48:02 INFO - 'tests': ['a11yr', 22:48:02 INFO - 'ts_paint', 22:48:02 INFO - 'tpaint', 22:48:02 INFO - 'sessionrestore', 22:48:02 INFO - 'sessionrestore_no_auto_restore', 22:48:02 INFO - 'tabpaint']}, 22:48:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:48:02 INFO - 'tests': ['a11yr', 22:48:02 INFO - 'ts_paint', 22:48:02 INFO - 'tpaint', 22:48:02 INFO - 'sessionrestore', 22:48:02 INFO - 'sessionrestore_no_auto_restore', 22:48:02 INFO - 'tabpaint']}, 22:48:02 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 22:48:02 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 22:48:02 INFO - 'bloom_basic_ref']}, 22:48:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:48:02 INFO - 'tests': ['tsvgx', 22:48:02 INFO - 'tsvgr_opacity', 22:48:02 INFO - 'tart', 22:48:02 INFO - 'tscrollx', 22:48:02 INFO - 'cart', 22:48:02 INFO - 'tsvg_static']}, 22:48:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:48:02 INFO - 'tsvgr_opacity', 22:48:02 INFO - 'tart', 22:48:02 INFO - 'tscrollx', 22:48:02 INFO - 'cart', 22:48:02 INFO - 'tsvg_static']}, 22:48:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:48:02 INFO - 'talos_options': ['--disable-e10s'], 22:48:02 INFO - 'tests': ['tp5o']}, 22:48:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:48:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:48:02 INFO - 'talos_options': ['--disable-e10s', 22:48:02 INFO - '--xperf_path', 22:48:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:48:02 INFO - 'tests': ['tp5n']}, 22:48:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:48:02 INFO - 'talos_options': ['--xperf_path', 22:48:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:48:02 INFO - 'tests': ['tp5n']}}, 22:48:02 INFO - 'talos.zip': {'path': '', 22:48:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:48:02 INFO - Running post-action listener: _resource_record_post_action 22:48:02 INFO - [mozharness: 2017-04-27 05:48:02.761606Z] Finished populate-webroot step (success) 22:48:02 INFO - [mozharness: 2017-04-27 05:48:02.761725Z] Running create-virtualenv step. 22:48:02 INFO - Running pre-action listener: _resource_record_pre_action 22:48:02 INFO - Running main action method: create_virtualenv 22:48:02 INFO - Creating virtualenv /builds/slave/test/build/venv 22:48:02 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:48:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:48:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:48:02 INFO - Using real prefix '/tools/python27' 22:48:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:48:03 INFO - Installing distribute.............................................................................................................................................................................................done. 22:48:06 INFO - Installing pip.................done. 22:48:06 INFO - Return code: 0 22:48:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:48:06 INFO - Reading from file tmpfile_stdout 22:48:06 INFO - Output received: 22:48:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:48:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:06 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 0x101222b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101225768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f347a0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101000d30>, '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 0x100f26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff5be8>, '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.4ciypBurcv/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.FzW4zNZXrE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ciypBurcv/Render', 22:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:06 INFO - 'HOME': '/Users/cltbld', 22:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:06 INFO - 'LOGNAME': 'cltbld', 22:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:06 INFO - 'MOZ_NO_REMOTE': '1', 22:48:06 INFO - 'NO_EM_RESTART': '1', 22:48:06 INFO - 'PAGER': '/bin/cat', 22:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:06 INFO - 'PWD': '/builds/slave/test', 22:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:06 INFO - 'SHELL': '/bin/bash', 22:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FzW4zNZXrE/Listeners', 22:48:06 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 22:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:06 INFO - 'USER': 'cltbld', 22:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:06 INFO - 'XPC_FLAGS': '0x0', 22:48:06 INFO - 'XPC_SERVICE_NAME': '0', 22:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:48:06 INFO - Cleaning up... 22:48:06 INFO - Return code: 0 22:48:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:06 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 0x101222b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101225768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f347a0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101000d30>, '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 0x100f26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff5be8>, '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.4ciypBurcv/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.FzW4zNZXrE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ciypBurcv/Render', 22:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:06 INFO - 'HOME': '/Users/cltbld', 22:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:06 INFO - 'LOGNAME': 'cltbld', 22:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:06 INFO - 'MOZ_NO_REMOTE': '1', 22:48:06 INFO - 'NO_EM_RESTART': '1', 22:48:06 INFO - 'PAGER': '/bin/cat', 22:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:06 INFO - 'PWD': '/builds/slave/test', 22:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:06 INFO - 'SHELL': '/bin/bash', 22:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FzW4zNZXrE/Listeners', 22:48:06 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 22:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:06 INFO - 'USER': 'cltbld', 22:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:06 INFO - 'XPC_FLAGS': '0x0', 22:48:06 INFO - 'XPC_SERVICE_NAME': '0', 22:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:07 INFO - Downloading/unpacking psutil>=3.1.1 22:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:48:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:48:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:48:10 INFO - Installing collected packages: psutil 22:48:10 INFO - Running setup.py install for psutil 22:48:10 INFO - building 'psutil._psutil_osx' extension 22:48:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:48:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:48:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:48:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 22:48:11 INFO - building 'psutil._psutil_posix' extension 22:48:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 22:48:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:48:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:48:11 INFO - ^ 22:48:11 INFO - 1 warning generated. 22:48:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 22:48:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:48:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:48:11 INFO - Successfully installed psutil 22:48:11 INFO - Cleaning up... 22:48:11 INFO - Return code: 0 22:48:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:11 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 0x101222b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101225768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f347a0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101000d30>, '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 0x100f26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff5be8>, '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.4ciypBurcv/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.FzW4zNZXrE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:48:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:48:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ciypBurcv/Render', 22:48:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:11 INFO - 'HOME': '/Users/cltbld', 22:48:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:11 INFO - 'LOGNAME': 'cltbld', 22:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:11 INFO - 'MOZ_NO_REMOTE': '1', 22:48:11 INFO - 'NO_EM_RESTART': '1', 22:48:11 INFO - 'PAGER': '/bin/cat', 22:48:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:11 INFO - 'PWD': '/builds/slave/test', 22:48:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:11 INFO - 'SHELL': '/bin/bash', 22:48:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FzW4zNZXrE/Listeners', 22:48:11 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 22:48:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:11 INFO - 'USER': 'cltbld', 22:48:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:11 INFO - 'XPC_FLAGS': '0x0', 22:48:11 INFO - 'XPC_SERVICE_NAME': '0', 22:48:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:48:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:48:15 INFO - Installing collected packages: mozsystemmonitor 22:48:15 INFO - Running setup.py install for mozsystemmonitor 22:48:15 INFO - Successfully installed mozsystemmonitor 22:48:15 INFO - Cleaning up... 22:48:15 INFO - Return code: 0 22:48:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:16 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 0x101222b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101225768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f347a0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101000d30>, '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 0x100f26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff5be8>, '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.4ciypBurcv/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.FzW4zNZXrE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:48:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:48:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ciypBurcv/Render', 22:48:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:16 INFO - 'HOME': '/Users/cltbld', 22:48:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:16 INFO - 'LOGNAME': 'cltbld', 22:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:16 INFO - 'MOZ_NO_REMOTE': '1', 22:48:16 INFO - 'NO_EM_RESTART': '1', 22:48:16 INFO - 'PAGER': '/bin/cat', 22:48:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:16 INFO - 'PWD': '/builds/slave/test', 22:48:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:16 INFO - 'SHELL': '/bin/bash', 22:48:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FzW4zNZXrE/Listeners', 22:48:16 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 22:48:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:16 INFO - 'USER': 'cltbld', 22:48:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:16 INFO - 'XPC_FLAGS': '0x0', 22:48:16 INFO - 'XPC_SERVICE_NAME': '0', 22:48:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:17 INFO - Downloading/unpacking jsonschema==2.5.1 22:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:48:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:19 INFO - Downloading functools32-3.2.3-2.tar.gz 22:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:48:20 INFO - warning: no files found matching '*.txt' 22:48:20 INFO - no previously-included directories found matching 'build' 22:48:20 INFO - no previously-included directories found matching 'dist' 22:48:20 INFO - no previously-included directories found matching '.git*' 22:48:20 INFO - Installing collected packages: jsonschema, functools32 22:48:20 INFO - Running setup.py install for functools32 22:48:20 INFO - warning: no files found matching '*.txt' 22:48:20 INFO - no previously-included directories found matching 'build' 22:48:20 INFO - no previously-included directories found matching 'dist' 22:48:20 INFO - no previously-included directories found matching '.git*' 22:48:20 INFO - Successfully installed jsonschema functools32 22:48:20 INFO - Cleaning up... 22:48:20 INFO - Return code: 0 22:48:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:20 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 0x101222b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101225768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f347a0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101000d30>, '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 0x100f26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff5be8>, '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.4ciypBurcv/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.FzW4zNZXrE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:48:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ciypBurcv/Render', 22:48:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:20 INFO - 'HOME': '/Users/cltbld', 22:48:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:20 INFO - 'LOGNAME': 'cltbld', 22:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:20 INFO - 'MOZ_NO_REMOTE': '1', 22:48:20 INFO - 'NO_EM_RESTART': '1', 22:48:20 INFO - 'PAGER': '/bin/cat', 22:48:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:20 INFO - 'PWD': '/builds/slave/test', 22:48:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:20 INFO - 'SHELL': '/bin/bash', 22:48:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FzW4zNZXrE/Listeners', 22:48:20 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 22:48:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:20 INFO - 'USER': 'cltbld', 22:48:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:20 INFO - 'XPC_FLAGS': '0x0', 22:48:20 INFO - 'XPC_SERVICE_NAME': '0', 22:48:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:48:20 INFO - Cleaning up... 22:48:20 INFO - Return code: 0 22:48:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:20 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 0x101222b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101225768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f347a0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101000d30>, '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 0x100f26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff5be8>, '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.4ciypBurcv/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.FzW4zNZXrE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:48:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ciypBurcv/Render', 22:48:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:20 INFO - 'HOME': '/Users/cltbld', 22:48:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:20 INFO - 'LOGNAME': 'cltbld', 22:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:20 INFO - 'MOZ_NO_REMOTE': '1', 22:48:20 INFO - 'NO_EM_RESTART': '1', 22:48:20 INFO - 'PAGER': '/bin/cat', 22:48:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:20 INFO - 'PWD': '/builds/slave/test', 22:48:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:20 INFO - 'SHELL': '/bin/bash', 22:48:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FzW4zNZXrE/Listeners', 22:48:20 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 22:48:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:20 INFO - 'USER': 'cltbld', 22:48:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:20 INFO - 'XPC_FLAGS': '0x0', 22:48:20 INFO - 'XPC_SERVICE_NAME': '0', 22:48:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:20 INFO - Downloading/unpacking blobuploader==1.2.4 22:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:23 INFO - Downloading blobuploader-1.2.4.tar.gz 22:48:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:48:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:48:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:24 INFO - Downloading docopt-0.6.1.tar.gz 22:48:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:48:24 INFO - Installing collected packages: blobuploader, requests, docopt 22:48:24 INFO - Running setup.py install for blobuploader 22:48:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:48:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:48:25 INFO - Running setup.py install for requests 22:48:25 INFO - Running setup.py install for docopt 22:48:25 INFO - Successfully installed blobuploader requests docopt 22:48:25 INFO - Cleaning up... 22:48:25 INFO - Return code: 0 22:48:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:25 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 0x101222b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101225768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f347a0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101000d30>, '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 0x100f26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff5be8>, '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.4ciypBurcv/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.FzW4zNZXrE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:48:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ciypBurcv/Render', 22:48:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:25 INFO - 'HOME': '/Users/cltbld', 22:48:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:25 INFO - 'LOGNAME': 'cltbld', 22:48:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:25 INFO - 'MOZ_NO_REMOTE': '1', 22:48:25 INFO - 'NO_EM_RESTART': '1', 22:48:25 INFO - 'PAGER': '/bin/cat', 22:48:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:25 INFO - 'PWD': '/builds/slave/test', 22:48:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:25 INFO - 'SHELL': '/bin/bash', 22:48:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FzW4zNZXrE/Listeners', 22:48:25 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 22:48:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:25 INFO - 'USER': 'cltbld', 22:48:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:25 INFO - 'XPC_FLAGS': '0x0', 22:48:25 INFO - 'XPC_SERVICE_NAME': '0', 22:48:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:48:26 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ZjU8W1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:48:26 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-RlNs32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:48:26 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-EbZPfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:48:26 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-b6jtaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:48:26 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ZEq97_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:48:26 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-A_wplv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:48:26 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-0ToA_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:48:26 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Rt3tQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:48:26 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-eCrir_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:48:26 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-IY0Vxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:48:27 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-irhF7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:48:27 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-G7T4Ib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:48:27 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-i9ooYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:48:27 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-fbYSrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:27 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Gi4Osv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:48:27 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-BV7r0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:48:27 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ee1mfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:48:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:48:27 INFO - Running setup.py install for manifestparser 22:48:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:48:27 INFO - Running setup.py install for mozcrash 22:48:28 INFO - Running setup.py install for mozdebug 22:48:28 INFO - Running setup.py install for mozdevice 22:48:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:48:28 INFO - Running setup.py install for mozfile 22:48:28 INFO - Running setup.py install for mozhttpd 22:48:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:48:28 INFO - Running setup.py install for mozinfo 22:48:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:48:28 INFO - Running setup.py install for mozInstall 22:48:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:48:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:48:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:48:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:48:28 INFO - Running setup.py install for mozleak 22:48:29 INFO - Running setup.py install for mozlog 22:48:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:48:29 INFO - Running setup.py install for moznetwork 22:48:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:48:29 INFO - Running setup.py install for mozprocess 22:48:29 INFO - Running setup.py install for mozprofile 22:48:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:48:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:48:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:48:29 INFO - Running setup.py install for mozrunner 22:48:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:48:29 INFO - Running setup.py install for mozscreenshot 22:48:30 INFO - Running setup.py install for moztest 22:48:30 INFO - Running setup.py install for mozversion 22:48:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:48:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:48:30 INFO - Cleaning up... 22:48:30 INFO - Return code: 0 22:48:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:30 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 0x101222b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101225768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f347a0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101000d30>, '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 0x100f26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff5be8>, '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.4ciypBurcv/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.FzW4zNZXrE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:48:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:48:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ciypBurcv/Render', 22:48:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:30 INFO - 'HOME': '/Users/cltbld', 22:48:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:30 INFO - 'LOGNAME': 'cltbld', 22:48:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:30 INFO - 'MOZ_NO_REMOTE': '1', 22:48:30 INFO - 'NO_EM_RESTART': '1', 22:48:30 INFO - 'PAGER': '/bin/cat', 22:48:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:30 INFO - 'PWD': '/builds/slave/test', 22:48:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:30 INFO - 'SHELL': '/bin/bash', 22:48:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FzW4zNZXrE/Listeners', 22:48:30 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 22:48:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:30 INFO - 'USER': 'cltbld', 22:48:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:30 INFO - 'XPC_FLAGS': '0x0', 22:48:30 INFO - 'XPC_SERVICE_NAME': '0', 22:48:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:48:30 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-MOHvcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:48:30 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-sR_QRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:48:30 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-VZr1gY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:48:31 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-2ybewb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:48:31 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-KZORx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:48:31 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-XhRqOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:48:31 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Z26SqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:48:31 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-fVY3s1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:48:31 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-MvlmNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:48:31 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-uX5KYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:48:31 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-AepwVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:48:31 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Gn4Mck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:48:31 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-NMYWOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:48:32 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-1PKdn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:32 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-TEblRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:48:32 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-fDcsZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:48:32 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-XjZx_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:48:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:35 INFO - Downloading blessings-1.6.tar.gz 22:48:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:48:35 INFO - Installing collected packages: blessings 22:48:35 INFO - Running setup.py install for blessings 22:48:35 INFO - Successfully installed blessings 22:48:35 INFO - Cleaning up... 22:48:35 INFO - Return code: 0 22:48:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:48:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:48:35 INFO - Reading from file tmpfile_stdout 22:48:35 INFO - Current package versions: 22:48:35 INFO - blessings == 1.6 22:48:35 INFO - blobuploader == 1.2.4 22:48:35 INFO - docopt == 0.6.1 22:48:35 INFO - functools32 == 3.2.3-2 22:48:35 INFO - jsonschema == 2.5.1 22:48:35 INFO - manifestparser == 1.1 22:48:35 INFO - mozInstall == 1.12 22:48:35 INFO - mozcrash == 1.0 22:48:35 INFO - mozdebug == 0.1 22:48:35 INFO - mozdevice == 0.50 22:48:35 INFO - mozfile == 1.2 22:48:35 INFO - mozhttpd == 0.7 22:48:35 INFO - mozinfo == 0.9 22:48:35 INFO - mozleak == 0.1 22:48:35 INFO - mozlog == 3.4 22:48:35 INFO - moznetwork == 0.27 22:48:35 INFO - mozprocess == 0.25 22:48:35 INFO - mozprofile == 0.28 22:48:35 INFO - mozrunner == 6.13 22:48:35 INFO - mozscreenshot == 0.1 22:48:35 INFO - mozsystemmonitor == 0.3 22:48:35 INFO - moztest == 0.8 22:48:35 INFO - mozversion == 1.4 22:48:35 INFO - psutil == 3.1.1 22:48:35 INFO - requests == 1.2.3 22:48:35 INFO - wsgiref == 0.1.2 22:48:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:35 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 0x101222b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101225768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f347a0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101000d30>, '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 0x100f26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff5be8>, '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.4ciypBurcv/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.FzW4zNZXrE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ciypBurcv/Render', 22:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:35 INFO - 'HOME': '/Users/cltbld', 22:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:35 INFO - 'LOGNAME': 'cltbld', 22:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:35 INFO - 'MOZ_NO_REMOTE': '1', 22:48:35 INFO - 'NO_EM_RESTART': '1', 22:48:35 INFO - 'PAGER': '/bin/cat', 22:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:35 INFO - 'PWD': '/builds/slave/test', 22:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:35 INFO - 'SHELL': '/bin/bash', 22:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FzW4zNZXrE/Listeners', 22:48:35 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 22:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:35 INFO - 'USER': 'cltbld', 22:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:35 INFO - 'XPC_FLAGS': '0x0', 22:48:35 INFO - 'XPC_SERVICE_NAME': '0', 22:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:48:35 INFO - Cleaning up... 22:48:36 INFO - Return code: 0 22:48:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:36 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 0x101222b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101225768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f347a0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101000d30>, '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 0x100f26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff5be8>, '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.4ciypBurcv/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.FzW4zNZXrE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:48:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ciypBurcv/Render', 22:48:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:36 INFO - 'HOME': '/Users/cltbld', 22:48:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:36 INFO - 'LOGNAME': 'cltbld', 22:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:36 INFO - 'MOZ_NO_REMOTE': '1', 22:48:36 INFO - 'NO_EM_RESTART': '1', 22:48:36 INFO - 'PAGER': '/bin/cat', 22:48:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:36 INFO - 'PWD': '/builds/slave/test', 22:48:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:36 INFO - 'SHELL': '/bin/bash', 22:48:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FzW4zNZXrE/Listeners', 22:48:36 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 22:48:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:36 INFO - 'USER': 'cltbld', 22:48:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:36 INFO - 'XPC_FLAGS': '0x0', 22:48:36 INFO - 'XPC_SERVICE_NAME': '0', 22:48:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:48:36 INFO - Cleaning up... 22:48:36 INFO - Return code: 0 22:48:36 INFO - Running post-action listener: _resource_record_post_action 22:48:36 INFO - Running post-action listener: _start_resource_monitoring 22:48:36 INFO - Starting resource monitoring. 22:48:36 INFO - [mozharness: 2017-04-27 05:48:36.309504Z] Finished create-virtualenv step (success) 22:48:36 INFO - [mozharness: 2017-04-27 05:48:36.310348Z] Running install step. 22:48:36 INFO - Running pre-action listener: _resource_record_pre_action 22:48:36 INFO - Running main action method: install 22:48:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:48:36 INFO - Reading from file tmpfile_stdout 22:48:36 INFO - Detecting whether we're running mozinstall >=1.0... 22:48:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:48:36 INFO - Reading from file tmpfile_stdout 22:48:36 INFO - Output received: 22:48:36 INFO - Usage: mozinstall [options] installer 22:48:36 INFO - Options: 22:48:36 INFO - -h, --help show this help message and exit 22:48:36 INFO - -d DEST, --destination=DEST 22:48:36 INFO - Directory to install application into. [default: 22:48:36 INFO - "/builds/slave/test"] 22:48:36 INFO - --app=APP Application being installed. [default: firefox] 22:48:36 INFO - mkdir: /builds/slave/test/build/application 22:48:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:48:56 INFO - Reading from file tmpfile_stdout 22:48:56 INFO - Output received: 22:48:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:48:56 INFO - Running post-action listener: _resource_record_post_action 22:48:56 INFO - [mozharness: 2017-04-27 05:48:56.283840Z] Finished install step (success) 22:48:56 INFO - [mozharness: 2017-04-27 05:48:56.283996Z] Running run-tests step. 22:48:56 INFO - Running pre-action listener: _resource_record_pre_action 22:48:56 INFO - Running main action method: run_tests 22:48:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:48:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:48:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:48:56 INFO - 2017-04-26 22:48:56.298 screenresolution[1984:15699] starting screenresolution argv=screenresolution get 22:48:56 INFO - 2017-04-26 22:48:56.301 screenresolution[1984:15699] Display 0: 1600x1200x32@60 22:48:56 INFO - 2017-04-26 22:48:56.307 screenresolution[1985:15701] starting screenresolution argv=screenresolution list 22:48:56 INFO - Available Modes on Display 0 22:48:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:48:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:48:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:48:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:48:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:48:56 INFO - Intel Iris: 22:48:56 INFO - Chipset Model: Intel Iris 22:48:56 INFO - Type: GPU 22:48:56 INFO - Bus: Built-In 22:48:56 INFO - VRAM (Dynamic, Max): 1536 MB 22:48:56 INFO - Vendor: Intel (0x8086) 22:48:56 INFO - Device ID: 0x0a2e 22:48:56 INFO - Revision ID: 0x0009 22:48:56 INFO - Displays: 22:48:56 INFO - Display: 22:48:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:48:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:48:56 INFO - Display Serial Number: 1600x1200 60 22:48:56 INFO - Main Display: Yes 22:48:56 INFO - Mirror: Off 22:48:56 INFO - Online: Yes 22:48:56 INFO - Rotation: Supported 22:48:56 INFO - Return code: 0 22:48:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:48:56 INFO - Python 2.7.3 22:48:56 INFO - Return code: 0 22:48:56 INFO - grabbing minidump binary from tooltool 22:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:56 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 0x101000d30>, '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 0x100f26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:48:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:48:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:48:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:48:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:48:56 INFO - Return code: 0 22:48:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:48:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:48:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:48:56 INFO - ENV: RUST_BACKTRACE is now 1 22:48:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:48:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:48:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0135 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:48:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4ciypBurcv/Render', 22:48:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:56 INFO - 'HOME': '/Users/cltbld', 22:48:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:56 INFO - 'LOGNAME': 'cltbld', 22:48:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:48:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:56 INFO - 'MOZ_NO_REMOTE': '1', 22:48:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:48:56 INFO - 'NO_EM_RESTART': '1', 22:48:56 INFO - 'PAGER': '/bin/cat', 22:48:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:56 INFO - 'PWD': '/builds/slave/test', 22:48:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:48:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:56 INFO - 'RUST_BACKTRACE': '1', 22:48:56 INFO - 'SHELL': '/bin/bash', 22:48:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FzW4zNZXrE/Listeners', 22:48:56 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 22:48:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:56 INFO - 'USER': 'cltbld', 22:48:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:56 INFO - 'XPC_FLAGS': '0x0', 22:48:56 INFO - 'XPC_SERVICE_NAME': '0', 22:48:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:48:56 INFO - mozversion application_buildid: 20170426090525 22:48:56 INFO - mozversion application_changeset: ba912b0f3ea88b1ee2a06d2e26cf77bfcf5a3f3e 22:48:56 INFO - mozversion application_display_name: Nightly 22:48:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:48:56 INFO - mozversion application_name: Firefox 22:48:56 INFO - mozversion application_remotingname: firefox 22:48:56 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 22:48:56 INFO - mozversion application_vendor: Mozilla 22:48:56 INFO - mozversion application_version: 55.0a1 22:48:56 INFO - mozversion platform_buildid: 20170426090525 22:48:56 INFO - mozversion platform_changeset: ba912b0f3ea88b1ee2a06d2e26cf77bfcf5a3f3e 22:48:56 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 22:48:56 INFO - mozversion platform_version: 55.0a1 22:48:56 INFO - using testdate: 1493272136 22:48:56 INFO - actual date: 1493272136 22:48:56 INFO - starting webserver on 'localhost:49531' 22:48:56 INFO - SUITE-START | Running 2 tests 22:48:56 INFO - TEST-START | dromaeo_css 22:48:56 INFO - operating with platform_type : mac_ 22:48:56 INFO - Initialising browser for dromaeo_css test... 22:48:56 INFO - TEST-INFO | started process 1995 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpUhj48g/profile http://localhost:49531/getInfo.html) 22:48:59 INFO - PID 1995 | 2017-04-26 22:48:59.647 plugin-container[1997:15875] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 22:48:59 INFO - PID 1995 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:48:59 INFO - PID 1995 | 2017-04-26 22:48:59.651 plugin-container[1997:15875] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 22:48:59 INFO - PID 1995 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:48:59 INFO - PID 1995 | 2017-04-26 22:48:59.651 plugin-container[1997:15875] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:48:59 INFO - PID 1995 | __metrics Screen width/height:1600/1200 22:48:59 INFO - PID 1995 | colorDepth:24 22:48:59 INFO - PID 1995 | Browser inner width/height: 1024/689 22:48:59 INFO - PID 1995 | __metrics 22:48:59 ERROR - PID 1995 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 22:49:00 INFO - TEST-INFO | 1995: exit 0 22:49:00 INFO - Browser initialized. 22:49:00 INFO - Running cycle 1/1 for dromaeo_css test... 22:49:00 INFO - TEST-INFO | started process 1998 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpUhj48g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:49:02 INFO - PID 1998 | 2017-04-26 22:49:02.957 plugin-container[1999:16004] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923b, name = 'com.apple.tsm.portname' 22:49:02 INFO - PID 1998 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:49:02 INFO - PID 1998 | 2017-04-26 22:49:02.958 plugin-container[1999:16004] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 22:49:02 INFO - PID 1998 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:49:02 INFO - PID 1998 | 2017-04-26 22:49:02.958 plugin-container[1999:16004] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:49:13 INFO - PID 1998 | RSS: Main: 238534656 22:49:13 INFO - PID 1998 | 22:52:16 INFO - PID 1998 | Cycle 1(1): loaded http://localhost:49531/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49531/tests/dromaeo/cssquery-ext.html) 22:52:18 INFO - PID 1998 | RSS: Main: 252514304 22:52:18 INFO - PID 1998 | 22:54:01 INFO - PID 1998 | ************************* 22:54:01 INFO - PID 1998 | A coding exception was thrown and uncaught in a Task. 22:54:01 INFO - PID 1998 | 22:54:01 ERROR - PID 1998 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:54:01 INFO - PID 1998 | Full stack: 22:54:01 INFO - PID 1998 | ************************* 22:54:01 INFO - PID 1998 | ************************* 22:54:01 INFO - PID 1998 | A coding exception was thrown and uncaught in a Task. 22:54:01 INFO - PID 1998 | 22:54:01 ERROR - PID 1998 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:54:01 INFO - PID 1998 | Full stack: 22:54:01 INFO - PID 1998 | ************************* 22:54:01 INFO - PID 1998 | ************************* 22:54:01 INFO - PID 1998 | A coding exception was thrown and uncaught in a Task. 22:54:01 INFO - PID 1998 | 22:54:01 ERROR - PID 1998 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:54:01 INFO - PID 1998 | Full stack: 22:54:01 INFO - PID 1998 | ************************* 22:54:01 INFO - PID 1998 | ************************* 22:54:01 INFO - PID 1998 | A coding exception was thrown and uncaught in a Task. 22:54:01 INFO - PID 1998 | 22:54:01 ERROR - PID 1998 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:54:01 INFO - PID 1998 | Full stack: 22:54:01 INFO - PID 1998 | ************************* 22:55:20 INFO - PID 1998 | Cycle 1(1): loaded http://localhost:49531/tests/dromaeo/cssquery-ext.html (next: http://localhost:49531/tests/dromaeo/cssquery-jquery.html) 22:55:20 INFO - PID 1998 | RSS: Main: 258715648 22:55:20 INFO - PID 1998 | 22:58:22 INFO - PID 1998 | Cycle 1(1): loaded http://localhost:49531/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49531/tests/dromaeo/cssquery-mootools.html) 22:58:23 INFO - PID 1998 | RSS: Main: 268595200 22:58:23 INFO - PID 1998 | 23:01:24 INFO - PID 1998 | Cycle 1(1): loaded http://localhost:49531/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49531/tests/dromaeo/cssquery-prototype.html) 23:01:25 INFO - PID 1998 | RSS: Main: 261922816 23:01:25 INFO - PID 1998 | 23:04:28 INFO - PID 1998 | Cycle 1(1): loaded http://localhost:49531/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49531/tests/dromaeo/cssquery-yui.html) 23:04:28 INFO - PID 1998 | RSS: Main: 270196736 23:04:28 INFO - PID 1998 | 23:07:30 INFO - PID 1998 | Cycle 1(1): loaded http://localhost:49531/tests/dromaeo/cssquery-yui.html (next: http://localhost:49531/tests/dromaeo/cssquery-dojo.html) 23:07:30 INFO - PID 1998 | RSS: Main: 264830976 23:07:30 INFO - PID 1998 | 23:07:30 INFO - PID 1998 | __start_tp_report 23:07:30 INFO - PID 1998 | _x_x_mozilla_page_load 23:07:30 INFO - PID 1998 | _x_x_mozilla_page_load_details 23:07:30 INFO - PID 1998 | |i|pagename|runs| 23:07:30 INFO - PID 1998 | |0;dojo.html;3576.695980841628;3597.730170237232;3611.9638803611956;3625.3111908737337;3634.9091272718188;6082.178905847709;6168.136460895473;6233.470155036822;6239.7816076437275;6294.527910406727;6146.8628303763835;6150.938490615093;6210.62736235825;6254.030625253093;6294.685265736706;5229.686218826882;5343.385510666265;5422.75597598109;5427;5558.610897237195;5498.257735205756;5523.889522209553;5536.141898005815;5578.888422231553;5602.327720673525;5288.418273989858;5393.946060539383;5454.209139674765;5461.972690136524;5535.501804837564;5439.075357189287;5448.809291674773;5458.9454105458835;5497.835064948058;5510.366307874566;4598.896264896401;5291.550218231455;5388.892222155574;5409.377921538995;5424.674519528841;4782.689125206885;6027.09593560965;6275.6862156891975;6277.18396608438;6318.178636777236;6391.58454700443;6438.130852334922;6638;6666.400023997815;6674;6058.909116363258;6745.561538500031;6780.762673306411;6826.658667066627;6833.521653484258;5880.353161152269;5996.940030599725;6305.684715764193;6329.651869147195;6366.77716279928;5575.247341608912;6646.966765166143;6873.450123990069;6997.825054373681;7002;4981.4769449207315;6162.168107305546;6576.5067619928695;6608.7356505739235;6620.635865027373;6720.092787473726;6762.256151823295;6770.69531871067;6776.966115169393;6782.355676210752;5496.4503549645515;6377;6451.258105317948;6598.10925525048;6642.1033160523675;6628.403443690091;6641.833954151185;6686.598804071771;6692.230393504712;6725.461963042945;5329.573634109262;5347.919781203285;5348.812791552355;5364.517193452608;5387.218853266333;4675.649326300541;4787.736674482937;4793.640476964241;4828.830990915371;4829.116644508645;5038.974805125951;6272.655003974825;6315.778947736899;6317.526185536103;6347.174867267226;6814.761483348059;6821;6822.385985261251;6840.281770414035;6840.6921688524135;6384.648844313608;6390.137331460286;6398.5521013528605;6404.135441715309;6411.5832470889245;5432.592555558349;5546.944530554723;5763;5810.448007439287;5832.533397328203;5825.951328760863;5998.790042348497;6867.793966181022;6973.267806880204;7081.575105493687;6517.185351831021;6589.835254118494;6721.361470660279;6783.559068660521;6826.180858296838;6977.372036516738;7024.473164512784;7035.613041282779;7055.3650171484815;7095.858082838211;6365.713542890674;6418.807435776934;6651.43462805667;6665.933340666531;6691.364320389555;6557.377049180321;6683.164604424447;6710.396064354231;6744.898826517704;6775.966120169368;6464.41820236181;6577.408033277028;6584.111144995364;6588.538802283794;6600.141981542369;5847.883042339215;6538.117354157318;6567.27759946396;6568.573042752206;6577.901331479936;5572.721363931868;6050.576459647782;6326.936730632635;6356.364363563606;6431.1317972073175;6456.935430645822;6520.18497687789;6524;6562.704678289393;6565.212174539086;6356.682165891594;6400.487960963205;6400.967995159995;6408;6417.197850268716 23:07:30 INFO - PID 1998 | |1;ext.html;694.4895501806171;1213.2114125818216;1269.0862578943163;1287.7167023254892;1293.1917551530294;37459.81270093653;38204.04489887754;38261.8086909565;38373;38439;38003.61996380035;38389.232215355674;38408.42387364185;38574.84275471741;38611.80694096525;7140.571565706075;7193.848984162535;8490.40567160299;8491.23578877901;8517.148285171495;8457.112003239663;8532.402731808776;8560.229579337858;8582.14178582143;8593.527355995418;8529.872051919227;8550.572471376438;8579.142085791433;8579.184977427134;8622.698205562776;8532.189442003031;8533.189347012023;8550.657973681045;8563.914360856435;8564.100769419247;7024.297570242986;8330.083690794008;8340.999080110425;8360.122187170382;8408.576468178053;37848.62151378506;37958.051048724;38030.42954355687;38053.0486737831;38068;3677.8161091945562;3718.1262403335463;3720.5535335759882;3735.869244576427;3875.6124387561017;31109;31115.910943116884;31116.688833111606;31142.844285778654;31239.843800781076;3025.7579393648675;3047.6495203051713;3054.205906464313;3073.861676224575;3078.5228289615147;249178.75410623013;306909;307806;308680;310491.4475427631;39257.41113883294;48037.75981120072;48468.78828029257;48600.27099593509;48712.5128748708;50803.23795143076;51044.97910041778;51112.233316500286;51244.48755512472;51280.743596281776;14121.870250379945;14159.008869379251;14268.500602478864;14283.500077497238;14290.928180386512;8936.41913275635;10342.276040677081;11240.887591124148;11389.715257118472;11710.70723231909;13748.65628359299;13759.17444953286;13761.862381376259;13782.65543361404;13839.654008649864;5200.401953775364;6094;6111.327753947062;6127.816165514952;6212.409821067082;1015.6089905386332;1021.8109649714678;1021.9233557483218;1046.8168070587617;1060.9469526523642;23033.539329213323;23623.88188059049;24891.875540622183;24900.003999840166;24905.128320508695;1110.74452875839;1117.3072694929197;1140.8003599370077;1151.83874257604;1156.8264760285856;10989.602544037272;12445.688857778629;12728.490860365668;12735.981121510256;12882.80675789864;273265;317450;317575;319287.4035629807;319642;54358.45641543613;54707.17939230835;54799.178012329845;54970.72514637481;55030.44969550333;1497.0568541818584;1505.1495904813746;1525.7558790593673;1544.9227538623024;1559.5009596928874;22957.196498122485;24329.27012189637;24337.51324973455;24422.389440264134;24484.51030979335;38436.80781596074;38524.03689907663;38599.807000964815;38617;38620.6137938617;279017;282519;283004;283095;283148;32909.34181316408;34229.828850855585;34513;34690.65309346975;34719;43463.9134021652;44147.77926110349;45511.772441138906;45530.54469455263;45694.771526142154;23997.880010599834;30009.549856751728;30064;30104.397912041197;31623.84188079045;25306.493870122864;30765.692343076284;31216.063518094492;31729.5240571387;32108.19729506781;32101.67898320987;35995.92012239637;38042.80978595089;38071.809640951615;41762.791186043876;26377;29829.359385233154;30747.077587672407;30894;31097;22484.325470235264;23765.762342376354;24607.015719371026;24653.89057492381;24849.50300994006 23:07:30 INFO - PID 1998 | |2;jquery.html;422.18518259758656;441.13317331443704;443.74706417342105;449.23629829290195;461.20442237140924;19465.610687786255;21319.57360852782;21768.564628707456;21805.454863628416;21988.120475180975;18836.34072807452;21701.131954721794;22405.66391504125;22469.43826404341;22810.65784013241;6048.0625503047095;6294.779682711106;6297.527685423589;6298.370162983688;6317.5893566918;5985.910211346833;5989.341172471025;6083.543734219929;6096.72564734588;6173.475254603364;5931.762729490815;6100.694965251741;6114.510839132859;6118.724657390429;6126.142340072393;5462.20797984294;6126.6017708848785;6178.7219575119225;6216.533759968021;6245;5261.86845328866;5295.523402893758;5377.677339359651;5504.669719816784;5510.1459273812625;21266.5746685067;25672;26196.476070478675;26330.86834565815;26517.602235966475;2167.4581354661336;2257.672637467558;2258.7063681721434;2259.3109101723967;2261.8303627228024;17149.742753858704;17165.485035448957;17585.7362139568;17703.468895933143;17770.555736106573;2090.571432856261;2187.409399462152;2222.377734234417;2222.4221702357504;2230.063373383183;157745;160791;162223;162585.18707406506;167176;12187.512499500099;12371.75256494865;12609.747805043848;12693.682657933625;12786.360681965865;16147.919260403858;16206.432774852823;16210.513684589481;16243.106629135511;16279.755803662954;7574.924250757422;7599.772006839804;8734.91265087341;8855.73432797017;9074.092590740873;8621.69824056155;8641.524716140673;8658.220760131619;8736.170063843925;8775.297976161892;7190;7731.458797884206;7847.332976696931;7861.213878612208;7896.249856263646;2222.6666000100113;2239.6528538076623;2245.6743772152945;2251.7973382395335;2404.9158279460135;815.9877601835977;831.3640065350012;958.6692591056075;974.9756256093513;990.8209231015213;17968.730469042974;20595.279165229444;20667.483312916997;20687.58624827495;21020.684689729667;861.6811779641573;990.1979396688705;994.9154321882577;1015.167562598662;1033.7002269341956;10305.175585953255;10632.096271816983;10653.467326633638;10709.62516311941;10762.92780414689;531969.1227789518;544040;554266;564717;565698;124818;128953;130700;131971.34014329867;134729.32635336957;1198.9460474278678;1237.1649136832602;1241.6399244219256;1257.283348491351;1258.025030601273;18065.458036258933;20429.69355459699;21192.026624348917;21609.567808644053;21666.89166554157;45036.77481612571;57919.71040144772;58022.4197758017;58824.41175588189;58975;81208.59395702984;81257.78113328187;81390.59304703439;81457;81581.59209203917;8654.350923680875;8676.956615216883;9044.14080662336;9048.864267036131;12556.748865022832;12274.263544187377;13416.262105584286;13482;14035.719285614436;14176.007679462338;11756.529738810694;11880.46537905779;11911.583094591648;12211.75576488483;12216.022718182701;10658.040776330168;10814.405207713651;10847.294925829796;11136.610218642309;11204.383758893338;11028.28316159447;11933;12024.579139730067;12294.508219671114;12363.381830908238;12892.226466412045;12892.548760793326;13068;13095.476180953036;13417.865821342051;11899.04807615406;12003.699907502032;12206.6948326289;12232.75534489323;12260.816087758869 23:07:30 INFO - PID 1998 | |3;mootools.html;1408.0988167572752;1451.8983671143017;1465.1794994802917;1470.125275461101;1482.236648126215;10043.19654427646;11497.022753065989;11611.593594224203;11825.822612660817;12067.034637229022;10148.42699381597;11631.069514438846;11785.469653865575;11805.940970295158;11818.05455563555;2493.8379005364595;2559.5008973250224;2572.2026171886682;2577.9226623201334;2590.5596048671773;2491.2277194069807;2552.247087109298;2562.1032638576535;2566.0762125634756;2567.319660290025;2501.949961000779;2518.445941892781;2544.414784599543;2554.501872134925;2555.246202370305;2463.08865719684;2478.938026549342;2515.987420062888;2516.3709072731817;2529.0768869362732;2419.3346829621996;2499.1627804685454;2524.6591710119083;2533.050106210171;2533.316004678745;11931.403429828473;11979.820302695467;11988;12005;12074.758504829853;6595.703193356262;6806.251312355637;6850.38346548808;6858.279880612564;6859.451243900476;3972.781497017663;4276.935845962313;4339.804708788112;4348.173846969097;4358.2808836541935;1237.307108019512;1247.2890452442111;1265.013289634087;1272.8854403103674;1310.7247478029626;15704.293306801108;16191.676166476604;16777.916110419494;16925.23836427364;16945.91527042369;5516.227728118067;5609.80365687199;5692.601517893708;5764.855878603068;5802.65184088956;11501.194916355942;11616.838316168485;11774.88225117755;11826.231294965799;11845.881541184477;6569.638669873108;6597.47220222381;6601.3068627794355;6606;6650.501212409089;6072.453479186895;6096.329403765582;6225.750969961151;6233.594816336924;6268.09112678659;3405.8297085145646;4192.329227323614;4360.127974405132;4362.520122786491;4409.757463339547;1065.2969040433431;1255.7174635706892;1262.9179103358251;1270.7585558743808;1273.4842388832537;775.9030121234846;795.431266644352;802.4583406200908;827.6027506796771;846.9026062002948;6171.7531298748445;6222.12890195373;6423.903641445382;6444.097826304227;6511;588.9146073819272;599.9190109335184;600.3726106219011;607.3471018654875;612.9295131059896;3868;3937.448757173998;4026.2148880968516;4205.537390887;4290.313549832012;14451.927740361441;14792.408303667948;14871.107733535806;14931.477398291008;14932.552023439313;14353;14611.926940365443;14860.554183374516;15181.468648597245;15305.234738263265;7576.24237576249;7811.789172678244;8381.287590554872;8668.133186681407;8780.560971951378;6122.693865306628;6861.4510839133745;6995.125609298838;7013.824654383681;7087.858242835217;12140.635780926586;12454.820406428658;13575.456981720621;13977.790333145214;14036.929815350857;13419.26194059297;13491.392887320291;13547.322633868464;13560.91512679003;13681.110727802661;8237.423380363556;8282.296004839414;8294.129116442928;8304.128066553167;8312.584370781558;10244.282900196915;10510.894891051297;10592.576296948037;10674.46627668629;10777.08394786452;9028.683996060106;9033.096690331176;9073.410228335137;9096.135867092615;9123.863142053007;7399.889001664872;7459.738909138154;8924.464532128093;8928.776780580538;8966.148215919477;7701.88447173304;8032.196780322155;8083.757487275391;8084.272415482635;8091.433599648203;5244.7902083916215;5257.8159764408065;5959.22530071088;6019.849503762441;6153.169322141453;5274.920876186783;5501.569591906054;6033.758649653964;6078.756849725962;6130.846728831815 23:07:30 INFO - PID 1998 | |4;prototype.html;336.3172759298625;380.4407520944212;383.2660455228238;408.2671604469976;415.646700304741;5967.4927631151295;6162.445379915806;6174.9073763893575;6325.557210995226;6326.165412030506;5866.325372582154;6033.849153771147;6168.84577885552;6171.858206231839;6181.876362472748;3702.296563652904;3982.362821948489;4491.910161796762;4497.92049908023;4524;4478.977605111986;4483.148201841656;4491.6182124519455;4497.70764900282;4508.098380323932;4442.489113751928;4457;4471.150481408522;4474.105178964204;4522.570355816209;4240.151969606091;4272.4325188467665;4400.8019639116;4442.267025940716;4449.933251001222;3768.208676178006;3798.620137986207;3805.6574908258112;3809.9238015239816;3825.2923209206388;4765.451973023112;5229.50319719626;6018.007028840236;6060.96969515154;6061.242344706911;4402.845900393503;5226.8693282667855;5262.184361423947;5287.233351164098;5337.412884582693;5716.456936591017;5808.88382232353;6625.933740662628;6632.63520506372;6656.966715166442;4224.598663126998;4305.138972205571;4306.763128027988;4309.482862056542;4333.241682705514;6701.497387695894;6903;7017.859642807115;7063.964680176618;7082.291770822928;4335.176316499855;4356.695031347838;4361.934570981438;4367.192069467159;4371;4429.512753597101;4441.113276370385;4442.71161363201;4449.421575195204;4463.977680111579;4853.417589889165;5129.7178655173575;5192.714400707921;5267;5275.654708049499;6118.357572454917;6293.143522660735;6599.8020059398295;6614.503912206507;6629.414528333954;1343.9529616463572;1347.3734713358335;1494.290212149242;1507.329238488884;1519.7188520123811;956.8469044952774;1002.4686915934566;1171.5196769324707;1199.4362649554696;1204.175140029083;5310.628256022119;6322.620642761449;6338.397852204033;6351.714172862232;6368.713407896563;6478.773242936569;6768.83077923046;6938.271481494471;6965.895511567331;6985.476089293324;5900.430239705002;6191.288001879751;6273.7176827042895;6474.902876456857;6498.350164983463;2955.2120966815196;3203.8718451262152;3516.279162771677;3634.200475895299;3644.815434983641;6946.409555187865;7116.288371162946;7182.569045857265;7250.963745181346;7286.453515986323;6847.828804279833;7051.330123638246;7136.180274030089;7142.250148713599;7153.177384600734;6029.8191054268445;7219.963900180571;7240.456965727486;7250.347468727839;7256.963715181496;6717.395434410926;6823.2024869803145;6926.203486598904;6944.236134025355;6950.721971121098;5401.810936617357;5705.34388545306;6455.548111632141;6810.148731408574;6848.417884479676;6246.156768836149;6988.846840271298;7042.6126563039525;7057.306246500905;7202.351788339075;3694.3350196964802;4345.34819777036;4365.017870979132;4393.0774537347515;4413.955860441354;5553.583481239004;5994.790182343597;6593.901091483535;6707.618795327784;6745.224299205458;5876.853078673067;6007.609505382136;6201.844953876189;6656.866862662817;6672.633005184762;5407.756650950795;5542.528885044655;5781.756922261734;6393.4885209184085;6686.83282917931;5593.272874526286;5731.484166425042;5912.852178695567;6572.605643661204;6575.704093315908;5777.826665200051;6374.394432528902;6438.742450301936;6708.6981085850275;6741.764038258833;6542.411182993554;6550.770723024862;6565.671716414256;6567.441767449629;6595.934040659532 23:07:30 INFO - PID 1998 | |5;yui.html;852.3905407633548;856.9614367353469;863.956802159892;877.8719345640849;887.4453466583383;16128.596785080379;16245.106519141456;16499.175041247934;17892.731609025876;18984.145713442893;14667.77998330026;17607.29570817166;17905.925644461342;17952;18387.63224735508;987.6888780034302;1000.6647772996055;1008.9798204035915;1013.5945621751284;1014.7818219082895;930.4231376546551;939.2955283537347;993.7018894331708;1019.5463018956567;1039.490649581707;1025.692292312307;1060.151878497203;1072.8658917635296;1077.4774234496263;1078.309881675728;995.9452230127304;1061.405612856795;1067.9374022847278;1070.925035247533;1071.2287153249686;778.7624774443792;795.1531618825932;795.1650766695005;801.7955421367573;806.822499050208;16297.185140743033;16604.66790664192;16713.749293760604;16827.158642067847;16891.493255202364;16159.596010099842;16376.508704738877;16874;16966.83033169665;17121.743173852403;13320.600381988555;13407.262600556965;13542.86457135426;13548.390322435514;13693.109947853358;3366.2762506061313;3380.6112297085906;3393.677600627961;3398.2693720850134;3404.0808981575083;128590;129498.35250823686;130258;131291.34354328262;131515;12879.356032198353;13059.738805223842;13145.53990610324;13238.867611323763;13306.600801975956;23514;24124.155654552007;24505;24914.875425622755;25088.996440142553;7251.637418129178;7405.296496832792;7417;7455.105387353552;7468;3347.73218142548;3692.8892133236045;3831.6743076838225;3911.7261791674878;3913.256481268607;4009.1781184857036;4191.7484950902435;4314.374415709767;4320.006398528356;4324.783760811947;919.9218066464425;947.8436058050345;953.2326477185793;962.5716556132594;964.1226483899618;539.9956081688057;587.9470847623649;644.8355669304298;650.3658932540735;654.1528720307214;10236;12199.817002744965;12300.446479908425;12428;13419.865801342057;658.8188248231775;663.3466035954581;673.0980486148584;679.7009315900988;701.431840209432;5404.648697834628;5458.8478080771565;5655.321361436571;5696;5774.538036957033;69255.92232233041;98846;99632;102710.48644756728;103233;86737.83155421038;92732.53633731788;92870;94405.11189776304;96143.0385696148;85172.14827851643;85792.57103714441;85908.57045714857;86390.56804715937;87991;8543.783789150913;9699.175570076623;9903.960084191056;10522.158227341948;10658.307210031324;41335.586644134375;41510.60362152901;41899.7905010473;42483;42815.571844281156;41349.75950721483;54073;54668.72665636647;54718;55159.17261241165;13888.444462221802;14152.929235353757;15462.690746185239;16045.679086417973;16156;17006;17657;18028.909855450638;18178;19043.333483328017;15280;16582.336706532085;16841;16925.492235232963;17107.401240956508;16201.43294984717;16399.91800040992;16964.745528817326;17046.57383565371;17057.573560661083;15641.608959776097;15732.449364272195;16550.006999580062;16560.337586496404;16633.584160396087;11812.881871181522;11863.940680296544;11937.940310298392;11977.520899163936;12038.277703337828;10779.407132607781;12896.806547901977;13016;13064.673383165496;13175.736485270048 23:07:30 INFO - PID 1998 | __end_tp_report 23:07:30 INFO - PID 1998 | __start_cc_report 23:07:30 INFO - PID 1998 | _x_x_mozilla_cycle_collect,426 23:07:30 INFO - PID 1998 | __end_cc_report 23:07:30 INFO - PID 1998 | __startTimestamp1493273250933__endTimestamp 23:07:30 INFO - PID 1998 | 23:07:30 INFO - PID 1998 | ------- Summary: start ------- 23:07:30 INFO - PID 1998 | Number of tests: 6 23:07:30 INFO - PID 1998 | 23:07:30 INFO - PID 1998 | [#0] dojo.html Cycles:165 Average:6110.66 Median:6365.71 stddev:745.22 (11.7%) stddev-sans-first:720.50 23:07:30 INFO - PID 1998 | Values: 3576.7 3597.7 3612.0 3625.3 3634.9 6082.2 6168.1 6233.5 6239.8 6294.5 6146.9 6150.9 6210.6 6254.0 6294.7 5229.7 5343.4 5422.8 5427.0 5558.6 5498.3 5523.9 5536.1 5578.9 5602.3 5288.4 5393.9 5454.2 5462.0 5535.5 5439.1 5448.8 5458.9 5497.8 5510.4 4598.9 5291.6 5388.9 5409.4 5424.7 4782.7 6027.1 6275.7 6277.2 6318.2 6391.6 6438.1 6638.0 6666.4 6674.0 6058.9 6745.6 6780.8 6826.7 6833.5 5880.4 5996.9 6305.7 6329.7 6366.8 5575.2 6647.0 6873.5 6997.8 7002.0 4981.5 6162.2 6576.5 6608.7 6620.6 6720.1 6762.3 6770.7 6777.0 6782.4 5496.5 6377.0 6451.3 6598.1 6642.1 6628.4 6641.8 6686.6 6692.2 6725.5 5329.6 5347.9 5348.8 5364.5 5387.2 4675.6 4787.7 4793.6 4828.8 4829.1 5039.0 6272.7 6315.8 6317.5 6347.2 6814.8 6821.0 6822.4 6840.3 6840.7 6384.6 6390.1 6398.6 6404.1 6411.6 5432.6 5546.9 5763.0 5810.4 5832.5 5826.0 5998.8 6867.8 6973.3 7081.6 6517.2 6589.8 6721.4 6783.6 6826.2 6977.4 7024.5 7035.6 7055.4 7095.9 6365.7 6418.8 6651.4 6665.9 6691.4 6557.4 6683.2 6710.4 6744.9 6776.0 6464.4 6577.4 6584.1 6588.5 6600.1 5847.9 6538.1 6567.3 6568.6 6577.9 5572.7 6050.6 6326.9 6356.4 6431.1 6456.9 6520.2 6524.0 6562.7 6565.2 6356.7 6400.5 6401.0 6408.0 6417.2 23:07:30 INFO - PID 1998 | 23:07:30 INFO - PID 1998 | [#1] ext.html Cycles:180 Average:44812.44 Median:319464.70 stddev:77792.30 (24.4%) stddev-sans-first:77940.00 23:07:30 INFO - PID 1998 | Values: 694.5 1213.2 1269.1 1287.7 1293.2 37459.8 38204.0 38261.8 38373.0 38439.0 38003.6 38389.2 38408.4 38574.8 38611.8 7140.6 7193.8 8490.4 8491.2 8517.1 8457.1 8532.4 8560.2 8582.1 8593.5 8529.9 8550.6 8579.1 8579.2 8622.7 8532.2 8533.2 8550.7 8563.9 8564.1 7024.3 8330.1 8341.0 8360.1 8408.6 37848.6 37958.1 38030.4 38053.0 38068.0 3677.8 3718.1 3720.6 3735.9 3875.6 31109.0 31115.9 31116.7 31142.8 31239.8 3025.8 3047.6 3054.2 3073.9 3078.5 249178.8 306909.0 307806.0 308680.0 310491.4 39257.4 48037.8 48468.8 48600.3 48712.5 50803.2 51045.0 51112.2 51244.5 51280.7 14121.9 14159.0 14268.5 14283.5 14290.9 8936.4 10342.3 11240.9 11389.7 11710.7 13748.7 13759.2 13761.9 13782.7 13839.7 5200.4 6094.0 6111.3 6127.8 6212.4 1015.6 1021.8 1021.9 1046.8 1060.9 23033.5 23623.9 24891.9 24900.0 24905.1 1110.7 1117.3 1140.8 1151.8 1156.8 10989.6 12445.7 12728.5 12736.0 12882.8 273265.0 317450.0 317575.0 319287.4 319642.0 54358.5 54707.2 54799.2 54970.7 55030.4 1497.1 1505.1 1525.8 1544.9 1559.5 22957.2 24329.3 24337.5 24422.4 24484.5 38436.8 38524.0 38599.8 38617.0 38620.6 279017.0 282519.0 283004.0 283095.0 283148.0 32909.3 34229.8 34513.0 34690.7 34719.0 43463.9 44147.8 45511.8 45530.5 45694.8 23997.9 30009.5 30064.0 30104.4 31623.8 25306.5 30765.7 31216.1 31729.5 32108.2 32101.7 35995.9 38042.8 38071.8 41762.8 26377.0 29829.4 30747.1 30894.0 31097.0 22484.3 23765.8 24607.0 24653.9 24849.5 23:07:30 INFO - PID 1998 | 23:07:30 INFO - PID 1998 | [#2] jquery.html Cycles:180 Average:35981.44 Median:2222.54 stddev:93961.47 (4227.7%) stddev-sans-first:94187.12 23:07:30 INFO - PID 1998 | Values: 422.2 441.1 443.7 449.2 461.2 19465.6 21319.6 21768.6 21805.5 21988.1 18836.3 21701.1 22405.7 22469.4 22810.7 6048.1 6294.8 6297.5 6298.4 6317.6 5985.9 5989.3 6083.5 6096.7 6173.5 5931.8 6100.7 6114.5 6118.7 6126.1 5462.2 6126.6 6178.7 6216.5 6245.0 5261.9 5295.5 5377.7 5504.7 5510.1 21266.6 25672.0 26196.5 26330.9 26517.6 2167.5 2257.7 2258.7 2259.3 2261.8 17149.7 17165.5 17585.7 17703.5 17770.6 2090.6 2187.4 2222.4 2222.4 2230.1 157745.0 160791.0 162223.0 162585.2 167176.0 12187.5 12371.8 12609.7 12693.7 12786.4 16147.9 16206.4 16210.5 16243.1 16279.8 7574.9 7599.8 8734.9 8855.7 9074.1 8621.7 8641.5 8658.2 8736.2 8775.3 7190.0 7731.5 7847.3 7861.2 7896.2 2222.7 2239.7 2245.7 2251.8 2404.9 816.0 831.4 958.7 975.0 990.8 17968.7 20595.3 20667.5 20687.6 21020.7 861.7 990.2 994.9 1015.2 1033.7 10305.2 10632.1 10653.5 10709.6 10762.9 531969.1 544040.0 554266.0 564717.0 565698.0 124818.0 128953.0 130700.0 131971.3 134729.3 1198.9 1237.2 1241.6 1257.3 1258.0 18065.5 20429.7 21192.0 21609.6 21666.9 45036.8 57919.7 58022.4 58824.4 58975.0 81208.6 81257.8 81390.6 81457.0 81581.6 8654.4 8677.0 9044.1 9048.9 12556.7 12274.3 13416.3 13482.0 14035.7 14176.0 11756.5 11880.5 11911.6 12211.8 12216.0 10658.0 10814.4 10847.3 11136.6 11204.4 11028.3 11933.0 12024.6 12294.5 12363.4 12892.2 12892.5 13068.0 13095.5 13417.9 11899.0 12003.7 12206.7 12232.8 12260.8 23:07:30 INFO - PID 1998 | 23:07:30 INFO - PID 1998 | [#3] mootools.html Cycles:180 Average:7011.85 Median:3636.91 stddev:4465.70 (122.8%) stddev-sans-first:4458.38 23:07:30 INFO - PID 1998 | Values: 1408.1 1451.9 1465.2 1470.1 1482.2 10043.2 11497.0 11611.6 11825.8 12067.0 10148.4 11631.1 11785.5 11805.9 11818.1 2493.8 2559.5 2572.2 2577.9 2590.6 2491.2 2552.2 2562.1 2566.1 2567.3 2501.9 2518.4 2544.4 2554.5 2555.2 2463.1 2478.9 2516.0 2516.4 2529.1 2419.3 2499.2 2524.7 2533.1 2533.3 11931.4 11979.8 11988.0 12005.0 12074.8 6595.7 6806.3 6850.4 6858.3 6859.5 3972.8 4276.9 4339.8 4348.2 4358.3 1237.3 1247.3 1265.0 1272.9 1310.7 15704.3 16191.7 16777.9 16925.2 16945.9 5516.2 5609.8 5692.6 5764.9 5802.7 11501.2 11616.8 11774.9 11826.2 11845.9 6569.6 6597.5 6601.3 6606.0 6650.5 6072.5 6096.3 6225.8 6233.6 6268.1 3405.8 4192.3 4360.1 4362.5 4409.8 1065.3 1255.7 1262.9 1270.8 1273.5 775.9 795.4 802.5 827.6 846.9 6171.8 6222.1 6423.9 6444.1 6511.0 588.9 599.9 600.4 607.3 612.9 3868.0 3937.4 4026.2 4205.5 4290.3 14451.9 14792.4 14871.1 14931.5 14932.6 14353.0 14611.9 14860.6 15181.5 15305.2 7576.2 7811.8 8381.3 8668.1 8780.6 6122.7 6861.5 6995.1 7013.8 7087.9 12140.6 12454.8 13575.5 13977.8 14036.9 13419.3 13491.4 13547.3 13560.9 13681.1 8237.4 8282.3 8294.1 8304.1 8312.6 10244.3 10510.9 10592.6 10674.5 10777.1 9028.7 9033.1 9073.4 9096.1 9123.9 7399.9 7459.7 8924.5 8928.8 8966.1 7701.9 8032.2 8083.8 8084.3 8091.4 5244.8 5257.8 5959.2 6019.8 6153.2 5274.9 5501.6 6033.8 6078.8 6130.8 23:07:30 INFO - PID 1998 | 23:07:30 INFO - PID 1998 | [#4] prototype.html Cycles:180 Average:5304.78 Median:5906.64 stddev:1707.06 (28.9%) stddev-sans-first:1670.62 23:07:30 INFO - PID 1998 | Values: 336.3 380.4 383.3 408.3 415.6 5967.5 6162.4 6174.9 6325.6 6326.2 5866.3 6033.8 6168.8 6171.9 6181.9 3702.3 3982.4 4491.9 4497.9 4524.0 4479.0 4483.1 4491.6 4497.7 4508.1 4442.5 4457.0 4471.2 4474.1 4522.6 4240.2 4272.4 4400.8 4442.3 4449.9 3768.2 3798.6 3805.7 3809.9 3825.3 4765.5 5229.5 6018.0 6061.0 6061.2 4402.8 5226.9 5262.2 5287.2 5337.4 5716.5 5808.9 6625.9 6632.6 6657.0 4224.6 4305.1 4306.8 4309.5 4333.2 6701.5 6903.0 7017.9 7064.0 7082.3 4335.2 4356.7 4361.9 4367.2 4371.0 4429.5 4441.1 4442.7 4449.4 4464.0 4853.4 5129.7 5192.7 5267.0 5275.7 6118.4 6293.1 6599.8 6614.5 6629.4 1344.0 1347.4 1494.3 1507.3 1519.7 956.8 1002.5 1171.5 1199.4 1204.2 5310.6 6322.6 6338.4 6351.7 6368.7 6478.8 6768.8 6938.3 6965.9 6985.5 5900.4 6191.3 6273.7 6474.9 6498.4 2955.2 3203.9 3516.3 3634.2 3644.8 6946.4 7116.3 7182.6 7251.0 7286.5 6847.8 7051.3 7136.2 7142.3 7153.2 6029.8 7220.0 7240.5 7250.3 7257.0 6717.4 6823.2 6926.2 6944.2 6950.7 5401.8 5705.3 6455.5 6810.1 6848.4 6246.2 6988.8 7042.6 7057.3 7202.4 3694.3 4345.3 4365.0 4393.1 4414.0 5553.6 5994.8 6593.9 6707.6 6745.2 5876.9 6007.6 6201.8 6656.9 6672.6 5407.8 5542.5 5781.8 6393.5 6686.8 5593.3 5731.5 5912.9 6572.6 6575.7 5777.8 6374.4 6438.7 6708.7 6741.8 6542.4 6550.8 6565.7 6567.4 6595.9 23:07:30 INFO - PID 1998 | 23:07:30 INFO - PID 1998 | [#5] yui.html Cycles:180 Average:21615.73 Median:18282.82 stddev:30799.12 (168.5%) stddev-sans-first:30846.06 23:07:30 INFO - PID 1998 | Values: 852.4 857.0 864.0 877.9 887.4 16128.6 16245.1 16499.2 17892.7 18984.1 14667.8 17607.3 17905.9 17952.0 18387.6 987.7 1000.7 1009.0 1013.6 1014.8 930.4 939.3 993.7 1019.5 1039.5 1025.7 1060.2 1072.9 1077.5 1078.3 995.9 1061.4 1067.9 1070.9 1071.2 778.8 795.2 795.2 801.8 806.8 16297.2 16604.7 16713.7 16827.2 16891.5 16159.6 16376.5 16874.0 16966.8 17121.7 13320.6 13407.3 13542.9 13548.4 13693.1 3366.3 3380.6 3393.7 3398.3 3404.1 128590.0 129498.4 130258.0 131291.3 131515.0 12879.4 13059.7 13145.5 13238.9 13306.6 23514.0 24124.2 24505.0 24914.9 25089.0 7251.6 7405.3 7417.0 7455.1 7468.0 3347.7 3692.9 3831.7 3911.7 3913.3 4009.2 4191.7 4314.4 4320.0 4324.8 919.9 947.8 953.2 962.6 964.1 540.0 587.9 644.8 650.4 654.2 10236.0 12199.8 12300.4 12428.0 13419.9 658.8 663.3 673.1 679.7 701.4 5404.6 5458.8 5655.3 5696.0 5774.5 69255.9 98846.0 99632.0 102710.5 103233.0 86737.8 92732.5 92870.0 94405.1 96143.0 85172.1 85792.6 85908.6 86390.6 87991.0 8543.8 9699.2 9904.0 10522.2 10658.3 41335.6 41510.6 41899.8 42483.0 42815.6 41349.8 54073.0 54668.7 54718.0 55159.2 13888.4 14152.9 15462.7 16045.7 16156.0 17006.0 17657.0 18028.9 18178.0 19043.3 15280.0 16582.3 16841.0 16925.5 17107.4 16201.4 16399.9 16964.7 17046.6 17057.6 15641.6 15732.4 16550.0 16560.3 16633.6 11812.9 11863.9 11937.9 11977.5 12038.3 10779.4 12896.8 13016.0 13064.7 13175.7 23:07:30 INFO - PID 1998 | -------- Summary: end -------- 23:07:30 INFO - PID 1998 | 23:07:31 INFO - TEST-INFO | 1998: exit 0 23:07:31 INFO - TEST-OK | dromaeo_css | took 1114726ms 23:07:31 INFO - TEST-START | kraken 23:07:31 INFO - operating with platform_type : mac_ 23:07:31 INFO - Initialising browser for kraken test... 23:07:31 INFO - TEST-INFO | started process 2005 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpWXDSu7/profile http://localhost:49531/getInfo.html) 23:07:34 INFO - PID 2005 | 2017-04-26 23:07:34.089 plugin-container[2006:18181] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 23:07:34 INFO - PID 2005 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:07:34 INFO - PID 2005 | 2017-04-26 23:07:34.092 plugin-container[2006:18181] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 23:07:34 INFO - PID 2005 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:07:34 INFO - PID 2005 | 2017-04-26 23:07:34.092 plugin-container[2006:18181] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:07:34 INFO - PID 2005 | __metrics Screen width/height:1600/1200 23:07:34 INFO - PID 2005 | colorDepth:24 23:07:34 INFO - PID 2005 | Browser inner width/height: 1024/689 23:07:34 INFO - PID 2005 | __metrics 23:07:34 ERROR - PID 2005 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 23:07:35 INFO - TEST-INFO | 2005: exit 0 23:07:35 INFO - Browser initialized. 23:07:35 INFO - Running cycle 1/1 for kraken test... 23:07:35 INFO - TEST-INFO | started process 2007 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpWXDSu7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:07:37 INFO - PID 2007 | 2017-04-26 23:07:37.586 plugin-container[2008:18312] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9447, name = 'com.apple.tsm.portname' 23:07:37 INFO - PID 2007 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:07:37 INFO - PID 2007 | 2017-04-26 23:07:37.587 plugin-container[2008:18312] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a17, name = 'com.apple.CFPasteboardClient' 23:07:37 INFO - PID 2007 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:07:37 INFO - PID 2007 | 2017-04-26 23:07:37.587 plugin-container[2008:18312] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:07:47 INFO - PID 2007 | RSS: Main: 252948480 23:07:47 INFO - PID 2007 | 23:07:50 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49531/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49531/tests/kraken/driver.html?testName=audio-beat-detection) 23:07:51 INFO - PID 2007 | RSS: Main: 260780032 23:07:51 INFO - PID 2007 | 23:07:53 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49531/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49531/tests/kraken/driver.html?testName=audio-dft) 23:07:54 INFO - PID 2007 | RSS: Main: 267767808 23:07:54 INFO - PID 2007 | 23:07:58 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49531/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49531/tests/kraken/driver.html?testName=audio-fft) 23:07:58 INFO - PID 2007 | RSS: Main: 264130560 23:07:58 INFO - PID 2007 | 23:08:00 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49531/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49531/tests/kraken/driver.html?testName=audio-oscillator) 23:08:01 INFO - PID 2007 | RSS: Main: 266088448 23:08:01 INFO - PID 2007 | 23:08:03 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49531/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49531/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:08:03 INFO - PID 2007 | RSS: Main: 266862592 23:08:03 INFO - PID 2007 | 23:08:06 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49531/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49531/tests/kraken/driver.html?testName=imaging-darkroom) 23:08:07 INFO - PID 2007 | RSS: Main: 272289792 23:08:07 INFO - PID 2007 | 23:08:10 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49531/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49531/tests/kraken/driver.html?testName=imaging-desaturate) 23:08:11 INFO - PID 2007 | RSS: Main: 273440768 23:08:11 INFO - PID 2007 | 23:08:13 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49531/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49531/tests/kraken/driver.html?testName=json-parse-financial) 23:08:14 INFO - PID 2007 | RSS: Main: 272838656 23:08:14 INFO - PID 2007 | 23:08:16 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49531/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49531/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:08:16 INFO - PID 2007 | RSS: Main: 272449536 23:08:16 INFO - PID 2007 | 23:08:18 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49531/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49531/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:08:19 INFO - PID 2007 | RSS: Main: 272371712 23:08:19 INFO - PID 2007 | 23:08:21 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49531/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49531/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:08:21 INFO - PID 2007 | RSS: Main: 272052224 23:08:21 INFO - PID 2007 | 23:08:24 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49531/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49531/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:08:25 INFO - PID 2007 | RSS: Main: 272318464 23:08:25 INFO - PID 2007 | 23:08:28 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49531/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49531/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:08:29 INFO - PID 2007 | RSS: Main: 269643776 23:08:29 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49531/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49531/tests/kraken/driver.html?testName=ai-astar) 23:08:31 INFO - PID 2007 | RSS: Main: 271429632 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | __start_tp_report 23:08:31 INFO - PID 2007 | _x_x_mozilla_page_load 23:08:31 INFO - PID 2007 | _x_x_mozilla_page_load_details 23:08:31 INFO - PID 2007 | |i|pagename|runs| 23:08:31 INFO - PID 2007 | |0;ai-astar;102;124;115;112;114;116;115;114;118;123 23:08:31 INFO - PID 2007 | |1;audio-beat-detection;144;107;110;148;119;115;111;109;150;143 23:08:31 INFO - PID 2007 | |2;audio-dft;256;251;271;227;240;229;196;278;208;276 23:08:31 INFO - PID 2007 | |3;audio-fft;82;58;59;107;88;51;72;89;91;47 23:08:31 INFO - PID 2007 | |4;audio-oscillator;84;69;83;70;69;69;85;72;72;97 23:08:31 INFO - PID 2007 | |5;imaging-gaussian-blur;89;89;88;89;91;100;88;88;89;88 23:08:31 INFO - PID 2007 | |6;imaging-darkroom;96;95;97;99;111;95;96;96;106;103 23:08:31 INFO - PID 2007 | |7;imaging-desaturate;90;96;87;91;101;89;90;93;93;86 23:08:31 INFO - PID 2007 | |8;json-parse-financial;50;49;47;51;49;47;47;50;48;51 23:08:31 INFO - PID 2007 | |9;json-stringify-tinderbox;59;64;57;59;71;60;60;57;57;71 23:08:31 INFO - PID 2007 | |10;stanford-crypto-aes;86;80;83;83;83;81;83;84;85;83 23:08:31 INFO - PID 2007 | |11;stanford-crypto-ccm;138;154;127;151;160;136;149;152;202;147 23:08:31 INFO - PID 2007 | |12;stanford-crypto-pbkdf2;241;193;185;208;181;165;239;231;190;180 23:08:31 INFO - PID 2007 | |13;stanford-crypto-sha256-iterative;67;68;70;65;66;68;66;69;69;65 23:08:31 INFO - PID 2007 | __end_tp_report 23:08:31 INFO - PID 2007 | __start_cc_report 23:08:31 INFO - PID 2007 | _x_x_mozilla_cycle_collect,1057 23:08:31 INFO - PID 2007 | __end_cc_report 23:08:31 INFO - PID 2007 | __startTimestamp1493273311479__endTimestamp 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | ------- Summary: start ------- 23:08:31 INFO - PID 2007 | Number of tests: 14 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | [#0] ai-astar Cycles:10 Average:115.30 Median:115.50 stddev:6.09 (5.3%) stddev-sans-first:4.15 23:08:31 INFO - PID 2007 | Values: 102.0 124.0 115.0 112.0 114.0 116.0 115.0 114.0 118.0 123.0 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:131.00 stddev:18.17 (13.9%) stddev-sans-first:18.01 23:08:31 INFO - PID 2007 | Values: 144.0 107.0 110.0 148.0 119.0 115.0 111.0 109.0 150.0 143.0 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | [#2] audio-dft Cycles:10 Average:243.20 Median:253.50 stddev:28.33 (11.2%) stddev-sans-first:29.67 23:08:31 INFO - PID 2007 | Values: 256.0 251.0 271.0 227.0 240.0 229.0 196.0 278.0 208.0 276.0 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | [#3] audio-fft Cycles:10 Average:74.40 Median:77.00 stddev:20.01 (26.0%) stddev-sans-first:21.04 23:08:31 INFO - PID 2007 | Values: 82.0 58.0 59.0 107.0 88.0 51.0 72.0 89.0 91.0 47.0 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:77.50 stddev:9.66 (12.5%) stddev-sans-first:9.91 23:08:31 INFO - PID 2007 | Values: 84.0 69.0 83.0 70.0 69.0 69.0 85.0 72.0 72.0 97.0 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:89.00 stddev:3.67 (4.1%) stddev-sans-first:3.87 23:08:31 INFO - PID 2007 | Values: 89.0 89.0 88.0 89.0 91.0 100.0 88.0 88.0 89.0 88.0 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | [#6] imaging-darkroom Cycles:10 Average:99.40 Median:96.00 stddev:5.48 (5.7%) stddev-sans-first:5.67 23:08:31 INFO - PID 2007 | Values: 96.0 95.0 97.0 99.0 111.0 95.0 96.0 96.0 106.0 103.0 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | [#7] imaging-desaturate Cycles:10 Average:91.60 Median:90.50 stddev:4.43 (4.9%) stddev-sans-first:4.66 23:08:31 INFO - PID 2007 | Values: 90.0 96.0 87.0 91.0 101.0 89.0 90.0 93.0 93.0 86.0 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | [#8] json-parse-financial Cycles:10 Average:48.90 Median:49.50 stddev:1.60 (3.2%) stddev-sans-first:1.64 23:08:31 INFO - PID 2007 | Values: 50.0 49.0 47.0 51.0 49.0 47.0 47.0 50.0 48.0 51.0 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:60.00 stddev:5.42 (9.0%) stddev-sans-first:5.67 23:08:31 INFO - PID 2007 | Values: 59.0 64.0 57.0 59.0 71.0 60.0 60.0 57.0 57.0 71.0 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:83.00 stddev:1.73 (2.1%) stddev-sans-first:1.48 23:08:31 INFO - PID 2007 | Values: 86.0 80.0 83.0 83.0 83.0 81.0 83.0 84.0 85.0 83.0 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | [#11] stanford-crypto-ccm Cycles:10 Average:151.60 Median:151.50 stddev:20.22 (13.3%) stddev-sans-first:20.84 23:08:31 INFO - PID 2007 | Values: 138.0 154.0 127.0 151.0 160.0 136.0 149.0 152.0 202.0 147.0 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.30 Median:200.50 stddev:27.02 (13.5%) stddev-sans-first:24.54 23:08:31 INFO - PID 2007 | Values: 241.0 193.0 185.0 208.0 181.0 165.0 239.0 231.0 190.0 180.0 23:08:31 INFO - PID 2007 | 23:08:31 INFO - PID 2007 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:1.77 (2.6%) stddev-sans-first:1.87 23:08:31 INFO - PID 2007 | Values: 67.0 68.0 70.0 65.0 66.0 68.0 66.0 69.0 69.0 65.0 23:08:31 INFO - PID 2007 | -------- Summary: end -------- 23:08:31 INFO - PID 2007 | 23:08:32 INFO - TEST-INFO | 2007: exit 0 23:08:32 INFO - TEST-OK | kraken | took 61003ms 23:08:32 INFO - SUITE-END | took 1175s 23:08:32 INFO - Completed test suite (00:19:35) 23:08:32 INFO - javascript benchmark 23:08:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7855.956537074497, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3576.695980841628, 3597.730170237232, 3611.9638803611956, 3625.3111908737337, 3634.9091272718188, 6082.178905847709, 6168.136460895473, 6233.470155036822, 6239.7816076437275, 6294.527910406727, 6146.8628303763835, 6150.938490615093, 6210.62736235825, 6254.030625253093, 6294.685265736706, 5229.686218826882, 5343.385510666265, 5422.75597598109, 5427.0, 5558.610897237195, 5498.257735205756, 5523.889522209553, 5536.141898005815, 5578.888422231553, 5602.327720673525, 5288.418273989858, 5393.946060539383, 5454.209139674765, 5461.972690136524, 5535.501804837564, 5439.075357189287, 5448.809291674773, 5458.9454105458835, 5497.835064948058, 5510.366307874566, 4598.896264896401, 5291.550218231455, 5388.892222155574, 5409.377921538995, 5424.674519528841, 4782.689125206885, 6027.09593560965, 6275.6862156891975, 6277.18396608438, 6318.178636777236, 6391.58454700443, 6438.130852334922, 6638.0, 6666.400023997815, 6674.0, 6058.909116363258, 6745.561538500031, 6780.762673306411, 6826.658667066627, 6833.521653484258, 5880.353161152269, 5996.940030599725, 6305.684715764193, 6329.651869147195, 6366.77716279928, 5575.247341608912, 6646.966765166143, 6873.450123990069, 6997.825054373681, 7002.0, 4981.4769449207315, 6162.168107305546, 6576.5067619928695, 6608.7356505739235, 6620.635865027373, 6720.092787473726, 6762.256151823295, 6770.69531871067, 6776.966115169393, 6782.355676210752, 5496.4503549645515, 6377.0, 6451.258105317948, 6598.10925525048, 6642.1033160523675, 6628.403443690091, 6641.833954151185, 6686.598804071771, 6692.230393504712, 6725.461963042945, 5329.573634109262, 5347.919781203285, 5348.812791552355, 5364.517193452608, 5387.218853266333, 4675.649326300541, 4787.736674482937, 4793.640476964241, 4828.830990915371, 4829.116644508645, 5038.974805125951, 6272.655003974825, 6315.778947736899, 6317.526185536103, 6347.174867267226, 6814.761483348059, 6821.0, 6822.385985261251, 6840.281770414035, 6840.6921688524135, 6384.648844313608, 6390.137331460286, 6398.5521013528605, 6404.135441715309, 6411.5832470889245, 5432.592555558349, 5546.944530554723, 5763.0, 5810.448007439287, 5832.533397328203, 5825.951328760863, 5998.790042348497, 6867.793966181022, 6973.267806880204, 7081.575105493687, 6517.185351831021, 6589.835254118494, 6721.361470660279, 6783.559068660521, 6826.180858296838, 6977.372036516738, 7024.473164512784, 7035.613041282779, 7055.3650171484815, 7095.858082838211, 6365.713542890674, 6418.807435776934, 6651.43462805667, 6665.933340666531, 6691.364320389555, 6557.377049180321, 6683.164604424447, 6710.396064354231, 6744.898826517704, 6775.966120169368, 6464.41820236181, 6577.408033277028, 6584.111144995364, 6588.538802283794, 6600.141981542369, 5847.883042339215, 6538.117354157318, 6567.27759946396, 6568.573042752206, 6577.901331479936, 5572.721363931868, 6050.576459647782, 6326.936730632635, 6356.364363563606, 6431.1317972073175, 6456.935430645822, 6520.18497687789, 6524.0, 6562.704678289393, 6565.212174539086, 6356.682165891594, 6400.487960963205, 6400.967995159995, 6408.0, 6417.197850268716], "value": 6064.36742110202, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.4895501806171, 1213.2114125818216, 1269.0862578943163, 1287.7167023254892, 1293.1917551530294, 37459.81270093653, 38204.04489887754, 38261.8086909565, 38373.0, 38439.0, 38003.61996380035, 38389.232215355674, 38408.42387364185, 38574.84275471741, 38611.80694096525, 7140.571565706075, 7193.848984162535, 8490.40567160299, 8491.23578877901, 8517.148285171495, 8457.112003239663, 8532.402731808776, 8560.229579337858, 8582.14178582143, 8593.527355995418, 8529.872051919227, 8550.572471376438, 8579.142085791433, 8579.184977427134, 8622.698205562776, 8532.189442003031, 8533.189347012023, 8550.657973681045, 8563.914360856435, 8564.100769419247, 7024.297570242986, 8330.083690794008, 8340.999080110425, 8360.122187170382, 8408.576468178053, 37848.62151378506, 37958.051048724, 38030.42954355687, 38053.0486737831, 38068.0, 3677.8161091945562, 3718.1262403335463, 3720.5535335759882, 3735.869244576427, 3875.6124387561017, 31109.0, 31115.910943116884, 31116.688833111606, 31142.844285778654, 31239.843800781076, 3025.7579393648675, 3047.6495203051713, 3054.205906464313, 3073.861676224575, 3078.5228289615147, 249178.75410623013, 306909.0, 307806.0, 308680.0, 310491.4475427631, 39257.41113883294, 48037.75981120072, 48468.78828029257, 48600.27099593509, 48712.5128748708, 50803.23795143076, 51044.97910041778, 51112.233316500286, 51244.48755512472, 51280.743596281776, 14121.870250379945, 14159.008869379251, 14268.500602478864, 14283.500077497238, 14290.928180386512, 8936.41913275635, 10342.276040677081, 11240.887591124148, 11389.715257118472, 11710.70723231909, 13748.65628359299, 13759.17444953286, 13761.862381376259, 13782.65543361404, 13839.654008649864, 5200.401953775364, 6094.0, 6111.327753947062, 6127.816165514952, 6212.409821067082, 1015.6089905386332, 1021.8109649714678, 1021.9233557483218, 1046.8168070587617, 1060.9469526523642, 23033.539329213323, 23623.88188059049, 24891.875540622183, 24900.003999840166, 24905.128320508695, 1110.74452875839, 1117.3072694929197, 1140.8003599370077, 1151.83874257604, 1156.8264760285856, 10989.602544037272, 12445.688857778629, 12728.490860365668, 12735.981121510256, 12882.80675789864, 273265.0, 317450.0, 317575.0, 319287.4035629807, 319642.0, 54358.45641543613, 54707.17939230835, 54799.178012329845, 54970.72514637481, 55030.44969550333, 1497.0568541818584, 1505.1495904813746, 1525.7558790593673, 1544.9227538623024, 1559.5009596928874, 22957.196498122485, 24329.27012189637, 24337.51324973455, 24422.389440264134, 24484.51030979335, 38436.80781596074, 38524.03689907663, 38599.807000964815, 38617.0, 38620.6137938617, 279017.0, 282519.0, 283004.0, 283095.0, 283148.0, 32909.34181316408, 34229.828850855585, 34513.0, 34690.65309346975, 34719.0, 43463.9134021652, 44147.77926110349, 45511.772441138906, 45530.54469455263, 45694.771526142154, 23997.880010599834, 30009.549856751728, 30064.0, 30104.397912041197, 31623.84188079045, 25306.493870122864, 30765.692343076284, 31216.063518094492, 31729.5240571387, 32108.19729506781, 32101.67898320987, 35995.92012239637, 38042.80978595089, 38071.809640951615, 41762.791186043876, 26377.0, 29829.359385233154, 30747.077587672407, 30894.0, 31097.0, 22484.325470235264, 23765.762342376354, 24607.015719371026, 24653.89057492381, 24849.50300994006], "value": 17610.721109248865, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.18518259758656, 441.13317331443704, 443.74706417342105, 449.23629829290195, 461.20442237140924, 19465.610687786255, 21319.57360852782, 21768.564628707456, 21805.454863628416, 21988.120475180975, 18836.34072807452, 21701.131954721794, 22405.66391504125, 22469.43826404341, 22810.65784013241, 6048.0625503047095, 6294.779682711106, 6297.527685423589, 6298.370162983688, 6317.5893566918, 5985.910211346833, 5989.341172471025, 6083.543734219929, 6096.72564734588, 6173.475254603364, 5931.762729490815, 6100.694965251741, 6114.510839132859, 6118.724657390429, 6126.142340072393, 5462.20797984294, 6126.6017708848785, 6178.7219575119225, 6216.533759968021, 6245.0, 5261.86845328866, 5295.523402893758, 5377.677339359651, 5504.669719816784, 5510.1459273812625, 21266.5746685067, 25672.0, 26196.476070478675, 26330.86834565815, 26517.602235966475, 2167.4581354661336, 2257.672637467558, 2258.7063681721434, 2259.3109101723967, 2261.8303627228024, 17149.742753858704, 17165.485035448957, 17585.7362139568, 17703.468895933143, 17770.555736106573, 2090.571432856261, 2187.409399462152, 2222.377734234417, 2222.4221702357504, 2230.063373383183, 157745.0, 160791.0, 162223.0, 162585.18707406506, 167176.0, 12187.512499500099, 12371.75256494865, 12609.747805043848, 12693.682657933625, 12786.360681965865, 16147.919260403858, 16206.432774852823, 16210.513684589481, 16243.106629135511, 16279.755803662954, 7574.924250757422, 7599.772006839804, 8734.91265087341, 8855.73432797017, 9074.092590740873, 8621.69824056155, 8641.524716140673, 8658.220760131619, 8736.170063843925, 8775.297976161892, 7190.0, 7731.458797884206, 7847.332976696931, 7861.213878612208, 7896.249856263646, 2222.6666000100113, 2239.6528538076623, 2245.6743772152945, 2251.7973382395335, 2404.9158279460135, 815.9877601835977, 831.3640065350012, 958.6692591056075, 974.9756256093513, 990.8209231015213, 17968.730469042974, 20595.279165229444, 20667.483312916997, 20687.58624827495, 21020.684689729667, 861.6811779641573, 990.1979396688705, 994.9154321882577, 1015.167562598662, 1033.7002269341956, 10305.175585953255, 10632.096271816983, 10653.467326633638, 10709.62516311941, 10762.92780414689, 531969.1227789518, 544040.0, 554266.0, 564717.0, 565698.0, 124818.0, 128953.0, 130700.0, 131971.34014329867, 134729.32635336957, 1198.9460474278678, 1237.1649136832602, 1241.6399244219256, 1257.283348491351, 1258.025030601273, 18065.458036258933, 20429.69355459699, 21192.026624348917, 21609.567808644053, 21666.89166554157, 45036.77481612571, 57919.71040144772, 58022.4197758017, 58824.41175588189, 58975.0, 81208.59395702984, 81257.78113328187, 81390.59304703439, 81457.0, 81581.59209203917, 8654.350923680875, 8676.956615216883, 9044.14080662336, 9048.864267036131, 12556.748865022832, 12274.263544187377, 13416.262105584286, 13482.0, 14035.719285614436, 14176.007679462338, 11756.529738810694, 11880.46537905779, 11911.583094591648, 12211.75576488483, 12216.022718182701, 10658.040776330168, 10814.405207713651, 10847.294925829796, 11136.610218642309, 11204.383758893338, 11028.28316159447, 11933.0, 12024.579139730067, 12294.508219671114, 12363.381830908238, 12892.226466412045, 12892.548760793326, 13068.0, 13095.476180953036, 13417.865821342051, 11899.04807615406, 12003.699907502032, 12206.6948326289, 12232.75534489323, 12260.816087758869], "value": 10561.683955572607, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.0988167572752, 1451.8983671143017, 1465.1794994802917, 1470.125275461101, 1482.236648126215, 10043.19654427646, 11497.022753065989, 11611.593594224203, 11825.822612660817, 12067.034637229022, 10148.42699381597, 11631.069514438846, 11785.469653865575, 11805.940970295158, 11818.05455563555, 2493.8379005364595, 2559.5008973250224, 2572.2026171886682, 2577.9226623201334, 2590.5596048671773, 2491.2277194069807, 2552.247087109298, 2562.1032638576535, 2566.0762125634756, 2567.319660290025, 2501.949961000779, 2518.445941892781, 2544.414784599543, 2554.501872134925, 2555.246202370305, 2463.08865719684, 2478.938026549342, 2515.987420062888, 2516.3709072731817, 2529.0768869362732, 2419.3346829621996, 2499.1627804685454, 2524.6591710119083, 2533.050106210171, 2533.316004678745, 11931.403429828473, 11979.820302695467, 11988.0, 12005.0, 12074.758504829853, 6595.703193356262, 6806.251312355637, 6850.38346548808, 6858.279880612564, 6859.451243900476, 3972.781497017663, 4276.935845962313, 4339.804708788112, 4348.173846969097, 4358.2808836541935, 1237.307108019512, 1247.2890452442111, 1265.013289634087, 1272.8854403103674, 1310.7247478029626, 15704.293306801108, 16191.676166476604, 16777.916110419494, 16925.23836427364, 16945.91527042369, 5516.227728118067, 5609.80365687199, 5692.601517893708, 5764.855878603068, 5802.65184088956, 11501.194916355942, 11616.838316168485, 11774.88225117755, 11826.231294965799, 11845.881541184477, 6569.638669873108, 6597.47220222381, 6601.3068627794355, 6606.0, 6650.501212409089, 6072.453479186895, 6096.329403765582, 6225.750969961151, 6233.594816336924, 6268.09112678659, 3405.8297085145646, 4192.329227323614, 4360.127974405132, 4362.520122786491, 4409.757463339547, 1065.2969040433431, 1255.7174635706892, 1262.9179103358251, 1270.7585558743808, 1273.4842388832537, 775.9030121234846, 795.431266644352, 802.4583406200908, 827.6027506796771, 846.9026062002948, 6171.7531298748445, 6222.12890195373, 6423.903641445382, 6444.097826304227, 6511.0, 588.9146073819272, 599.9190109335184, 600.3726106219011, 607.3471018654875, 612.9295131059896, 3868.0, 3937.448757173998, 4026.2148880968516, 4205.537390887, 4290.313549832012, 14451.927740361441, 14792.408303667948, 14871.107733535806, 14931.477398291008, 14932.552023439313, 14353.0, 14611.926940365443, 14860.554183374516, 15181.468648597245, 15305.234738263265, 7576.24237576249, 7811.789172678244, 8381.287590554872, 8668.133186681407, 8780.560971951378, 6122.693865306628, 6861.4510839133745, 6995.125609298838, 7013.824654383681, 7087.858242835217, 12140.635780926586, 12454.820406428658, 13575.456981720621, 13977.790333145214, 14036.929815350857, 13419.26194059297, 13491.392887320291, 13547.322633868464, 13560.91512679003, 13681.110727802661, 8237.423380363556, 8282.296004839414, 8294.129116442928, 8304.128066553167, 8312.584370781558, 10244.282900196915, 10510.894891051297, 10592.576296948037, 10674.46627668629, 10777.08394786452, 9028.683996060106, 9033.096690331176, 9073.410228335137, 9096.135867092615, 9123.863142053007, 7399.889001664872, 7459.738909138154, 8924.464532128093, 8928.776780580538, 8966.148215919477, 7701.88447173304, 8032.196780322155, 8083.757487275391, 8084.272415482635, 8091.433599648203, 5244.7902083916215, 5257.8159764408065, 5959.22530071088, 6019.849503762441, 6153.169322141453, 5274.920876186783, 5501.569591906054, 6033.758649653964, 6078.756849725962, 6130.846728831815], "value": 5270.2074981109645, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.3172759298625, 380.4407520944212, 383.2660455228238, 408.2671604469976, 415.646700304741, 5967.4927631151295, 6162.445379915806, 6174.9073763893575, 6325.557210995226, 6326.165412030506, 5866.325372582154, 6033.849153771147, 6168.84577885552, 6171.858206231839, 6181.876362472748, 3702.296563652904, 3982.362821948489, 4491.910161796762, 4497.92049908023, 4524.0, 4478.977605111986, 4483.148201841656, 4491.6182124519455, 4497.70764900282, 4508.098380323932, 4442.489113751928, 4457.0, 4471.150481408522, 4474.105178964204, 4522.570355816209, 4240.151969606091, 4272.4325188467665, 4400.8019639116, 4442.267025940716, 4449.933251001222, 3768.208676178006, 3798.620137986207, 3805.6574908258112, 3809.9238015239816, 3825.2923209206388, 4765.451973023112, 5229.50319719626, 6018.007028840236, 6060.96969515154, 6061.242344706911, 4402.845900393503, 5226.8693282667855, 5262.184361423947, 5287.233351164098, 5337.412884582693, 5716.456936591017, 5808.88382232353, 6625.933740662628, 6632.63520506372, 6656.966715166442, 4224.598663126998, 4305.138972205571, 4306.763128027988, 4309.482862056542, 4333.241682705514, 6701.497387695894, 6903.0, 7017.859642807115, 7063.964680176618, 7082.291770822928, 4335.176316499855, 4356.695031347838, 4361.934570981438, 4367.192069467159, 4371.0, 4429.512753597101, 4441.113276370385, 4442.71161363201, 4449.421575195204, 4463.977680111579, 4853.417589889165, 5129.7178655173575, 5192.714400707921, 5267.0, 5275.654708049499, 6118.357572454917, 6293.143522660735, 6599.8020059398295, 6614.503912206507, 6629.414528333954, 1343.9529616463572, 1347.3734713358335, 1494.290212149242, 1507.329238488884, 1519.7188520123811, 956.8469044952774, 1002.4686915934566, 1171.5196769324707, 1199.4362649554696, 1204.175140029083, 5310.628256022119, 6322.620642761449, 6338.397852204033, 6351.714172862232, 6368.713407896563, 6478.773242936569, 6768.83077923046, 6938.271481494471, 6965.895511567331, 6985.476089293324, 5900.430239705002, 6191.288001879751, 6273.7176827042895, 6474.902876456857, 6498.350164983463, 2955.2120966815196, 3203.8718451262152, 3516.279162771677, 3634.200475895299, 3644.815434983641, 6946.409555187865, 7116.288371162946, 7182.569045857265, 7250.963745181346, 7286.453515986323, 6847.828804279833, 7051.330123638246, 7136.180274030089, 7142.250148713599, 7153.177384600734, 6029.8191054268445, 7219.963900180571, 7240.456965727486, 7250.347468727839, 7256.963715181496, 6717.395434410926, 6823.2024869803145, 6926.203486598904, 6944.236134025355, 6950.721971121098, 5401.810936617357, 5705.34388545306, 6455.548111632141, 6810.148731408574, 6848.417884479676, 6246.156768836149, 6988.846840271298, 7042.6126563039525, 7057.306246500905, 7202.351788339075, 3694.3350196964802, 4345.34819777036, 4365.017870979132, 4393.0774537347515, 4413.955860441354, 5553.583481239004, 5994.790182343597, 6593.901091483535, 6707.618795327784, 6745.224299205458, 5876.853078673067, 6007.609505382136, 6201.844953876189, 6656.866862662817, 6672.633005184762, 5407.756650950795, 5542.528885044655, 5781.756922261734, 6393.4885209184085, 6686.83282917931, 5593.272874526286, 5731.484166425042, 5912.852178695567, 6572.605643661204, 6575.704093315908, 5777.826665200051, 6374.394432528902, 6438.742450301936, 6708.6981085850275, 6741.764038258833, 6542.411182993554, 6550.770723024862, 6565.671716414256, 6567.441767449629, 6595.934040659532], "value": 4772.776162030394, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [852.3905407633548, 856.9614367353469, 863.956802159892, 877.8719345640849, 887.4453466583383, 16128.596785080379, 16245.106519141456, 16499.175041247934, 17892.731609025876, 18984.145713442893, 14667.77998330026, 17607.29570817166, 17905.925644461342, 17952.0, 18387.63224735508, 987.6888780034302, 1000.6647772996055, 1008.9798204035915, 1013.5945621751284, 1014.7818219082895, 930.4231376546551, 939.2955283537347, 993.7018894331708, 1019.5463018956567, 1039.490649581707, 1025.692292312307, 1060.151878497203, 1072.8658917635296, 1077.4774234496263, 1078.309881675728, 995.9452230127304, 1061.405612856795, 1067.9374022847278, 1070.925035247533, 1071.2287153249686, 778.7624774443792, 795.1531618825932, 795.1650766695005, 801.7955421367573, 806.822499050208, 16297.185140743033, 16604.66790664192, 16713.749293760604, 16827.158642067847, 16891.493255202364, 16159.596010099842, 16376.508704738877, 16874.0, 16966.83033169665, 17121.743173852403, 13320.600381988555, 13407.262600556965, 13542.86457135426, 13548.390322435514, 13693.109947853358, 3366.2762506061313, 3380.6112297085906, 3393.677600627961, 3398.2693720850134, 3404.0808981575083, 128590.0, 129498.35250823686, 130258.0, 131291.34354328262, 131515.0, 12879.356032198353, 13059.738805223842, 13145.53990610324, 13238.867611323763, 13306.600801975956, 23514.0, 24124.155654552007, 24505.0, 24914.875425622755, 25088.996440142553, 7251.637418129178, 7405.296496832792, 7417.0, 7455.105387353552, 7468.0, 3347.73218142548, 3692.8892133236045, 3831.6743076838225, 3911.7261791674878, 3913.256481268607, 4009.1781184857036, 4191.7484950902435, 4314.374415709767, 4320.006398528356, 4324.783760811947, 919.9218066464425, 947.8436058050345, 953.2326477185793, 962.5716556132594, 964.1226483899618, 539.9956081688057, 587.9470847623649, 644.8355669304298, 650.3658932540735, 654.1528720307214, 10236.0, 12199.817002744965, 12300.446479908425, 12428.0, 13419.865801342057, 658.8188248231775, 663.3466035954581, 673.0980486148584, 679.7009315900988, 701.431840209432, 5404.648697834628, 5458.8478080771565, 5655.321361436571, 5696.0, 5774.538036957033, 69255.92232233041, 98846.0, 99632.0, 102710.48644756728, 103233.0, 86737.83155421038, 92732.53633731788, 92870.0, 94405.11189776304, 96143.0385696148, 85172.14827851643, 85792.57103714441, 85908.57045714857, 86390.56804715937, 87991.0, 8543.783789150913, 9699.175570076623, 9903.960084191056, 10522.158227341948, 10658.307210031324, 41335.586644134375, 41510.60362152901, 41899.7905010473, 42483.0, 42815.571844281156, 41349.75950721483, 54073.0, 54668.72665636647, 54718.0, 55159.17261241165, 13888.444462221802, 14152.929235353757, 15462.690746185239, 16045.679086417973, 16156.0, 17006.0, 17657.0, 18028.909855450638, 18178.0, 19043.333483328017, 15280.0, 16582.336706532085, 16841.0, 16925.492235232963, 17107.401240956508, 16201.43294984717, 16399.91800040992, 16964.745528817326, 17046.57383565371, 17057.573560661083, 15641.608959776097, 15732.449364272195, 16550.006999580062, 16560.337586496404, 16633.584160396087, 11812.881871181522, 11863.940680296544, 11937.940310298392, 11977.520899163936, 12038.277703337828, 10779.407132607781, 12896.806547901977, 13016.0, 13064.673383165496, 13175.736485270048], "value": 8284.5385311687, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 124.0, 115.0, 112.0, 114.0, 116.0, 115.0, 114.0, 118.0, 123.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 107.0, 110.0, 148.0, 119.0, 115.0, 111.0, 109.0, 150.0, 143.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [256.0, 251.0, 271.0, 227.0, 240.0, 229.0, 196.0, 278.0, 208.0, 276.0], "value": 243.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 59.0, 107.0, 88.0, 51.0, 72.0, 89.0, 91.0, 47.0], "value": 74.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 69.0, 83.0, 70.0, 69.0, 69.0, 85.0, 72.0, 72.0, 97.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 88.0, 89.0, 91.0, 100.0, 88.0, 88.0, 89.0, 88.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 97.0, 99.0, 111.0, 95.0, 96.0, 96.0, 106.0, 103.0], "value": 99.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 96.0, 87.0, 91.0, 101.0, 89.0, 90.0, 93.0, 93.0, 86.0], "value": 91.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 47.0, 51.0, 49.0, 47.0, 47.0, 50.0, 48.0, 51.0], "value": 48.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 57.0, 59.0, 71.0, 60.0, 60.0, 57.0, 57.0, 71.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 80.0, 83.0, 83.0, 83.0, 81.0, 83.0, 84.0, 85.0, 83.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 154.0, 127.0, 151.0, 160.0, 136.0, 149.0, 152.0, 202.0, 147.0], "value": 151.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [241.0, 193.0, 185.0, 208.0, 181.0, 165.0, 239.0, 231.0, 190.0, 180.0], "value": 201.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 65.0, 66.0, 68.0, 66.0, 69.0, 69.0, 65.0], "value": 67.3, "unit": "score"}]}]} 23:08:32 INFO - Return code: 0 23:08:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:08:32 ERROR - # TBPL SUCCESS # 23:08:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:08:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:08:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:08:32 INFO - 2017-04-26 23:08:32.808 screenresolution[2012:18709] starting screenresolution argv=screenresolution get 23:08:32 INFO - 2017-04-26 23:08:32.813 screenresolution[2012:18709] Display 0: 1600x1200x32@60 23:08:32 INFO - 2017-04-26 23:08:32.820 screenresolution[2013:18711] starting screenresolution argv=screenresolution list 23:08:32 INFO - Available Modes on Display 0 23:08:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:08:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:08:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:08:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:08:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:08:33 INFO - Intel Iris: 23:08:33 INFO - Chipset Model: Intel Iris 23:08:33 INFO - Type: GPU 23:08:33 INFO - Bus: Built-In 23:08:33 INFO - VRAM (Dynamic, Max): 1536 MB 23:08:33 INFO - Vendor: Intel (0x8086) 23:08:33 INFO - Device ID: 0x0a2e 23:08:33 INFO - Revision ID: 0x0009 23:08:33 INFO - Displays: 23:08:33 INFO - Display: 23:08:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:08:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:08:33 INFO - Display Serial Number: 1600x1200 60 23:08:33 INFO - Main Display: Yes 23:08:33 INFO - Mirror: Off 23:08:33 INFO - Online: Yes 23:08:33 INFO - Rotation: Supported 23:08:33 INFO - Return code: 0 23:08:33 INFO - Running post-action listener: _resource_record_post_action 23:08:33 INFO - [mozharness: 2017-04-27 06:08:33.069753Z] Finished run-tests step (success) 23:08:33 INFO - Running post-run listener: _resource_record_post_run 23:08:33 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 839680; Write bytes: 513755136; Read time: 9; Write time: 2062 23:08:33 INFO - TinderboxPrint: CPU usage
54.4% 23:08:33 INFO - TinderboxPrint: I/O read bytes / time
839,680 / 9 23:08:33 INFO - TinderboxPrint: I/O write bytes / time
513,755,136 / 2,062 23:08:33 INFO - TinderboxPrint: CPU idle
2,179.9 (45.6%) 23:08:33 INFO - TinderboxPrint: CPU system
964.8 (20.2%) 23:08:33 INFO - TinderboxPrint: CPU user
1,640.9 (34.3%) 23:08:33 INFO - TinderboxPrint: Swap in / out
209,674,240 / 0 23:08:33 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 193810944; Write bytes: 192708608; Read time: 15058; Write time: 372 23:08:33 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 839680; Write bytes: 304207872; Read time: 9; Write time: 1640 23:08:33 INFO - Running post-run listener: _upload_blobber_files 23:08:33 INFO - Blob upload gear active. 23:08:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:08:33 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 23:08:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:08:33 INFO - (blobuploader) - INFO - Open directory for files ... 23:08:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:08:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:08:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:34 INFO - (blobuploader) - INFO - Done attempting. 23:08:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:08:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:08:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:36 INFO - (blobuploader) - INFO - Done attempting. 23:08:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:08:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:08:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:37 INFO - (blobuploader) - INFO - Done attempting. 23:08:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:08:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:08:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:39 INFO - (blobuploader) - INFO - Done attempting. 23:08:39 INFO - (blobuploader) - INFO - Iteration through files over. 23:08:39 INFO - Return code: 0 23:08:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:08:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:08:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ca4336b48417dde35552f1c360f6a4594885f78f3a63412f01caf51a30dc8a741e731dd7b5a96ae25529694d92473b93d613167e35873d5a86160cace337bb2f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/41cf5581ec8a9dc4bd740ff5a23f5e071061daa5cbae7f63d0f434cd9cd3288ff30ff271226b8b36700b7bf29f7de20b217909c870342d7dbecbe0b0b2c0fb5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/db3423f37ea003697a338813f29dd450d3a0e9584e97f5a7e8c0bd39d6f2aee07bce291afda3362343d784d75c1d38ccd2499795a34b44100de27ffc9376c01e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a48b924ee524c57de026e16e2585a1de798d2b86ddabd10d4eb1e5917cbc1de3ee0c71ac715daf3d84f23b4a32b0b137e24a3ed088d15e6fb04c065b4c3ba9a4"} 23:08:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:08:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:08:39 INFO - Contents: 23:08:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ca4336b48417dde35552f1c360f6a4594885f78f3a63412f01caf51a30dc8a741e731dd7b5a96ae25529694d92473b93d613167e35873d5a86160cace337bb2f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/41cf5581ec8a9dc4bd740ff5a23f5e071061daa5cbae7f63d0f434cd9cd3288ff30ff271226b8b36700b7bf29f7de20b217909c870342d7dbecbe0b0b2c0fb5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/db3423f37ea003697a338813f29dd450d3a0e9584e97f5a7e8c0bd39d6f2aee07bce291afda3362343d784d75c1d38ccd2499795a34b44100de27ffc9376c01e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a48b924ee524c57de026e16e2585a1de798d2b86ddabd10d4eb1e5917cbc1de3ee0c71ac715daf3d84f23b4a32b0b137e24a3ed088d15e6fb04c065b4c3ba9a4"} 23:08:39 INFO - Running post-run listener: copy_logs_to_upload_dir 23:08:39 INFO - Copying logs to upload dir... 23:08:39 INFO - mkdir: /builds/slave/test/build/upload/logs 23:08:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.198494 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-04-26 23:08:39.804044) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-26 23:08:39.807802) ========= 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.4ciypBurcv/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.FzW4zNZXrE/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ca4336b48417dde35552f1c360f6a4594885f78f3a63412f01caf51a30dc8a741e731dd7b5a96ae25529694d92473b93d613167e35873d5a86160cace337bb2f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/41cf5581ec8a9dc4bd740ff5a23f5e071061daa5cbae7f63d0f434cd9cd3288ff30ff271226b8b36700b7bf29f7de20b217909c870342d7dbecbe0b0b2c0fb5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/db3423f37ea003697a338813f29dd450d3a0e9584e97f5a7e8c0bd39d6f2aee07bce291afda3362343d784d75c1d38ccd2499795a34b44100de27ffc9376c01e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a48b924ee524c57de026e16e2585a1de798d2b86ddabd10d4eb1e5917cbc1de3ee0c71ac715daf3d84f23b4a32b0b137e24a3ed088d15e6fb04c065b4c3ba9a4"} build_url:https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011173 build_url: 'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ca4336b48417dde35552f1c360f6a4594885f78f3a63412f01caf51a30dc8a741e731dd7b5a96ae25529694d92473b93d613167e35873d5a86160cace337bb2f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/41cf5581ec8a9dc4bd740ff5a23f5e071061daa5cbae7f63d0f434cd9cd3288ff30ff271226b8b36700b7bf29f7de20b217909c870342d7dbecbe0b0b2c0fb5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/db3423f37ea003697a338813f29dd450d3a0e9584e97f5a7e8c0bd39d6f2aee07bce291afda3362343d784d75c1d38ccd2499795a34b44100de27ffc9376c01e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a48b924ee524c57de026e16e2585a1de798d2b86ddabd10d4eb1e5917cbc1de3ee0c71ac715daf3d84f23b4a32b0b137e24a3ed088d15e6fb04c065b4c3ba9a4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-26 23:08:39.844108) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 23:08:39.846862) ========= 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.4ciypBurcv/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.FzW4zNZXrE/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.005270 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 23:08:39.895052) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-26 23:08:39.896166) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-26 23:08:39.896483) ========= ========= Total master_lag: 0.20 =========