builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0134 starttime: 1491842630.31 results: success (0) buildid: 20170410083550 builduid: e4729175b0c14233895009d0ed2df458 revision: 718da6d0b0bc7e97a93061e19b71ec157e7b51a9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 09:43:50.306862) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 09:43:50.307267) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 09:43:50.307531) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 09:43:50.333929) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 09:43:50.334229) ========= 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.yVePuqgP8F/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.cx7CHNroDC/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-10 09:43:50-- 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% 9.83M=0.001s 2017-04-10 09:43:50 (9.83 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.271506 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 09:43:50.734324) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 09:43:50.734642) ========= 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.yVePuqgP8F/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.cx7CHNroDC/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.028941 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 09:43:50.786135) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 09:43:50.786488) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 718da6d0b0bc7e97a93061e19b71ec157e7b51a9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 718da6d0b0bc7e97a93061e19b71ec157e7b51a9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yVePuqgP8F/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.cx7CHNroDC/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-10 09:43:51,204 truncating revision to first 12 chars 2017-04-10 09:43:51,204 Setting DEBUG logging. 2017-04-10 09:43:51,204 attempt 1/10 2017-04-10 09:43:51,204 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/718da6d0b0bc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-10 09:43:51,966 unpacking tar archive at: mozilla-aurora-718da6d0b0bc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.036966 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 09:43:51.847280) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 09:43:51.847607) ========= script_repo_revision: 718da6d0b0bc7e97a93061e19b71ec157e7b51a9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 09:43:51.848005) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 09:43:51.848308) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 09:43:51.867766) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-10 09:43:51.868106) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yVePuqgP8F/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.cx7CHNroDC/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:43:52 INFO - MultiFileLogger online at 20170410 09:43:52 in /builds/slave/test 09:43:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:43:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:43:52 INFO - {'append_to_log': False, 09:43:52 INFO - 'base_work_dir': '/builds/slave/test', 09:43:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:43:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:43:52 INFO - 'branch': 'Mozilla-Aurora', 09:43:52 INFO - 'buildbot_json_path': 'buildprops.json', 09:43:52 INFO - 'config_files': ('talos/mac_config.py',), 09:43:52 INFO - 'default_actions': ('clobber', 09:43:52 INFO - 'read-buildbot-config', 09:43:52 INFO - 'download-and-extract', 09:43:52 INFO - 'populate-webroot', 09:43:52 INFO - 'create-virtualenv', 09:43:52 INFO - 'install', 09:43:52 INFO - 'run-tests'), 09:43:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:43:52 INFO - 'download_minidump_stackwalk': True, 09:43:52 INFO - 'download_symbols': 'ondemand', 09:43:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:43:52 INFO - 'tooltool.py': '/tools/tooltool.py', 09:43:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:43:52 INFO - '/tools/misc-python/virtualenv.py')}, 09:43:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:43:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:43:52 INFO - 'gecko_profile': False, 09:43:52 INFO - 'gecko_profile_interval': 0, 09:43:52 INFO - 'installer_path': 'installer.exe', 09:43:52 INFO - 'log_level': 'info', 09:43:52 INFO - 'log_name': 'talos', 09:43:52 INFO - 'log_to_console': True, 09:43:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:43:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:43:52 INFO - 'opt_config_files': (), 09:43:52 INFO - 'pip_index': False, 09:43:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:43:52 INFO - 'cmd': ('bash', 09:43:52 INFO - '-c', 09:43:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:43:52 INFO - 'enabled': True, 09:43:52 INFO - 'halt_on_failure': False, 09:43:52 INFO - 'name': 'check_screen_resolution'},), 09:43:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:43:52 INFO - 'cmd': ('bash', 09:43:52 INFO - '-c', 09:43:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:43:52 INFO - 'enabled': True, 09:43:52 INFO - 'halt_on_failure': False, 09:43:52 INFO - 'name': 'check_screen_resolution'},), 09:43:52 INFO - 'run_cmd_checks_enabled': True, 09:43:52 INFO - 'suite': 'dromaeojs', 09:43:52 INFO - 'system_bits': '32', 09:43:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:43:52 INFO - 'title': 't-yosemite-r7-0134', 09:43:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:43:52 INFO - 'use_talos_json': True, 09:43:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:43:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:43:52 INFO - 'work_dir': 'build'} 09:43:52 INFO - [mozharness: 2017-04-10 16:43:52.399301Z] Running clobber step. 09:43:52 INFO - Running pre-action listener: _resource_record_pre_action 09:43:52 INFO - Running main action method: clobber 09:43:52 INFO - rmtree: /builds/slave/test/build 09:43:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:43:54 INFO - Running post-action listener: _resource_record_post_action 09:43:54 INFO - [mozharness: 2017-04-10 16:43:54.933234Z] Finished clobber step (success) 09:43:54 INFO - [mozharness: 2017-04-10 16:43:54.933355Z] Running read-buildbot-config step. 09:43:54 INFO - Running pre-action listener: _resource_record_pre_action 09:43:54 INFO - Running main action method: read_buildbot_config 09:43:54 INFO - Using buildbot properties: 09:43:54 INFO - { 09:43:54 INFO - "project": "", 09:43:54 INFO - "product": "firefox", 09:43:54 INFO - "script_repo_revision": "production", 09:43:54 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:43:54 INFO - "repository": "", 09:43:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:43:54 INFO - "buildid": "20170410083550", 09:43:54 INFO - "pgo_build": "False", 09:43:54 INFO - "basedir": "/builds/slave/test", 09:43:54 INFO - "buildnumber": 27, 09:43:54 INFO - "slavename": "t-yosemite-r7-0134", 09:43:54 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:43:54 INFO - "platform": "yosemite_r7", 09:43:54 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:43:54 INFO - "branch": "mozilla-aurora", 09:43:54 INFO - "repo_path": "releases/mozilla-aurora", 09:43:54 INFO - "revision": "718da6d0b0bc7e97a93061e19b71ec157e7b51a9", 09:43:54 INFO - "stage_platform": "macosx64", 09:43:54 INFO - "builduid": "e4729175b0c14233895009d0ed2df458", 09:43:54 INFO - "slavebuilddir": "test" 09:43:54 INFO - } 09:43:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 09:43:54 INFO - Running post-action listener: _resource_record_post_action 09:43:54 INFO - [mozharness: 2017-04-10 16:43:54.936543Z] Finished read-buildbot-config step (success) 09:43:54 INFO - [mozharness: 2017-04-10 16:43:54.936678Z] Running download-and-extract step. 09:43:54 INFO - Running pre-action listener: _resource_record_pre_action 09:43:54 INFO - Running main action method: download_and_extract 09:43:54 INFO - mkdir: /builds/slave/test/build/tests 09:43:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:54 INFO - trying https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 09:43:54 INFO - Downloading https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:43:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 09:43:56 INFO - Downloaded 1199 bytes. 09:43:56 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:43:56 INFO - Using the following test package requirements: 09:43:56 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:43:56 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:43:56 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 09:43:56 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:43:56 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 09:43:56 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:43:56 INFO - u'jsshell-mac.zip'], 09:43:56 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:43:56 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 09:43:56 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:43:56 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:43:56 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 09:43:56 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:43:56 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 09:43:56 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:43:56 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 09:43:56 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:43:56 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 09:43:56 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:43:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:43:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:43:56 INFO - Fetch https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:43:57 INFO - Expected file size: 51790437 09:43:57 INFO - Obtained file size: 51790437 09:44:14 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:44:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:44:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:44:14 INFO - Fetch https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:44:16 INFO - Expected file size: 51790437 09:44:16 INFO - Obtained file size: 51790437 09:44:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 09:44:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:44:31 INFO - Fetch https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 09:44:32 INFO - Expected file size: 14404424 09:44:32 INFO - Obtained file size: 14404424 09:44:32 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')]} 09:44:32 INFO - trying https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:44:32 INFO - Downloading https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 09:44:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 09:44:34 INFO - Downloaded 74686586 bytes. 09:44:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:44:34 INFO - mkdir: /builds/slave/test/properties 09:44:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:44:34 INFO - Writing to file /builds/slave/test/properties/build_url 09:44:34 INFO - Contents: 09:44:34 INFO - build_url:https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:44:35 INFO - Running post-action listener: _resource_record_post_action 09:44:35 INFO - Running post-action listener: set_extra_try_arguments 09:44:35 INFO - [mozharness: 2017-04-10 16:44:35.404383Z] Finished download-and-extract step (success) 09:44:35 INFO - [mozharness: 2017-04-10 16:44:35.404530Z] Running populate-webroot step. 09:44:35 INFO - Running pre-action listener: _resource_record_pre_action 09:44:35 INFO - Running main action method: populate_webroot 09:44:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:44:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:44:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:44:35 INFO - '--tppagecycles', 09:44:35 INFO - '7'], 09:44:35 INFO - 'tests': ['tsvgm']}}, 09:44:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:44:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:44:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:44:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:44:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:44:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:44:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:44:35 INFO - 'talos_options': ['--disable-e10s'], 09:44:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:44:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:44:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:44:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:44:35 INFO - 'talos_options': ['--disable-e10s'], 09:44:35 INFO - 'tests': ['damp', 'tps']}, 09:44:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:44:35 INFO - 'tests': ['damp', 'tps']}, 09:44:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:44:35 INFO - 'tests': ['dromaeo_dom']}, 09:44:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:44:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:44:35 INFO - 'tests': ['basic_compositor_video']}, 09:44:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:44:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:44:35 INFO - 'tests': ['a11yr', 09:44:35 INFO - 'ts_paint', 09:44:35 INFO - 'tpaint', 09:44:35 INFO - 'sessionrestore', 09:44:35 INFO - 'sessionrestore_no_auto_restore', 09:44:35 INFO - 'tabpaint']}, 09:44:35 INFO - 'other-e10s': {'tests': ['a11yr', 09:44:35 INFO - 'ts_paint', 09:44:35 INFO - 'tpaint', 09:44:35 INFO - 'sessionrestore', 09:44:35 INFO - 'sessionrestore_no_auto_restore', 09:44:35 INFO - 'tabpaint']}, 09:44:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:44:35 INFO - 'ts_paint', 09:44:35 INFO - 'tpaint', 09:44:35 INFO - 'sessionrestore', 09:44:35 INFO - 'sessionrestore_no_auto_restore', 09:44:35 INFO - 'tabpaint']}, 09:44:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:44:35 INFO - 'ts_paint', 09:44:35 INFO - 'tpaint', 09:44:35 INFO - 'sessionrestore', 09:44:35 INFO - 'sessionrestore_no_auto_restore', 09:44:35 INFO - 'tabpaint']}, 09:44:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:44:35 INFO - 'tests': ['a11yr', 09:44:35 INFO - 'ts_paint', 09:44:35 INFO - 'tpaint', 09:44:35 INFO - 'sessionrestore', 09:44:35 INFO - 'sessionrestore_no_auto_restore', 09:44:35 INFO - 'tabpaint']}, 09:44:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:44:35 INFO - 'tests': ['a11yr', 09:44:35 INFO - 'ts_paint', 09:44:35 INFO - 'tpaint', 09:44:35 INFO - 'sessionrestore', 09:44:35 INFO - 'sessionrestore_no_auto_restore', 09:44:35 INFO - 'tabpaint']}, 09:44:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:44:35 INFO - 'tests': ['tsvgx', 09:44:35 INFO - 'tsvgr_opacity', 09:44:35 INFO - 'tart', 09:44:35 INFO - 'tscrollx', 09:44:35 INFO - 'cart', 09:44:35 INFO - 'tsvg_static']}, 09:44:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:44:35 INFO - 'tsvgr_opacity', 09:44:35 INFO - 'tart', 09:44:35 INFO - 'tscrollx', 09:44:35 INFO - 'cart', 09:44:35 INFO - 'tsvg_static']}, 09:44:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:44:35 INFO - 'talos_options': ['--disable-e10s'], 09:44:35 INFO - 'tests': ['tp5o']}, 09:44:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:44:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:44:35 INFO - 'talos_options': ['--disable-e10s', 09:44:35 INFO - '--xperf_path', 09:44:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:44:35 INFO - 'tests': ['tp5n']}, 09:44:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:44:35 INFO - 'talos_options': ['--xperf_path', 09:44:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:44:35 INFO - 'tests': ['tp5n']}}, 09:44:35 INFO - 'talos.zip': {'path': '', 09:44:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:44:35 INFO - Running post-action listener: _resource_record_post_action 09:44:35 INFO - [mozharness: 2017-04-10 16:44:35.423074Z] Finished populate-webroot step (success) 09:44:35 INFO - [mozharness: 2017-04-10 16:44:35.423191Z] Running create-virtualenv step. 09:44:35 INFO - Running pre-action listener: _resource_record_pre_action 09:44:35 INFO - Running main action method: create_virtualenv 09:44:35 INFO - Creating virtualenv /builds/slave/test/build/venv 09:44:35 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 09:44:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:44:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:44:35 INFO - Using real prefix '/tools/python27' 09:44:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:44:36 INFO - Installing distribute.............................................................................................................................................................................................done. 09:44:39 INFO - Installing pip.................done. 09:44:39 INFO - Return code: 0 09:44:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:44:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:44:39 INFO - Reading from file tmpfile_stdout 09:44:39 INFO - Output received: 09:44:39 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) 09:44:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:44:39 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')]} 09:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:39 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')]} 09:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:40 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 0x108bdfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd56347f6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bdd30>, '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 0x1088e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2be8>, '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.yVePuqgP8F/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.cx7CHNroDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:40 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 09:44:40 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 09:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVePuqgP8F/Render', 09:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:40 INFO - 'HOME': '/Users/cltbld', 09:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:40 INFO - 'LOGNAME': 'cltbld', 09:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:40 INFO - 'MOZ_NO_REMOTE': '1', 09:44:40 INFO - 'NO_EM_RESTART': '1', 09:44:40 INFO - 'PAGER': '/bin/cat', 09:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:40 INFO - 'PWD': '/builds/slave/test', 09:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:40 INFO - 'SHELL': '/bin/bash', 09:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cx7CHNroDC/Listeners', 09:44:40 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 09:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:40 INFO - 'USER': 'cltbld', 09:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:40 INFO - 'XPC_FLAGS': '0x0', 09:44:40 INFO - 'XPC_SERVICE_NAME': '0', 09:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:40 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 09:44:40 INFO - Cleaning up... 09:44:40 INFO - Return code: 0 09:44:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:44:40 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')]} 09:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:40 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')]} 09:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:40 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 0x108bdfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd56347f6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bdd30>, '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 0x1088e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2be8>, '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.yVePuqgP8F/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.cx7CHNroDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:40 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 09:44:40 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 09:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVePuqgP8F/Render', 09:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:40 INFO - 'HOME': '/Users/cltbld', 09:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:40 INFO - 'LOGNAME': 'cltbld', 09:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:40 INFO - 'MOZ_NO_REMOTE': '1', 09:44:40 INFO - 'NO_EM_RESTART': '1', 09:44:40 INFO - 'PAGER': '/bin/cat', 09:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:40 INFO - 'PWD': '/builds/slave/test', 09:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:40 INFO - 'SHELL': '/bin/bash', 09:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cx7CHNroDC/Listeners', 09:44:40 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 09:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:40 INFO - 'USER': 'cltbld', 09:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:40 INFO - 'XPC_FLAGS': '0x0', 09:44:40 INFO - 'XPC_SERVICE_NAME': '0', 09:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:41 INFO - Downloading/unpacking psutil>=3.1.1 09:44:41 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 09:44:41 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 09:44:41 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 09:44:41 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 09:44:41 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 09:44:41 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 09:44:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:44:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:44:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:44:44 INFO - Installing collected packages: psutil 09:44:44 INFO - Running setup.py install for psutil 09:44:45 INFO - building 'psutil._psutil_osx' extension 09:44:45 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 09:44:45 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 09:44:45 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 09:44:45 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 09:44:45 INFO - building 'psutil._psutil_posix' extension 09:44:45 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 09:44:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:44:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:44:45 INFO - ^ 09:44:45 INFO - 1 warning generated. 09:44:45 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 09:44:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:44:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:44:46 INFO - Successfully installed psutil 09:44:46 INFO - Cleaning up... 09:44:46 INFO - Return code: 0 09:44:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108bdfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd56347f6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bdd30>, '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 0x1088e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2be8>, '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.yVePuqgP8F/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.cx7CHNroDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:44:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:44:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVePuqgP8F/Render', 09:44:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:46 INFO - 'HOME': '/Users/cltbld', 09:44:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:46 INFO - 'LOGNAME': 'cltbld', 09:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:46 INFO - 'MOZ_NO_REMOTE': '1', 09:44:46 INFO - 'NO_EM_RESTART': '1', 09:44:46 INFO - 'PAGER': '/bin/cat', 09:44:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:46 INFO - 'PWD': '/builds/slave/test', 09:44:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:46 INFO - 'SHELL': '/bin/bash', 09:44:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cx7CHNroDC/Listeners', 09:44:46 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 09:44:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:46 INFO - 'USER': 'cltbld', 09:44:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:46 INFO - 'XPC_FLAGS': '0x0', 09:44:46 INFO - 'XPC_SERVICE_NAME': '0', 09:44:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:44:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:44:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:44:49 INFO - Installing collected packages: mozsystemmonitor 09:44:49 INFO - Running setup.py install for mozsystemmonitor 09:44:49 INFO - Successfully installed mozsystemmonitor 09:44:49 INFO - Cleaning up... 09:44:49 INFO - Return code: 0 09:44:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108bdfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd56347f6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bdd30>, '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 0x1088e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2be8>, '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.yVePuqgP8F/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.cx7CHNroDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:44:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVePuqgP8F/Render', 09:44:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:49 INFO - 'HOME': '/Users/cltbld', 09:44:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:49 INFO - 'LOGNAME': 'cltbld', 09:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:49 INFO - 'MOZ_NO_REMOTE': '1', 09:44:49 INFO - 'NO_EM_RESTART': '1', 09:44:49 INFO - 'PAGER': '/bin/cat', 09:44:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:49 INFO - 'PWD': '/builds/slave/test', 09:44:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:49 INFO - 'SHELL': '/bin/bash', 09:44:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cx7CHNroDC/Listeners', 09:44:49 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 09:44:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:49 INFO - 'USER': 'cltbld', 09:44:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:49 INFO - 'XPC_FLAGS': '0x0', 09:44:49 INFO - 'XPC_SERVICE_NAME': '0', 09:44:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:49 INFO - Downloading/unpacking jsonschema==2.5.1 09:44:49 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 09:44:49 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 09:44:49 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 09:44:49 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 09:44:49 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 09:44:49 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 09:44:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:44:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:44:52 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 09:44:52 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 09:44:52 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 09:44:52 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 09:44:52 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 09:44:52 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 09:44:52 INFO - Downloading functools32-3.2.3-2.tar.gz 09:44:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:44:52 INFO - warning: no files found matching '*.txt' 09:44:52 INFO - no previously-included directories found matching 'build' 09:44:52 INFO - no previously-included directories found matching 'dist' 09:44:52 INFO - no previously-included directories found matching '.git*' 09:44:52 INFO - Installing collected packages: jsonschema, functools32 09:44:53 INFO - Running setup.py install for functools32 09:44:53 INFO - warning: no files found matching '*.txt' 09:44:53 INFO - no previously-included directories found matching 'build' 09:44:53 INFO - no previously-included directories found matching 'dist' 09:44:53 INFO - no previously-included directories found matching '.git*' 09:44:53 INFO - Successfully installed jsonschema functools32 09:44:53 INFO - Cleaning up... 09:44:53 INFO - Return code: 0 09:44:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:53 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 0x108bdfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd56347f6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bdd30>, '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 0x1088e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2be8>, '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.yVePuqgP8F/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.cx7CHNroDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:53 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 09:44:53 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 09:44:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVePuqgP8F/Render', 09:44:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:53 INFO - 'HOME': '/Users/cltbld', 09:44:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:53 INFO - 'LOGNAME': 'cltbld', 09:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:53 INFO - 'MOZ_NO_REMOTE': '1', 09:44:53 INFO - 'NO_EM_RESTART': '1', 09:44:53 INFO - 'PAGER': '/bin/cat', 09:44:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:53 INFO - 'PWD': '/builds/slave/test', 09:44:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:53 INFO - 'SHELL': '/bin/bash', 09:44:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cx7CHNroDC/Listeners', 09:44:53 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 09:44:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:53 INFO - 'USER': 'cltbld', 09:44:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:53 INFO - 'XPC_FLAGS': '0x0', 09:44:53 INFO - 'XPC_SERVICE_NAME': '0', 09:44:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:44:53 INFO - Cleaning up... 09:44:53 INFO - Return code: 0 09:44:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:53 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 0x108bdfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd56347f6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bdd30>, '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 0x1088e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2be8>, '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.yVePuqgP8F/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.cx7CHNroDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:53 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 09:44:53 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 09:44:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVePuqgP8F/Render', 09:44:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:53 INFO - 'HOME': '/Users/cltbld', 09:44:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:53 INFO - 'LOGNAME': 'cltbld', 09:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:53 INFO - 'MOZ_NO_REMOTE': '1', 09:44:53 INFO - 'NO_EM_RESTART': '1', 09:44:53 INFO - 'PAGER': '/bin/cat', 09:44:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:53 INFO - 'PWD': '/builds/slave/test', 09:44:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:53 INFO - 'SHELL': '/bin/bash', 09:44:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cx7CHNroDC/Listeners', 09:44:53 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 09:44:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:53 INFO - 'USER': 'cltbld', 09:44:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:53 INFO - 'XPC_FLAGS': '0x0', 09:44:53 INFO - 'XPC_SERVICE_NAME': '0', 09:44:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:53 INFO - Downloading/unpacking blobuploader==1.2.4 09:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:56 INFO - Downloading blobuploader-1.2.4.tar.gz 09:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:44:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:44:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:57 INFO - Downloading docopt-0.6.1.tar.gz 09:44:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:44:57 INFO - Installing collected packages: blobuploader, requests, docopt 09:44:57 INFO - Running setup.py install for blobuploader 09:44:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:44:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:44:57 INFO - Running setup.py install for requests 09:44:58 INFO - Running setup.py install for docopt 09:44:58 INFO - Successfully installed blobuploader requests docopt 09:44:58 INFO - Cleaning up... 09:44:58 INFO - Return code: 0 09:44:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108bdfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd56347f6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bdd30>, '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 0x1088e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2be8>, '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.yVePuqgP8F/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.cx7CHNroDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:44:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:44:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVePuqgP8F/Render', 09:44:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:58 INFO - 'HOME': '/Users/cltbld', 09:44:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:58 INFO - 'LOGNAME': 'cltbld', 09:44:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:58 INFO - 'MOZ_NO_REMOTE': '1', 09:44:58 INFO - 'NO_EM_RESTART': '1', 09:44:58 INFO - 'PAGER': '/bin/cat', 09:44:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:58 INFO - 'PWD': '/builds/slave/test', 09:44:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:58 INFO - 'SHELL': '/bin/bash', 09:44:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cx7CHNroDC/Listeners', 09:44:58 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 09:44:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:58 INFO - 'USER': 'cltbld', 09:44:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:58 INFO - 'XPC_FLAGS': '0x0', 09:44:58 INFO - 'XPC_SERVICE_NAME': '0', 09:44:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:44:58 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-IzMpDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:44:58 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-hyNgPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:44:59 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-fcaGcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:44:59 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-7EjuUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:44:59 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-fBfW6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:44:59 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-9XMUc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:44:59 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-PRyvfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:44:59 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-8KydzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:44:59 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-byUP4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:44:59 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-H4eQoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:44:59 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Xqu16o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:44:59 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-puYCSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:45:00 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-gUAERV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:45:00 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-iMKOW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:00 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-tP96pG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:45:00 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-LTiF5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:45:00 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-nkouk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:45:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:45:00 INFO - Running setup.py install for manifestparser 09:45:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:45:00 INFO - Running setup.py install for mozcrash 09:45:00 INFO - Running setup.py install for mozdebug 09:45:01 INFO - Running setup.py install for mozdevice 09:45:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:45:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:45:01 INFO - Running setup.py install for mozfile 09:45:01 INFO - Running setup.py install for mozhttpd 09:45:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:45:01 INFO - Running setup.py install for mozinfo 09:45:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:45:01 INFO - Running setup.py install for mozInstall 09:45:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:45:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:45:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:45:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:45:01 INFO - Running setup.py install for mozleak 09:45:01 INFO - Running setup.py install for mozlog 09:45:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:45:02 INFO - Running setup.py install for moznetwork 09:45:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:45:02 INFO - Running setup.py install for mozprocess 09:45:02 INFO - Running setup.py install for mozprofile 09:45:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:45:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:45:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:45:02 INFO - Running setup.py install for mozrunner 09:45:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:45:02 INFO - Running setup.py install for mozscreenshot 09:45:02 INFO - Running setup.py install for moztest 09:45:03 INFO - Running setup.py install for mozversion 09:45:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:45:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:45:03 INFO - Cleaning up... 09:45:03 INFO - Return code: 0 09:45:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108bdfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd56347f6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bdd30>, '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 0x1088e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2be8>, '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.yVePuqgP8F/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.cx7CHNroDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:45:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVePuqgP8F/Render', 09:45:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:03 INFO - 'HOME': '/Users/cltbld', 09:45:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:03 INFO - 'LOGNAME': 'cltbld', 09:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:03 INFO - 'MOZ_NO_REMOTE': '1', 09:45:03 INFO - 'NO_EM_RESTART': '1', 09:45:03 INFO - 'PAGER': '/bin/cat', 09:45:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:03 INFO - 'PWD': '/builds/slave/test', 09:45:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:03 INFO - 'SHELL': '/bin/bash', 09:45:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cx7CHNroDC/Listeners', 09:45:03 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 09:45:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:03 INFO - 'USER': 'cltbld', 09:45:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:03 INFO - 'XPC_FLAGS': '0x0', 09:45:03 INFO - 'XPC_SERVICE_NAME': '0', 09:45:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:45:03 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-ncBGH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:45:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:45:03 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-NmWlmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:45:03 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)) 09:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:45:03 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-seWimK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:45:03 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)) 09:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:45:03 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-k3Tf5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:45:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 09:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:45:03 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-8H0Cga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:45:04 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-v1pqBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:45:04 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-nWSsgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:45:04 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-NlPJoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:45:04 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-rlkSwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:45:04 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Nwhqbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:45:04 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-yplTng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:45:04 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-uJbnBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:45:04 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)) 09:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:45:04 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-65jLbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:45:04 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)) 09:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:45:04 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-0IToQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:45:04 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)) 09:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:04 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-mnq9G3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:45:05 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-YofsIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:45:05 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-tegdIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:45:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:07 INFO - Downloading blessings-1.6.tar.gz 09:45:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:45:08 INFO - Installing collected packages: blessings 09:45:08 INFO - Running setup.py install for blessings 09:45:08 INFO - Successfully installed blessings 09:45:08 INFO - Cleaning up... 09:45:08 INFO - Return code: 0 09:45:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:45:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:45:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:45:08 INFO - Reading from file tmpfile_stdout 09:45:08 INFO - Current package versions: 09:45:08 INFO - blessings == 1.6 09:45:08 INFO - blobuploader == 1.2.4 09:45:08 INFO - docopt == 0.6.1 09:45:08 INFO - functools32 == 3.2.3-2 09:45:08 INFO - jsonschema == 2.5.1 09:45:08 INFO - manifestparser == 1.1 09:45:08 INFO - mozInstall == 1.12 09:45:08 INFO - mozcrash == 1.0 09:45:08 INFO - mozdebug == 0.1 09:45:08 INFO - mozdevice == 0.49 09:45:08 INFO - mozfile == 1.2 09:45:08 INFO - mozhttpd == 0.7 09:45:08 INFO - mozinfo == 0.9 09:45:08 INFO - mozleak == 0.1 09:45:08 INFO - mozlog == 3.4 09:45:08 INFO - moznetwork == 0.27 09:45:08 INFO - mozprocess == 0.25 09:45:08 INFO - mozprofile == 0.28 09:45:08 INFO - mozrunner == 6.13 09:45:08 INFO - mozscreenshot == 0.1 09:45:08 INFO - mozsystemmonitor == 0.3 09:45:08 INFO - moztest == 0.8 09:45:08 INFO - mozversion == 1.4 09:45:08 INFO - psutil == 3.1.1 09:45:08 INFO - requests == 1.2.3 09:45:08 INFO - wsgiref == 0.1.2 09:45:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:08 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 0x108bdfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd56347f6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bdd30>, '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 0x1088e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2be8>, '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.yVePuqgP8F/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.cx7CHNroDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:08 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 09:45:08 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 09:45:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVePuqgP8F/Render', 09:45:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:08 INFO - 'HOME': '/Users/cltbld', 09:45:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:08 INFO - 'LOGNAME': 'cltbld', 09:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:08 INFO - 'MOZ_NO_REMOTE': '1', 09:45:08 INFO - 'NO_EM_RESTART': '1', 09:45:08 INFO - 'PAGER': '/bin/cat', 09:45:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:08 INFO - 'PWD': '/builds/slave/test', 09:45:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:08 INFO - 'SHELL': '/bin/bash', 09:45:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cx7CHNroDC/Listeners', 09:45:08 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 09:45:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:08 INFO - 'USER': 'cltbld', 09:45:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:08 INFO - 'XPC_FLAGS': '0x0', 09:45:08 INFO - 'XPC_SERVICE_NAME': '0', 09:45:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:08 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)) 09:45:08 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)) 09:45:08 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)) 09:45:08 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)) 09:45:08 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)) 09:45:08 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)) 09:45:08 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)) 09:45:08 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)) 09:45:08 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)) 09:45:08 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)) 09:45:08 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)) 09:45:08 INFO - Cleaning up... 09:45:08 INFO - Return code: 0 09:45:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108bdfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108be2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd56347f6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089bdd30>, '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 0x1088e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2be8>, '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.yVePuqgP8F/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.cx7CHNroDC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:45:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:45:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVePuqgP8F/Render', 09:45:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:08 INFO - 'HOME': '/Users/cltbld', 09:45:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:08 INFO - 'LOGNAME': 'cltbld', 09:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:08 INFO - 'MOZ_NO_REMOTE': '1', 09:45:08 INFO - 'NO_EM_RESTART': '1', 09:45:08 INFO - 'PAGER': '/bin/cat', 09:45:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:08 INFO - 'PWD': '/builds/slave/test', 09:45:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:08 INFO - 'SHELL': '/bin/bash', 09:45:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cx7CHNroDC/Listeners', 09:45:08 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 09:45:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:08 INFO - 'USER': 'cltbld', 09:45:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:08 INFO - 'XPC_FLAGS': '0x0', 09:45:08 INFO - 'XPC_SERVICE_NAME': '0', 09:45:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:45:09 INFO - Cleaning up... 09:45:09 INFO - Return code: 0 09:45:09 INFO - Running post-action listener: _resource_record_post_action 09:45:09 INFO - Running post-action listener: _start_resource_monitoring 09:45:09 INFO - Starting resource monitoring. 09:45:09 INFO - [mozharness: 2017-04-10 16:45:09.163203Z] Finished create-virtualenv step (success) 09:45:09 INFO - [mozharness: 2017-04-10 16:45:09.164218Z] Running install step. 09:45:09 INFO - Running pre-action listener: _resource_record_pre_action 09:45:09 INFO - Running main action method: install 09:45:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:45:09 INFO - Reading from file tmpfile_stdout 09:45:09 INFO - Detecting whether we're running mozinstall >=1.0... 09:45:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:45:09 INFO - Reading from file tmpfile_stdout 09:45:09 INFO - Output received: 09:45:09 INFO - Usage: mozinstall [options] installer 09:45:09 INFO - Options: 09:45:09 INFO - -h, --help show this help message and exit 09:45:09 INFO - -d DEST, --destination=DEST 09:45:09 INFO - Directory to install application into. [default: 09:45:09 INFO - "/builds/slave/test"] 09:45:09 INFO - --app=APP Application being installed. [default: firefox] 09:45:09 INFO - mkdir: /builds/slave/test/build/application 09:45:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:45:29 INFO - Reading from file tmpfile_stdout 09:45:29 INFO - Output received: 09:45:29 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:45:29 INFO - Running post-action listener: _resource_record_post_action 09:45:29 INFO - [mozharness: 2017-04-10 16:45:29.771692Z] Finished install step (success) 09:45:29 INFO - [mozharness: 2017-04-10 16:45:29.771857Z] Running run-tests step. 09:45:29 INFO - Running pre-action listener: _resource_record_pre_action 09:45:29 INFO - Running main action method: run_tests 09:45:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:45:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:45:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:45:29 INFO - 2017-04-10 09:45:29.786 screenresolution[1622:7411] starting screenresolution argv=screenresolution get 09:45:29 INFO - 2017-04-10 09:45:29.789 screenresolution[1622:7411] Display 0: 1600x1200x32@60 09:45:29 INFO - 2017-04-10 09:45:29.795 screenresolution[1623:7413] starting screenresolution argv=screenresolution list 09:45:29 INFO - Available Modes on Display 0 09:45:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:45:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:45:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:45:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:45:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:45:30 INFO - Intel Iris: 09:45:30 INFO - Chipset Model: Intel Iris 09:45:30 INFO - Type: GPU 09:45:30 INFO - Bus: Built-In 09:45:30 INFO - VRAM (Dynamic, Max): 1536 MB 09:45:30 INFO - Vendor: Intel (0x8086) 09:45:30 INFO - Device ID: 0x0a2e 09:45:30 INFO - Revision ID: 0x0009 09:45:30 INFO - Displays: 09:45:30 INFO - Display: 09:45:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:45:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:45:30 INFO - Display Serial Number: 1600x1200 60 09:45:30 INFO - Main Display: Yes 09:45:30 INFO - Mirror: Off 09:45:30 INFO - Online: Yes 09:45:30 INFO - Rotation: Supported 09:45:30 INFO - Return code: 0 09:45:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:45:30 INFO - Python 2.7.3 09:45:30 INFO - Return code: 0 09:45:30 INFO - grabbing minidump binary from tooltool 09:45: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')]} 09:45:30 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 0x1089bdd30>, '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 0x1088e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1089b2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:45:30 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 09:45:30 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 09:45:30 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 09:45:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:45:30 INFO - Return code: 0 09:45:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:45:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:45:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:45:30 INFO - ENV: RUST_BACKTRACE is now 1 09:45:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:45:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:45:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0134 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:45:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVePuqgP8F/Render', 09:45:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:30 INFO - 'HOME': '/Users/cltbld', 09:45:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:30 INFO - 'LOGNAME': 'cltbld', 09:45:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:45:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:30 INFO - 'MOZ_NO_REMOTE': '1', 09:45:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:45:30 INFO - 'NO_EM_RESTART': '1', 09:45:30 INFO - 'PAGER': '/bin/cat', 09:45:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:30 INFO - 'PWD': '/builds/slave/test', 09:45:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:45:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:30 INFO - 'RUST_BACKTRACE': '1', 09:45:30 INFO - 'SHELL': '/bin/bash', 09:45:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cx7CHNroDC/Listeners', 09:45:30 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 09:45:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:30 INFO - 'USER': 'cltbld', 09:45:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:30 INFO - 'XPC_FLAGS': '0x0', 09:45:30 INFO - 'XPC_SERVICE_NAME': '0', 09:45:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:45:30 INFO - mozversion application_buildid: 20170410083550 09:45:30 INFO - mozversion application_changeset: 718da6d0b0bc7e97a93061e19b71ec157e7b51a9 09:45:30 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:45:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:45:30 INFO - mozversion application_name: Firefox 09:45:30 INFO - mozversion application_remotingname: firefox-dev 09:45:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:45:30 INFO - mozversion application_vendor: Mozilla 09:45:30 INFO - mozversion application_version: 54.0a2 09:45:30 INFO - mozversion platform_buildid: 20170410083550 09:45:30 INFO - mozversion platform_changeset: 718da6d0b0bc7e97a93061e19b71ec157e7b51a9 09:45:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:45:30 INFO - mozversion platform_version: 54.0a2 09:45:30 INFO - using testdate: 1491842730 09:45:30 INFO - actual date: 1491842730 09:45:30 INFO - starting webserver on 'localhost:49236' 09:45:30 INFO - SUITE-START | Running 2 tests 09:45:30 INFO - TEST-START | dromaeo_css 09:45:30 INFO - operating with platform_type : mac_ 09:45:30 INFO - Initialising browser for dromaeo_css test... 09:45:30 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpm9pzop/profile http://localhost:49236/getInfo.html) 09:45:33 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 09:45:33 INFO - PROCESS | 1633 | colorDepth:24 09:45:33 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 09:45:33 INFO - PROCESS | 1633 | __metrics 09:45:33 INFO - PROCESS | 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:45:33 INFO - PROCESS | 1633 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:45:34 INFO - TEST-INFO | 1633: exit 0 09:45:34 INFO - Browser initialized. 09:45:34 INFO - Running cycle 1/1 for dromaeo_css test... 09:45:34 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpm9pzop/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:45:46 INFO - PROCESS | 1638 | RSS: Main: 214765568 09:45:46 INFO - PROCESS | 1638 | 09:48:50 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 09:48:53 INFO - PROCESS | 1638 | RSS: Main: 432627712 09:48:53 INFO - PROCESS | 1638 | 09:50:36 INFO - PROCESS | 1638 | ************************* 09:50:36 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 09:50:36 INFO - PROCESS | 1638 | 09:50:36 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:50:36 INFO - PROCESS | 1638 | Full stack: 09:50:36 INFO - PROCESS | 1638 | ************************* 09:50:36 INFO - PROCESS | 1638 | ************************* 09:50:36 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 09:50:36 INFO - PROCESS | 1638 | 09:50:36 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:50:36 INFO - PROCESS | 1638 | Full stack: 09:50:36 INFO - PROCESS | 1638 | ************************* 09:50:36 INFO - PROCESS | 1638 | ************************* 09:50:36 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 09:50:36 INFO - PROCESS | 1638 | 09:50:36 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:50:36 INFO - PROCESS | 1638 | Full stack: 09:50:36 INFO - PROCESS | 1638 | ************************* 09:50:36 INFO - PROCESS | 1638 | ************************* 09:50:36 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 09:50:36 INFO - PROCESS | 1638 | 09:50:36 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:50:36 INFO - PROCESS | 1638 | Full stack: 09:50:36 INFO - PROCESS | 1638 | ************************* 09:51:56 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 09:51:56 INFO - PROCESS | 1638 | RSS: Main: 353386496 09:51:56 INFO - PROCESS | 1638 | 09:54:59 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 09:55:00 INFO - PROCESS | 1638 | RSS: Main: 331255808 09:55:00 INFO - PROCESS | 1638 | 09:58:02 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 09:58:03 INFO - PROCESS | 1638 | RSS: Main: 319463424 09:58:03 INFO - PROCESS | 1638 | 10:01:06 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 10:01:07 INFO - PROCESS | 1638 | RSS: Main: 329928704 10:01:07 INFO - PROCESS | 1638 | 10:04:10 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 10:04:10 INFO - PROCESS | 1638 | RSS: Main: 482705408 10:04:10 INFO - PROCESS | 1638 | 10:04:10 INFO - PROCESS | 1638 | __start_tp_report 10:04:10 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 10:04:10 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 10:04:10 INFO - PROCESS | 1638 | |i|pagename|runs| 10:04:10 INFO - PROCESS | 1638 | |0;dojo.html;3360.126367144542;3400.625931147575;3401.574803149607;3430.176757578182;3447.6897079262862;5934.88130237395;5949.524038076955;5967.343592204854;5967.970160149199;5996.22049133612;5260.763265653045;5627.409122042188;5849.181114643953;5936.673482958436;5957.076653118774;5331.493508116722;5356.571474282048;5366.543843773284;5440.102383106782;5475.917861232085;5273.288106105665;5281.392639846409;5317.601179911503;5442.591805614575;5470.9452905470835;5349.117395629716;5374.167004114369;5384;5385.892282154355;5396;5345.973270133644;5346.732663366816;5348.839534813962;5373.623846330758;5379.112446446332;5207.557357624606;5313.229581710669;5316.175992721134;5316.840494785162;5376.166694162411;5900.734466948998;5915.497182739429;5923.526117910556;5926.436988486087;5928.525717942554;6283.8743225135695;6296.244450665904;6298.338674439164;6299;6311.211098612674;6262.248530176408;6320.873582528369;6346.365363463663;6366.649834259114;6374.394432528902;5949;6011.188489553898;6020.397960203988;6027.668478233695;6062.757489700407;6270.6864656766975;6422.678866056678;6449.613023218575;6458.54790164684;6467.450266727381;6192.071189321638;6205.317415084337;6286.151369565142;6317.1156038154695;6364.109024736541;5359.866003349947;6277.05844123376;6327.683615819191;6360.618362898241;6376.043593461018;5756.02147634903;6344.143540621957;6374.649394283266;6378.97936330185;6402.231732192166;6116.143739876421;6245.375462453719;6282.434580887742;6318.178636777281;6351.333110023381;5108.770105345268;5160.509751573594;5175.9741201293755;5181.4041385240425;5186.533212010937;4533.727976321431;4540.250858608292;4548.590626843534;4552.362669226311;4558.042811009659;5918.763249469973;5930.28836539618;5976.820695379146;5991.221141251609;5992.31088424828;6270.498360131179;6284.937150628527;6313.873722525524;6327.367263273636;6337.4613157882095;5964.970175149097;5997.280326360865;5997.940020599825;6000.489958353502;6013.609115407485;5574.721263936787;5606.215129881738;5614.129809879475;5620.606557540933;5629.268195134607;6272.749090036347;6328.14570033058;6345.555811093363;6449.967750161407;6508.316626754123;6295.244570651551;6316.968415157895;6321.431071203614;6330.3669633036325;6343;6476.708548115252;6481;6482.35176482348;6496.837579060561;6497.415232629086;6179.474744646755;6334.524910631629;6338.112664227104;6397.424231819158;6414.647194404352;6275.34108918557;6332.746690132527;6335;6349.14286571307;6361.61830290184;6257.186565746424;6334.746610135543;6340.904886426615;6342.23893132827;6343.3022367538615;6113.633182009094;6195.597286176384;6364.172657554488;6368.331325210786;6372.139761132188;5111.233315002779;5771.769129234784;5907.143464197753;6035.18524999137;6214;5534.612577119724;5578.525825304894;6148.139260503623;6152.892479353759;6172.444479996821;6005.969970150121;6016.067509536029;6018.8194354169445;6031.125486804301;6108.099244189703 10:04:10 INFO - PROCESS | 1638 | |1;ext.html;683.2518392360366;811.1563973467594;873.8252349530096;890.2699786175335;893.5398269891003;40281.1943761125;40821.183576328454;40878.591214087886;40960.59039409605;42554.787226063825;41796;41812.58187418125;41939.58060419402;42076;42124.578754212445;7387.704491820321;7566.167721550625;9263.768405789859;9431;9440;10870.782584348308;11136.554537818478;11224.887751122465;11244.831327530093;11250.156238282121;11107.055900248448;11127.888721112806;11145.72135696606;11146.665600032013;11188.832167517494;7349.706011759523;11082.722931926686;11085.556577736881;11092.944535277353;11106.389148596823;7433.628318584049;10714.089302409304;10961.780764384748;11084.722881927935;11093.83359249612;39487.60512394897;39548.406773898416;39551.20897582061;39732.00669983245;39789.80105099485;3829.291581057513;3868.3617203161443;3870.9419358709642;3892.338302488555;3915.23652887687;27111.593326100123;28330.150095497167;28934.131976040753;29309.853450732822;29491.410171796444;3027.5004624236976;3043.360894212218;3047.801892876956;3068.892588759405;3119.2357872321427;320518;322084;322231;322327;322865;41948.79025604852;43207.35188972168;44709.10581788346;52218;52279;51582.226266606034;53534.66163345947;56953.71523142441;57238;57466.425335746106;12353.38233088342;14568.344424500923;14703.191324477257;14817.185054822088;14818.555443336718;12706.30115343665;12722.109452338247;12728.554500592434;12733.936330318475;12736.426860791287;12672.80990785139;12785.936070319589;12791.360431978364;12843.678908027186;12899.742005160031;4439.089986552749;5455.18172274162;5491.395946445888;5493.807716729975;5500.752466138953;1102.448775612194;1115.1023426141871;1118.5749415222162;1164.8427462292652;1180.976380472403;19684.114214860365;25900.611490827654;26499.337516562242;26655.600165997526;26695.33261668435;1242.142921384242;1242.726600147966;1255.9686007849693;1276.2215048820212;1297.5653156192782;13393.464261429628;13702.52041178574;13776.448942042207;13800.792988105186;13810.723785524233;457864;458073;458242;458781;459168;56209.437905621235;56291.71854140785;56332;56408.71795641078;56412.71793641005;1431.7196905829228;1435.2608406670572;1484;1484.2949598940418;1484.3765618440282;21195.93518918017;24628.630570541816;25046.49907001886;25938.962441502885;26023.73976260289;43114.137717244856;43198;43266.918327042076;43268.35097473478;43377;428657;429092;430759;431062;431499;28375.57436638411;29019.12942611725;29139.7086029137;33978;35369;42288.13559322034;42940.35589466098;42958.78520607502;43565.12869742651;45018;28459.43081138407;28767.424651506433;29391.118266452042;29939;31006.534901976036;30193.547096793125;30964.535531966587;31194.844025779727;31503.52744708877;31523.684763152076;36430.63569364379;36457.270854583294;36480.63519364772;36868;36937.44593831148;25400;26221.606675899497;30311.545326820557;31139.377212455172;31457.21356966094;23393.06427742871;24150.87924560366;24390.51218975646;24761.752382476665;24793.876030619733 10:04:10 INFO - PROCESS | 1638 | |2;jquery.html;384.53087233575064;409.76233784405065;423.92369373512764;432.3579484465568;449.82007197121146;12821.038422118332;14738.778918316233;20881.895590522025;21299.25452609159;21350;21442;21804.127834886585;22040.559188816253;22056.558868822653;22712.43218919528;5600.719964001804;6454.451371633393;6682.164729408824;6741.494387920901;6761.864762704743;5745.368009518949;6364.33174516676;6589.308122647101;6694.698738556776;6696;6693.966530167342;6696.631685257309;6696.631685257309;6706.429953453938;6708.664566771667;6405.615663060231;6584.308647591982;6658.633775142365;6669.699863506154;6678.998150277449;6410.326915673833;6514.739410423577;6515.153030106103;6517.967410162919;6519.380658837403;25874.87062564694;26202.95188192489;26302;26492.205233843015;26594.468110637874;2849.244950088228;2906.1862678449857;2917.343597690524;2933.8973135940355;2949.3069128754723;15731.921340393339;15807.841921580752;16319.592010199722;16377.83622163775;16478.928869623556;1668.816430192678;1974;2020.6261841559358;2031.14691829432;2052.1893851928553;127943;128319.35840320832;132505.337473312;151451;155168.99999999886;19723.53455818023;20221.29225477101;20266.493337666383;20284.695729563773;20464.59070818575;32556.837215813768;33043.83478082642;33104;33129.17177070544;33179.00462986114;8012.921563586751;10058.994100589882;10244.897551024544;10433.321834080913;10445.529951152217;8462.153784621489;8487.321014318839;8499.490030598185;8648.394612377073;8668.089850565726;9213.81572368549;9307.76730581741;9348.90651093494;9424.481653509123;9451.905480945103;3277.410066188109;3371.544841446422;3929.626685464876;3936.842526298973;3953.565107838193;878.1789027259533;879.595386122378;901.7970956534728;911.4166933162782;930.6463543853292;20987.8950605246;21536.353909382742;21567;21577.352679419644;21581.81300028482;800.3837045475068;886.1271647427279;925.2782829393084;932.9720108396759;971.3394891473865;10336.793264134676;10737.946310268555;10776.461176941122;10821;10833;776010;779802;787769;793122;810766;88745;136247;141868;147436;153718;1126.2116518437126;1196.9341686207167;1225.2587184753097;1226.926384416938;1227.9570215042431;14672.3397447113;19827.603447931248;19999.500012499804;20272.290469833486;20510.974451277674;41341.793291033355;62632;63753.362466376595;63842;66013.00980485392;86476.78470545288;87141.56429217814;96154.51922740342;97222.5138874301;99429;10801.621943231949;11131.220814542905;11408.429578521207;11701.824472633087;11811.527538898692;12287.631371058882;12299.44652490658;12345.691357716129;12487.06347023995;12669.429875655433;10567.418791966516;10804.405757683402;10904.890951090387;11089.112870970464;11105.944470277596;8359.749207523784;9635.421874687541;9972.30193886421;10093.747656308651;10095.242856785624;9023.323250756352;10806.675799726021;10806.729831754268;10808.513616887101;10881.238313317992;11116.777664446827;11123.443827808416;11123.499442524944;11457.369844658624;11492.85071492844;8531.061583225965;10255.794884102426;10432.321899076536;10576.788464230825;10640.201984851012 10:04:10 INFO - PROCESS | 1638 | |3;mootools.html;1372.334417807364;1544.9613759656;1548.736714758491;1562.2188905547227;1572.0724772384292;10137.189024878011;10642.467876606168;10662.573497060119;10669.146468282539;10671.679849604505;10407.167426605873;10609.416482093482;10916.290441121342;11092.223544351898;11102.389368584725;2387.808975281978;2430.307362401716;2434.4157402223427;2434.9878250608813;2435.5265064635905;2325.790678838904;2397.712274527059;2422.9757702422926;2423.418379588895;2423.9515209695796;2342.6603142544323;2409.542186984467;2415.7342692303832;2420.225527831095;2425.333033415807;2319.3158018384615;2397.2328854766392;2397.6283676030243;2402.2192787344184;2408.0127147869466;1978.5449346650207;1987.8906660133687;1993.6909778984282;2266.7193035934642;2380.3811009137576;10155.441450720207;10966.506507207196;10983.780324393547;10988.505517251742;10990.780184396348;5514.862128446821;5579.860503487445;5742;5744.942550574483;5773.509251713609;3357.244619960514;3538.681518663307;3607.5851277103206;3611.783293002402;3619.0771353305004;1126.594426006637;1183.9052875769917;1188.102982248397;1191.195942738648;1211.1219365959696;13449.462021519128;13815.309234538232;14814.333354999158;15167;15192.924035379863;4733.408323959505;4941.703497790144;4981;5001.299818025406;5007.84976450707;12852.678683032998;12862.421191046426;12886.420111095023;12889;12906.612801615967;4921.311016457699;5935.7625694972585;5971.313298970674;5974.61165024272;5989;4164.9583504165175;5313.282706834528;5316.946830531722;5320.095583750772;5323.86690332737;3308.3714094322004;3803.201327721154;3833.7316387852584;3942.64516193544;4023.959760402417;972.3436680240951;1121;1128.7686024364984;1136.8976792088752;1149.5804031528432;922.7001224602031;936.7892224249625;942.5522876633544;945.664289177346;993.2203220471895;6340.619562826153;6359.077933699587;6439.8068057958335;6500.252470965805;6599.175103112111;1009.3641006166215;1018.567108978696;1026.8037736037434;1027.1012863744224;1032.5921261101823;3707.4994875691978;4022.8189731462153;4081.73463727634;4143.295639741251;4158.91682166361;13066.150700204456;13587;13639.181649101085;13650.726985460235;13682.863171368159;13494.98787590915;13506.662333441742;13512.662183445296;13535.86464135346;13550.86449135496;9617.807643847083;9972.152367048737;9990.950045249727;10225.693229203136;10286.022827831195;6443.355664433707;6506.967465162644;6512.144038948877;6679.6994135265;6680.3987641112535;11944.581939632419;12101.818472723093;12139.696507587028;12321.137520373488;12321.383930803604;12172.695682608006;12263.9386803069;12277.508899643914;12298.954588860048;12343.012558995079;8720.694775683074;8726.3891527595;8823.250023747796;8896.243819275434;8927.687530936386;9760.365576237522;9918.851217231602;9971.601135954481;9991.700248992542;9995.450250236307;8417.200365965224;8508.361872859685;8535.786605334668;8539.231469167804;8553.486790792573;8083.827844962565;8206.22040906113;8271.669133234604;8277.296429803293;8324.500529968222;6431.356864313719;7573.431992600687;7578.924210758042;7585.279398457138;7587.734429294948;5514.365847927379;5550.195221692913;5695.2311437957005;5696.287964004499;5765.682887441231;5644.52021578154;5675.375708671961;5744.971275143765;5752.827415177551;5837.182794408871 10:04:10 INFO - PROCESS | 1638 | |4;prototype.html;362.1905042230614;363.4403019350201;368.8690514867222;371.10378436076877;385.2180074448869;5059.418166910807;5063.8480845574595;5097.515736005078;5098.31172791673;5117.283580298761;4227.577242275771;4754.90490190196;4878.585320247783;4910.508949105088;4952.876178095549;3791.9431208531896;4047.615476529725;4305.806238719258;4306.2033523798045;4376.934345984812;3942.206296135266;4203.5376108628025;4271.9359209611885;4378.64970802335;4401.779911004453;3511.613722490524;3648.5986541480415;3844.2503711776217;4396.450443694538;4426.734395936254;4315.525292217853;4327.069680018811;4332.72878976374;4339.913201735963;4355.564443555619;3363.5963684358035;3963.978371030766;4142.565030671766;4146.569433545422;4154.792260386968;4457.9969506861025;4645.953540464619;4977.975110124463;5000.399952005746;5069.518395752398;4189.581041895816;4300.61294483498;4726.621870250372;5014.398272207322;5057.4689657585795;5025.773840177201;5396.892062158774;5448.536874365684;5453.754581043862;5457.935702538007;4239.554846741079;4327.783610819446;4576.880807273447;4651.232546629802;4667.556582124693;5405.567524605944;5701.0046483730775;5709.343425506078;5723.799667011676;5763.769449221984;4778.474367819537;4780.948191397887;4805.51029180955;4807.567318941312;4836.516348365135;4052.176520565757;4394.846180383735;4889.339939108175;4894.510548945077;4901.632377571696;4403.295472724413;4706.529347065334;4718.711424958892;4733.242681170997;4745.816924214831;4572.8399506017795;4931.027588964371;5266.578673706094;5309.36287645485;5448.237246785453;1374.3265799758046;1418.4184484361363;1520.908745475275;1522.2921341575998;1525.2831169350388;1083.014456844268;1088.7805657663469;1135.8239472881717;1137.1634809782142;1151.8596589376457;4319.4816622005565;4536.296873984537;4883.658143929962;4944.90110197794;4995.775190116381;4630.86107416778;4866.372159034153;5088.837244171213;5468.562514998791;5484.533814625802;4530.637548996073;4577.885552861138;4597.356370108188;4701.48283688794;4703.96594196748;2502.524520341166;2663.387420893205;2774.820701201981;2811.655991493107;2858.0279607071056;4641.286970423647;5076.847694569169;5313.671582104474;5644.717764111778;5679.829605111853;4718.531879068882;5122.081607570211;5164.967273698746;5580.776768929279;5612.85967850807;4911.673848061004;5215.426303106655;5431.805002899356;5490.258815059916;5690.902550163875;5100.979804039282;5101.821436249714;5216.686998780086;5337.6797392156595;5402.000629883484;4656.790444429941;4857.587105096107;4986.276989836381;4997.675151115166;5291.947080529146;5279.868003299948;5457.017736807413;5535.11438169907;5742.856428589319;5797.710114494174;3908.687305015535;4128.8348466061025;4205.873823785291;4229.488358822758;4294.613484786384;5004.2493625956395;5053.117137265877;5111.565516931102;5448.828501872116;5520.806771762969;4397.868063958086;5171.741412929263;5207.3230480037355;5273.789048438173;5364.8122315718765;4586.656000799887;4800.2876576873405;4892.951070489249;5267.262583238273;5277.736113194402;4866.8076321301505;5123.487651234846;5129.923051154161;5156.303898973741;5210.843674689765;4809;5201.817936372209;5271.630985831109;5272.860804268631;5283.471652834686;4404.7357158570585;4928.67963582366;5050.747462626928;5202.658803538498;5243.606729495227 10:04:10 INFO - PROCESS | 1638 | |5;yui.html;638.8083574927521;734.4895297768053;752.6575408189275;818.9672413103475;837.0917554453416;15217;15934.760978585331;16308.103054332036;16344.264508097134;16357.427490037837;14834.703305933901;14946.701065978674;14976.101433913946;15002;15435.768463473029;941.3081385181903;954.3891909178132;958.832204364237;960.8798900137483;962.1533050915185;958.0802429667527;958.3818437108062;959.0505399654327;959.3428501476476;959.6161535385867;939.6945992552413;967.9080487353725;1000.9449480278582;1002.1982414068752;1004.036125319694;989.0949780950422;996.6013594562161;998.3211416236956;999.4153420249163;999.4902599674145;734.5262305812721;737.1522748838825;737.8266107464741;738.3207449146798;738.7857521318865;14222.644433889236;14613.561593152222;15952.601184970468;15986.96084754499;16000.919995399949;14139.293035348293;14449.638759031108;14537.636559086108;14573.854261457356;14599.854001459955;11718.472668729843;11747.29516229029;11967.940160299142;11971.102167337485;12064.517419303218;3619.1133172372674;3740.812959352049;3751.4372844073337;3881.8059097045316;3896.6687831534355;99121.50439247758;101528.49235753847;107177.92822071757;107203.46398268036;107773;10904.291221070605;10923.235373523936;10934.343939363664;10969.341839489656;11033;23122;23169.420764480685;23393.7660623395;23413.06347746071;23494.412639684146;7377.9631101845225;7731.381489480828;7820;7822.530648161015;7994.320482758914;4157.605027522381;4252.851150209728;4260.637845783143;4284.443022407129;4302.935455968163;3292.6378098409155;3377.59468863733;3381.6956473917467;3389.508521264403;3391.7117045050954;605.1346574398652;605.2222893581726;609.4880300547472;617.8084793713963;628.2492421556196;607.7629724407396;613.2312608572299;637.5027478566725;641.3650486018901;653.9247986481615;9220.8616870747;9679.516024198761;9818.950905245429;10759.946200269105;11211.66365009051;552.5524325296523;592.5022980696236;598.3896425645817;630.9495240380759;714.1430283659629;4309.827606895752;4496.033352829158;4666.2767271073035;5044.319016932741;5061.493850614909;73671.63164184253;91841;95626.04373956172;96470.55294170593;96829;63110.36889631137;64075.398115047494;88853.55573222092;89261.55369223242;91479.08520914706;53692.926141477736;65467.67266163639;69278.96081558781;70773.29226707667;71332.64333678299;10848.349099054081;11048.447577621248;11050.226484146033;11122.391090143827;11187.160962927976;40577.79711101425;48314.275285871445;48953.51046489632;51051.97896042133;53120.46879531155;64553.35446645474;64886.35113648803;65772.67113664401;66489.00266496104;67503.66248168728;12046.75906481883;12111.273323600612;12466.750664986468;12527.503022854635;12791.616251512469;14251.429942802171;14313.499027533986;14530.418783248551;14593.781093283396;14609.931914298117;12922.676933076747;13128.277944713132;13329.733405332034;13669.521566745116;13982.440702372198;12956.35218239103;12986.415611297703;13595.320233988063;13972.650683732989;14082.507112251022;12747.936260318638;13241.271730054554;13344.466221351426;13385.397657105648;13822.447102116204;9217.018341056704;9478;9676.040517301455;9877.40735555869;10082.54628541732;9087;11116.277441966246;11282.097432205568;11393.772124557298;11430.314181149157 10:04:10 INFO - PROCESS | 1638 | __end_tp_report 10:04:10 INFO - PROCESS | 1638 | __start_cc_report 10:04:10 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,2313 10:04:10 INFO - PROCESS | 1638 | __end_cc_report 10:04:10 INFO - PROCESS | 1638 | __startTimestamp1491843850592__endTimestamp 10:04:10 INFO - PROCESS | 1638 | 10:04:10 INFO - PROCESS | 1638 | ------- Summary: start ------- 10:04:10 INFO - PROCESS | 1638 | Number of tests: 6 10:04:10 INFO - PROCESS | 1638 | 10:04:10 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5866.65 Median:6020.40 stddev:640.34 (10.6%) stddev-sans-first:611.37 10:04:10 INFO - PROCESS | 1638 | Values: 3360.1 3400.6 3401.6 3430.2 3447.7 5934.9 5949.5 5967.3 5968.0 5996.2 5260.8 5627.4 5849.2 5936.7 5957.1 5331.5 5356.6 5366.5 5440.1 5475.9 5273.3 5281.4 5317.6 5442.6 5470.9 5349.1 5374.2 5384.0 5385.9 5396.0 5346.0 5346.7 5348.8 5373.6 5379.1 5207.6 5313.2 5316.2 5316.8 5376.2 5900.7 5915.5 5923.5 5926.4 5928.5 6283.9 6296.2 6298.3 6299.0 6311.2 6262.2 6320.9 6346.4 6366.6 6374.4 5949.0 6011.2 6020.4 6027.7 6062.8 6270.7 6422.7 6449.6 6458.5 6467.5 6192.1 6205.3 6286.2 6317.1 6364.1 5359.9 6277.1 6327.7 6360.6 6376.0 5756.0 6344.1 6374.6 6379.0 6402.2 6116.1 6245.4 6282.4 6318.2 6351.3 5108.8 5160.5 5176.0 5181.4 5186.5 4533.7 4540.3 4548.6 4552.4 4558.0 5918.8 5930.3 5976.8 5991.2 5992.3 6270.5 6284.9 6313.9 6327.4 6337.5 5965.0 5997.3 5997.9 6000.5 6013.6 5574.7 5606.2 5614.1 5620.6 5629.3 6272.7 6328.1 6345.6 6450.0 6508.3 6295.2 6317.0 6321.4 6330.4 6343.0 6476.7 6481.0 6482.4 6496.8 6497.4 6179.5 6334.5 6338.1 6397.4 6414.6 6275.3 6332.7 6335.0 6349.1 6361.6 6257.2 6334.7 6340.9 6342.2 6343.3 6113.6 6195.6 6364.2 6368.3 6372.1 5111.2 5771.8 5907.1 6035.2 6214.0 5534.6 5578.5 6148.1 6152.9 6172.4 6006.0 6016.1 6018.8 6031.1 6108.1 10:04:10 INFO - PROCESS | 1638 | 10:04:10 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:54451.45 Median:17129.31 stddev:108106.03 (631.1%) stddev-sans-first:108333.92 10:04:10 INFO - PROCESS | 1638 | Values: 683.3 811.2 873.8 890.3 893.5 40281.2 40821.2 40878.6 40960.6 42554.8 41796.0 41812.6 41939.6 42076.0 42124.6 7387.7 7566.2 9263.8 9431.0 9440.0 10870.8 11136.6 11224.9 11244.8 11250.2 11107.1 11127.9 11145.7 11146.7 11188.8 7349.7 11082.7 11085.6 11092.9 11106.4 7433.6 10714.1 10961.8 11084.7 11093.8 39487.6 39548.4 39551.2 39732.0 39789.8 3829.3 3868.4 3870.9 3892.3 3915.2 27111.6 28330.2 28934.1 29309.9 29491.4 3027.5 3043.4 3047.8 3068.9 3119.2 320518.0 322084.0 322231.0 322327.0 322865.0 41948.8 43207.4 44709.1 52218.0 52279.0 51582.2 53534.7 56953.7 57238.0 57466.4 12353.4 14568.3 14703.2 14817.2 14818.6 12706.3 12722.1 12728.6 12733.9 12736.4 12672.8 12785.9 12791.4 12843.7 12899.7 4439.1 5455.2 5491.4 5493.8 5500.8 1102.4 1115.1 1118.6 1164.8 1181.0 19684.1 25900.6 26499.3 26655.6 26695.3 1242.1 1242.7 1256.0 1276.2 1297.6 13393.5 13702.5 13776.4 13800.8 13810.7 457864.0 458073.0 458242.0 458781.0 459168.0 56209.4 56291.7 56332.0 56408.7 56412.7 1431.7 1435.3 1484.0 1484.3 1484.4 21195.9 24628.6 25046.5 25939.0 26023.7 43114.1 43198.0 43266.9 43268.4 43377.0 428657.0 429092.0 430759.0 431062.0 431499.0 28375.6 29019.1 29139.7 33978.0 35369.0 42288.1 42940.4 42958.8 43565.1 45018.0 28459.4 28767.4 29391.1 29939.0 31006.5 30193.5 30964.5 31194.8 31503.5 31523.7 36430.6 36457.3 36480.6 36868.0 36937.4 25400.0 26221.6 30311.5 31139.4 31457.2 23393.1 24150.9 24390.5 24761.8 24793.9 10:04:10 INFO - PROCESS | 1638 | 10:04:10 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:43070.98 Median:26397.10 stddev:130795.65 (495.5%) stddev-sans-first:131123.30 10:04:10 INFO - PROCESS | 1638 | Values: 384.5 409.8 423.9 432.4 449.8 12821.0 14738.8 20881.9 21299.3 21350.0 21442.0 21804.1 22040.6 22056.6 22712.4 5600.7 6454.5 6682.2 6741.5 6761.9 5745.4 6364.3 6589.3 6694.7 6696.0 6694.0 6696.6 6696.6 6706.4 6708.7 6405.6 6584.3 6658.6 6669.7 6679.0 6410.3 6514.7 6515.2 6518.0 6519.4 25874.9 26203.0 26302.0 26492.2 26594.5 2849.2 2906.2 2917.3 2933.9 2949.3 15731.9 15807.8 16319.6 16377.8 16478.9 1668.8 1974.0 2020.6 2031.1 2052.2 127943.0 128319.4 132505.3 151451.0 155169.0 19723.5 20221.3 20266.5 20284.7 20464.6 32556.8 33043.8 33104.0 33129.2 33179.0 8012.9 10059.0 10244.9 10433.3 10445.5 8462.2 8487.3 8499.5 8648.4 8668.1 9213.8 9307.8 9348.9 9424.5 9451.9 3277.4 3371.5 3929.6 3936.8 3953.6 878.2 879.6 901.8 911.4 930.6 20987.9 21536.4 21567.0 21577.4 21581.8 800.4 886.1 925.3 933.0 971.3 10336.8 10737.9 10776.5 10821.0 10833.0 776010.0 779802.0 787769.0 793122.0 810766.0 88745.0 136247.0 141868.0 147436.0 153718.0 1126.2 1196.9 1225.3 1226.9 1228.0 14672.3 19827.6 19999.5 20272.3 20511.0 41341.8 62632.0 63753.4 63842.0 66013.0 86476.8 87141.6 96154.5 97222.5 99429.0 10801.6 11131.2 11408.4 11701.8 11811.5 12287.6 12299.4 12345.7 12487.1 12669.4 10567.4 10804.4 10904.9 11089.1 11105.9 8359.7 9635.4 9972.3 10093.7 10095.2 9023.3 10806.7 10806.7 10808.5 10881.2 11116.8 11123.4 11123.5 11457.4 11492.9 8531.1 10255.8 10432.3 10576.8 10640.2 10:04:10 INFO - PROCESS | 1638 | 10:04:10 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6627.89 Median:2871.95 stddev:4159.47 (144.8%) stddev-sans-first:4152.39 10:04:10 INFO - PROCESS | 1638 | Values: 1372.3 1545.0 1548.7 1562.2 1572.1 10137.2 10642.5 10662.6 10669.1 10671.7 10407.2 10609.4 10916.3 11092.2 11102.4 2387.8 2430.3 2434.4 2435.0 2435.5 2325.8 2397.7 2423.0 2423.4 2424.0 2342.7 2409.5 2415.7 2420.2 2425.3 2319.3 2397.2 2397.6 2402.2 2408.0 1978.5 1987.9 1993.7 2266.7 2380.4 10155.4 10966.5 10983.8 10988.5 10990.8 5514.9 5579.9 5742.0 5744.9 5773.5 3357.2 3538.7 3607.6 3611.8 3619.1 1126.6 1183.9 1188.1 1191.2 1211.1 13449.5 13815.3 14814.3 15167.0 15192.9 4733.4 4941.7 4981.0 5001.3 5007.8 12852.7 12862.4 12886.4 12889.0 12906.6 4921.3 5935.8 5971.3 5974.6 5989.0 4165.0 5313.3 5316.9 5320.1 5323.9 3308.4 3803.2 3833.7 3942.6 4024.0 972.3 1121.0 1128.8 1136.9 1149.6 922.7 936.8 942.6 945.7 993.2 6340.6 6359.1 6439.8 6500.3 6599.2 1009.4 1018.6 1026.8 1027.1 1032.6 3707.5 4022.8 4081.7 4143.3 4158.9 13066.2 13587.0 13639.2 13650.7 13682.9 13495.0 13506.7 13512.7 13535.9 13550.9 9617.8 9972.2 9991.0 10225.7 10286.0 6443.4 6507.0 6512.1 6679.7 6680.4 11944.6 12101.8 12139.7 12321.1 12321.4 12172.7 12263.9 12277.5 12299.0 12343.0 8720.7 8726.4 8823.3 8896.2 8927.7 9760.4 9918.9 9971.6 9991.7 9995.5 8417.2 8508.4 8535.8 8539.2 8553.5 8083.8 8206.2 8271.7 8277.3 8324.5 6431.4 7573.4 7578.9 7585.3 7587.7 5514.4 5550.2 5695.2 5696.3 5765.7 5644.5 5675.4 5745.0 5752.8 5837.2 10:04:10 INFO - PROCESS | 1638 | 10:04:10 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4475.19 Median:4847.05 stddev:1216.83 (25.1%) stddev-sans-first:1180.43 10:04:10 INFO - PROCESS | 1638 | Values: 362.2 363.4 368.9 371.1 385.2 5059.4 5063.8 5097.5 5098.3 5117.3 4227.6 4754.9 4878.6 4910.5 4952.9 3791.9 4047.6 4305.8 4306.2 4376.9 3942.2 4203.5 4271.9 4378.6 4401.8 3511.6 3648.6 3844.3 4396.5 4426.7 4315.5 4327.1 4332.7 4339.9 4355.6 3363.6 3964.0 4142.6 4146.6 4154.8 4458.0 4646.0 4978.0 5000.4 5069.5 4189.6 4300.6 4726.6 5014.4 5057.5 5025.8 5396.9 5448.5 5453.8 5457.9 4239.6 4327.8 4576.9 4651.2 4667.6 5405.6 5701.0 5709.3 5723.8 5763.8 4778.5 4780.9 4805.5 4807.6 4836.5 4052.2 4394.8 4889.3 4894.5 4901.6 4403.3 4706.5 4718.7 4733.2 4745.8 4572.8 4931.0 5266.6 5309.4 5448.2 1374.3 1418.4 1520.9 1522.3 1525.3 1083.0 1088.8 1135.8 1137.2 1151.9 4319.5 4536.3 4883.7 4944.9 4995.8 4630.9 4866.4 5088.8 5468.6 5484.5 4530.6 4577.9 4597.4 4701.5 4704.0 2502.5 2663.4 2774.8 2811.7 2858.0 4641.3 5076.8 5313.7 5644.7 5679.8 4718.5 5122.1 5165.0 5580.8 5612.9 4911.7 5215.4 5431.8 5490.3 5690.9 5101.0 5101.8 5216.7 5337.7 5402.0 4656.8 4857.6 4986.3 4997.7 5291.9 5279.9 5457.0 5535.1 5742.9 5797.7 3908.7 4128.8 4205.9 4229.5 4294.6 5004.2 5053.1 5111.6 5448.8 5520.8 4397.9 5171.7 5207.3 5273.8 5364.8 4586.7 4800.3 4893.0 5267.3 5277.7 4866.8 5123.5 5129.9 5156.3 5210.8 4809.0 5201.8 5271.6 5272.9 5283.5 4404.7 4928.7 5050.7 5202.7 5243.6 10:04:10 INFO - PROCESS | 1638 | 10:04:10 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:19503.34 Median:4205.23 stddev:27140.69 (645.4%) stddev-sans-first:27179.86 10:04:10 INFO - PROCESS | 1638 | Values: 638.8 734.5 752.7 819.0 837.1 15217.0 15934.8 16308.1 16344.3 16357.4 14834.7 14946.7 14976.1 15002.0 15435.8 941.3 954.4 958.8 960.9 962.2 958.1 958.4 959.1 959.3 959.6 939.7 967.9 1000.9 1002.2 1004.0 989.1 996.6 998.3 999.4 999.5 734.5 737.2 737.8 738.3 738.8 14222.6 14613.6 15952.6 15987.0 16000.9 14139.3 14449.6 14537.6 14573.9 14599.9 11718.5 11747.3 11967.9 11971.1 12064.5 3619.1 3740.8 3751.4 3881.8 3896.7 99121.5 101528.5 107177.9 107203.5 107773.0 10904.3 10923.2 10934.3 10969.3 11033.0 23122.0 23169.4 23393.8 23413.1 23494.4 7378.0 7731.4 7820.0 7822.5 7994.3 4157.6 4252.9 4260.6 4284.4 4302.9 3292.6 3377.6 3381.7 3389.5 3391.7 605.1 605.2 609.5 617.8 628.2 607.8 613.2 637.5 641.4 653.9 9220.9 9679.5 9819.0 10759.9 11211.7 552.6 592.5 598.4 630.9 714.1 4309.8 4496.0 4666.3 5044.3 5061.5 73671.6 91841.0 95626.0 96470.6 96829.0 63110.4 64075.4 88853.6 89261.6 91479.1 53692.9 65467.7 69279.0 70773.3 71332.6 10848.3 11048.4 11050.2 11122.4 11187.2 40577.8 48314.3 48953.5 51052.0 53120.5 64553.4 64886.4 65772.7 66489.0 67503.7 12046.8 12111.3 12466.8 12527.5 12791.6 14251.4 14313.5 14530.4 14593.8 14609.9 12922.7 13128.3 13329.7 13669.5 13982.4 12956.4 12986.4 13595.3 13972.7 14082.5 12747.9 13241.3 13344.5 13385.4 13822.4 9217.0 9478.0 9676.0 9877.4 10082.5 9087.0 11116.3 11282.1 11393.8 11430.3 10:04:10 INFO - PROCESS | 1638 | -------- Summary: end -------- 10:04:10 INFO - PROCESS | 1638 | 10:04:11 INFO - TEST-INFO | 1638: exit 0 10:04:11 INFO - TEST-OK | dromaeo_css | took 1120945ms 10:04:11 INFO - TEST-START | kraken 10:04:11 INFO - operating with platform_type : mac_ 10:04:11 INFO - Initialising browser for kraken test... 10:04:11 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmptu1TWf/profile http://localhost:49236/getInfo.html) 10:04:13 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 10:04:13 INFO - PROCESS | 1645 | colorDepth:24 10:04:13 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 10:04:13 INFO - PROCESS | 1645 | __metrics 10:04:13 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:04:13 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:04:14 INFO - TEST-INFO | 1645: exit 0 10:04:14 INFO - Browser initialized. 10:04:14 INFO - Running cycle 1/1 for kraken test... 10:04:14 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmptu1TWf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:04:26 INFO - PROCESS | 1646 | RSS: Main: 215027712 10:04:26 INFO - PROCESS | 1646 | 10:04:29 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 10:04:30 INFO - PROCESS | 1646 | RSS: Main: 268414976 10:04:30 INFO - PROCESS | 1646 | 10:04:32 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 10:04:33 INFO - PROCESS | 1646 | RSS: Main: 279535616 10:04:33 INFO - PROCESS | 1646 | 10:04:36 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 10:04:37 INFO - PROCESS | 1646 | RSS: Main: 377393152 10:04:37 INFO - PROCESS | 1646 | 10:04:39 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 10:04:40 INFO - PROCESS | 1646 | RSS: Main: 278695936 10:04:40 INFO - PROCESS | 1646 | 10:04:42 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:04:42 INFO - PROCESS | 1646 | RSS: Main: 280096768 10:04:42 INFO - PROCESS | 1646 | 10:04:45 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 10:04:46 INFO - PROCESS | 1646 | RSS: Main: 320831488 10:04:46 INFO - PROCESS | 1646 | 10:04:49 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 10:04:50 INFO - PROCESS | 1646 | RSS: Main: 321474560 10:04:50 INFO - PROCESS | 1646 | 10:04:52 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 10:04:53 INFO - PROCESS | 1646 | RSS: Main: 317657088 10:04:53 INFO - PROCESS | 1646 | 10:04:55 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:04:55 INFO - PROCESS | 1646 | RSS: Main: 295608320 10:04:55 INFO - PROCESS | 1646 | 10:04:57 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:04:58 INFO - PROCESS | 1646 | RSS: Main: 298164224 10:04:58 INFO - PROCESS | 1646 | 10:05:00 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:05:01 INFO - PROCESS | 1646 | RSS: Main: 306069504 10:05:01 INFO - PROCESS | 1646 | 10:05:04 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:05:04 INFO - PROCESS | 1646 | RSS: Main: 318455808 10:05:04 INFO - PROCESS | 1646 | 10:05:07 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:05:08 INFO - PROCESS | 1646 | RSS: Main: 312266752 10:05:08 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 10:05:10 INFO - PROCESS | 1646 | RSS: Main: 356941824 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | __start_tp_report 10:05:10 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 10:05:10 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 10:05:10 INFO - PROCESS | 1646 | |i|pagename|runs| 10:05:10 INFO - PROCESS | 1646 | |0;ai-astar;117;114;115;114;114;118;92;157;172;169 10:05:10 INFO - PROCESS | 1646 | |1;audio-beat-detection;139;111;110;142;111;110;108;108;128;108 10:05:10 INFO - PROCESS | 1646 | |2;audio-dft;185;187;185;184;185;185;187;184;185;185 10:05:10 INFO - PROCESS | 1646 | |3;audio-fft;82;59;61;92;63;60;63;78;101;70 10:05:10 INFO - PROCESS | 1646 | |4;audio-oscillator;84;71;114;62;72;100;116;82;68;83 10:05:10 INFO - PROCESS | 1646 | |5;imaging-gaussian-blur;90;90;93;89;90;89;90;90;90;90 10:05:10 INFO - PROCESS | 1646 | |6;imaging-darkroom;145;135;96;97;97;96;97;96;97;97 10:05:10 INFO - PROCESS | 1646 | |7;imaging-desaturate;88;84;84;86;86;86;85;84;86;84 10:05:10 INFO - PROCESS | 1646 | |8;json-parse-financial;65;61;61;62;63;67;65;66;64;66 10:05:10 INFO - PROCESS | 1646 | |9;json-stringify-tinderbox;60;58;64;63;61;58;59;59;58;62 10:05:10 INFO - PROCESS | 1646 | |10;stanford-crypto-aes;83;86;82;89;85;89;101;85;88;89 10:05:10 INFO - PROCESS | 1646 | |11;stanford-crypto-ccm;143;125;135;144;141;149;131;140;172;141 10:05:10 INFO - PROCESS | 1646 | |12;stanford-crypto-pbkdf2;165;165;165;221;209;164;166;164;162;171 10:05:10 INFO - PROCESS | 1646 | |13;stanford-crypto-sha256-iterative;66;63;63;65;65;64;67;73;81;82 10:05:10 INFO - PROCESS | 1646 | __end_tp_report 10:05:10 INFO - PROCESS | 1646 | __start_cc_report 10:05:10 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,2152 10:05:10 INFO - PROCESS | 1646 | __end_cc_report 10:05:10 INFO - PROCESS | 1646 | __startTimestamp1491843910599__endTimestamp 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | ------- Summary: start ------- 10:05:10 INFO - PROCESS | 1646 | Number of tests: 14 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | [#0] ai-astar Cycles:10 Average:128.20 Median:137.50 stddev:27.35 (19.9%) stddev-sans-first:28.71 10:05:10 INFO - PROCESS | 1646 | Values: 117.0 114.0 115.0 114.0 114.0 118.0 92.0 157.0 172.0 169.0 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | [#1] audio-beat-detection Cycles:10 Average:117.50 Median:111.00 stddev:13.50 (12.2%) stddev-sans-first:11.87 10:05:10 INFO - PROCESS | 1646 | Values: 139.0 111.0 110.0 142.0 111.0 110.0 108.0 108.0 128.0 108.0 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | [#2] audio-dft Cycles:10 Average:185.20 Median:185.00 stddev:1.03 (0.6%) stddev-sans-first:1.09 10:05:10 INFO - PROCESS | 1646 | Values: 185.0 187.0 185.0 184.0 185.0 185.0 187.0 184.0 185.0 185.0 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | [#3] audio-fft Cycles:10 Average:72.90 Median:66.50 stddev:14.79 (22.2%) stddev-sans-first:15.32 10:05:10 INFO - PROCESS | 1646 | Values: 82.0 59.0 61.0 92.0 63.0 60.0 63.0 78.0 101.0 70.0 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | [#4] audio-oscillator Cycles:10 Average:85.20 Median:71.50 stddev:18.93 (26.5%) stddev-sans-first:20.07 10:05:10 INFO - PROCESS | 1646 | Values: 84.0 71.0 114.0 62.0 72.0 100.0 116.0 82.0 68.0 83.0 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:90.10 Median:90.00 stddev:1.10 (1.2%) stddev-sans-first:1.17 10:05:10 INFO - PROCESS | 1646 | Values: 90.0 90.0 93.0 89.0 90.0 89.0 90.0 90.0 90.0 90.0 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | [#6] imaging-darkroom Cycles:10 Average:105.30 Median:97.00 stddev:18.45 (19.0%) stddev-sans-first:12.80 10:05:10 INFO - PROCESS | 1646 | Values: 145.0 135.0 96.0 97.0 97.0 96.0 97.0 96.0 97.0 97.0 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:86.00 stddev:1.34 (1.6%) stddev-sans-first:1.00 10:05:10 INFO - PROCESS | 1646 | Values: 88.0 84.0 84.0 86.0 86.0 86.0 85.0 84.0 86.0 84.0 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:65.00 stddev:2.16 (3.3%) stddev-sans-first:2.26 10:05:10 INFO - PROCESS | 1646 | Values: 65.0 61.0 61.0 62.0 63.0 67.0 65.0 66.0 64.0 66.0 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.50 stddev:2.20 (3.6%) stddev-sans-first:2.33 10:05:10 INFO - PROCESS | 1646 | Values: 60.0 58.0 64.0 63.0 61.0 58.0 59.0 59.0 58.0 62.0 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:87.00 stddev:5.31 (6.1%) stddev-sans-first:5.36 10:05:10 INFO - PROCESS | 1646 | Values: 83.0 86.0 82.0 89.0 85.0 89.0 101.0 85.0 88.0 89.0 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:142.10 Median:142.00 stddev:12.56 (8.8%) stddev-sans-first:13.31 10:05:10 INFO - PROCESS | 1646 | Values: 143.0 125.0 135.0 144.0 141.0 149.0 131.0 140.0 172.0 141.0 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:165.50 stddev:21.29 (12.9%) stddev-sans-first:22.26 10:05:10 INFO - PROCESS | 1646 | Values: 165.0 165.0 165.0 221.0 209.0 164.0 166.0 164.0 162.0 171.0 10:05:10 INFO - PROCESS | 1646 | 10:05:10 INFO - PROCESS | 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:66.50 stddev:7.23 (10.9%) stddev-sans-first:7.60 10:05:10 INFO - PROCESS | 1646 | Values: 66.0 63.0 63.0 65.0 65.0 64.0 67.0 73.0 81.0 82.0 10:05:10 INFO - PROCESS | 1646 | -------- Summary: end -------- 10:05:10 INFO - PROCESS | 1646 | 10:05:11 INFO - TEST-INFO | 1646: exit 0 10:05:11 INFO - TEST-OK | kraken | took 60180ms 10:05:11 INFO - SUITE-END | took 1181s 10:05:11 INFO - Completed test suite (00:19:41) 10:05:11 INFO - javascript benchmark 10:05:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7586.834664101179, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3360.126367144542, 3400.625931147575, 3401.574803149607, 3430.176757578182, 3447.6897079262862, 5934.88130237395, 5949.524038076955, 5967.343592204854, 5967.970160149199, 5996.22049133612, 5260.763265653045, 5627.409122042188, 5849.181114643953, 5936.673482958436, 5957.076653118774, 5331.493508116722, 5356.571474282048, 5366.543843773284, 5440.102383106782, 5475.917861232085, 5273.288106105665, 5281.392639846409, 5317.601179911503, 5442.591805614575, 5470.9452905470835, 5349.117395629716, 5374.167004114369, 5384.0, 5385.892282154355, 5396.0, 5345.973270133644, 5346.732663366816, 5348.839534813962, 5373.623846330758, 5379.112446446332, 5207.557357624606, 5313.229581710669, 5316.175992721134, 5316.840494785162, 5376.166694162411, 5900.734466948998, 5915.497182739429, 5923.526117910556, 5926.436988486087, 5928.525717942554, 6283.8743225135695, 6296.244450665904, 6298.338674439164, 6299.0, 6311.211098612674, 6262.248530176408, 6320.873582528369, 6346.365363463663, 6366.649834259114, 6374.394432528902, 5949.0, 6011.188489553898, 6020.397960203988, 6027.668478233695, 6062.757489700407, 6270.6864656766975, 6422.678866056678, 6449.613023218575, 6458.54790164684, 6467.450266727381, 6192.071189321638, 6205.317415084337, 6286.151369565142, 6317.1156038154695, 6364.109024736541, 5359.866003349947, 6277.05844123376, 6327.683615819191, 6360.618362898241, 6376.043593461018, 5756.02147634903, 6344.143540621957, 6374.649394283266, 6378.97936330185, 6402.231732192166, 6116.143739876421, 6245.375462453719, 6282.434580887742, 6318.178636777281, 6351.333110023381, 5108.770105345268, 5160.509751573594, 5175.9741201293755, 5181.4041385240425, 5186.533212010937, 4533.727976321431, 4540.250858608292, 4548.590626843534, 4552.362669226311, 4558.042811009659, 5918.763249469973, 5930.28836539618, 5976.820695379146, 5991.221141251609, 5992.31088424828, 6270.498360131179, 6284.937150628527, 6313.873722525524, 6327.367263273636, 6337.4613157882095, 5964.970175149097, 5997.280326360865, 5997.940020599825, 6000.489958353502, 6013.609115407485, 5574.721263936787, 5606.215129881738, 5614.129809879475, 5620.606557540933, 5629.268195134607, 6272.749090036347, 6328.14570033058, 6345.555811093363, 6449.967750161407, 6508.316626754123, 6295.244570651551, 6316.968415157895, 6321.431071203614, 6330.3669633036325, 6343.0, 6476.708548115252, 6481.0, 6482.35176482348, 6496.837579060561, 6497.415232629086, 6179.474744646755, 6334.524910631629, 6338.112664227104, 6397.424231819158, 6414.647194404352, 6275.34108918557, 6332.746690132527, 6335.0, 6349.14286571307, 6361.61830290184, 6257.186565746424, 6334.746610135543, 6340.904886426615, 6342.23893132827, 6343.3022367538615, 6113.633182009094, 6195.597286176384, 6364.172657554488, 6368.331325210786, 6372.139761132188, 5111.233315002779, 5771.769129234784, 5907.143464197753, 6035.18524999137, 6214.0, 5534.612577119724, 5578.525825304894, 6148.139260503623, 6152.892479353759, 6172.444479996821, 6005.969970150121, 6016.067509536029, 6018.8194354169445, 6031.125486804301, 6108.099244189703], "value": 5826.388697873687, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.2518392360366, 811.1563973467594, 873.8252349530096, 890.2699786175335, 893.5398269891003, 40281.1943761125, 40821.183576328454, 40878.591214087886, 40960.59039409605, 42554.787226063825, 41796.0, 41812.58187418125, 41939.58060419402, 42076.0, 42124.578754212445, 7387.704491820321, 7566.167721550625, 9263.768405789859, 9431.0, 9440.0, 10870.782584348308, 11136.554537818478, 11224.887751122465, 11244.831327530093, 11250.156238282121, 11107.055900248448, 11127.888721112806, 11145.72135696606, 11146.665600032013, 11188.832167517494, 7349.706011759523, 11082.722931926686, 11085.556577736881, 11092.944535277353, 11106.389148596823, 7433.628318584049, 10714.089302409304, 10961.780764384748, 11084.722881927935, 11093.83359249612, 39487.60512394897, 39548.406773898416, 39551.20897582061, 39732.00669983245, 39789.80105099485, 3829.291581057513, 3868.3617203161443, 3870.9419358709642, 3892.338302488555, 3915.23652887687, 27111.593326100123, 28330.150095497167, 28934.131976040753, 29309.853450732822, 29491.410171796444, 3027.5004624236976, 3043.360894212218, 3047.801892876956, 3068.892588759405, 3119.2357872321427, 320518.0, 322084.0, 322231.0, 322327.0, 322865.0, 41948.79025604852, 43207.35188972168, 44709.10581788346, 52218.0, 52279.0, 51582.226266606034, 53534.66163345947, 56953.71523142441, 57238.0, 57466.425335746106, 12353.38233088342, 14568.344424500923, 14703.191324477257, 14817.185054822088, 14818.555443336718, 12706.30115343665, 12722.109452338247, 12728.554500592434, 12733.936330318475, 12736.426860791287, 12672.80990785139, 12785.936070319589, 12791.360431978364, 12843.678908027186, 12899.742005160031, 4439.089986552749, 5455.18172274162, 5491.395946445888, 5493.807716729975, 5500.752466138953, 1102.448775612194, 1115.1023426141871, 1118.5749415222162, 1164.8427462292652, 1180.976380472403, 19684.114214860365, 25900.611490827654, 26499.337516562242, 26655.600165997526, 26695.33261668435, 1242.142921384242, 1242.726600147966, 1255.9686007849693, 1276.2215048820212, 1297.5653156192782, 13393.464261429628, 13702.52041178574, 13776.448942042207, 13800.792988105186, 13810.723785524233, 457864.0, 458073.0, 458242.0, 458781.0, 459168.0, 56209.437905621235, 56291.71854140785, 56332.0, 56408.71795641078, 56412.71793641005, 1431.7196905829228, 1435.2608406670572, 1484.0, 1484.2949598940418, 1484.3765618440282, 21195.93518918017, 24628.630570541816, 25046.49907001886, 25938.962441502885, 26023.73976260289, 43114.137717244856, 43198.0, 43266.918327042076, 43268.35097473478, 43377.0, 428657.0, 429092.0, 430759.0, 431062.0, 431499.0, 28375.57436638411, 29019.12942611725, 29139.7086029137, 33978.0, 35369.0, 42288.13559322034, 42940.35589466098, 42958.78520607502, 43565.12869742651, 45018.0, 28459.43081138407, 28767.424651506433, 29391.118266452042, 29939.0, 31006.534901976036, 30193.547096793125, 30964.535531966587, 31194.844025779727, 31503.52744708877, 31523.684763152076, 36430.63569364379, 36457.270854583294, 36480.63519364772, 36868.0, 36937.44593831148, 25400.0, 26221.606675899497, 30311.545326820557, 31139.377212455172, 31457.21356966094, 23393.06427742871, 24150.87924560366, 24390.51218975646, 24761.752382476665, 24793.876030619733], "value": 18699.530660859262, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.53087233575064, 409.76233784405065, 423.92369373512764, 432.3579484465568, 449.82007197121146, 12821.038422118332, 14738.778918316233, 20881.895590522025, 21299.25452609159, 21350.0, 21442.0, 21804.127834886585, 22040.559188816253, 22056.558868822653, 22712.43218919528, 5600.719964001804, 6454.451371633393, 6682.164729408824, 6741.494387920901, 6761.864762704743, 5745.368009518949, 6364.33174516676, 6589.308122647101, 6694.698738556776, 6696.0, 6693.966530167342, 6696.631685257309, 6696.631685257309, 6706.429953453938, 6708.664566771667, 6405.615663060231, 6584.308647591982, 6658.633775142365, 6669.699863506154, 6678.998150277449, 6410.326915673833, 6514.739410423577, 6515.153030106103, 6517.967410162919, 6519.380658837403, 25874.87062564694, 26202.95188192489, 26302.0, 26492.205233843015, 26594.468110637874, 2849.244950088228, 2906.1862678449857, 2917.343597690524, 2933.8973135940355, 2949.3069128754723, 15731.921340393339, 15807.841921580752, 16319.592010199722, 16377.83622163775, 16478.928869623556, 1668.816430192678, 1974.0, 2020.6261841559358, 2031.14691829432, 2052.1893851928553, 127943.0, 128319.35840320832, 132505.337473312, 151451.0, 155168.99999999886, 19723.53455818023, 20221.29225477101, 20266.493337666383, 20284.695729563773, 20464.59070818575, 32556.837215813768, 33043.83478082642, 33104.0, 33129.17177070544, 33179.00462986114, 8012.921563586751, 10058.994100589882, 10244.897551024544, 10433.321834080913, 10445.529951152217, 8462.153784621489, 8487.321014318839, 8499.490030598185, 8648.394612377073, 8668.089850565726, 9213.81572368549, 9307.76730581741, 9348.90651093494, 9424.481653509123, 9451.905480945103, 3277.410066188109, 3371.544841446422, 3929.626685464876, 3936.842526298973, 3953.565107838193, 878.1789027259533, 879.595386122378, 901.7970956534728, 911.4166933162782, 930.6463543853292, 20987.8950605246, 21536.353909382742, 21567.0, 21577.352679419644, 21581.81300028482, 800.3837045475068, 886.1271647427279, 925.2782829393084, 932.9720108396759, 971.3394891473865, 10336.793264134676, 10737.946310268555, 10776.461176941122, 10821.0, 10833.0, 776010.0, 779802.0, 787769.0, 793122.0, 810766.0, 88745.0, 136247.0, 141868.0, 147436.0, 153718.0, 1126.2116518437126, 1196.9341686207167, 1225.2587184753097, 1226.926384416938, 1227.9570215042431, 14672.3397447113, 19827.603447931248, 19999.500012499804, 20272.290469833486, 20510.974451277674, 41341.793291033355, 62632.0, 63753.362466376595, 63842.0, 66013.00980485392, 86476.78470545288, 87141.56429217814, 96154.51922740342, 97222.5138874301, 99429.0, 10801.621943231949, 11131.220814542905, 11408.429578521207, 11701.824472633087, 11811.527538898692, 12287.631371058882, 12299.44652490658, 12345.691357716129, 12487.06347023995, 12669.429875655433, 10567.418791966516, 10804.405757683402, 10904.890951090387, 11089.112870970464, 11105.944470277596, 8359.749207523784, 9635.421874687541, 9972.30193886421, 10093.747656308651, 10095.242856785624, 9023.323250756352, 10806.675799726021, 10806.729831754268, 10808.513616887101, 10881.238313317992, 11116.777664446827, 11123.443827808416, 11123.499442524944, 11457.369844658624, 11492.85071492844, 8531.061583225965, 10255.794884102426, 10432.321899076536, 10576.788464230825, 10640.201984851012], "value": 11191.59123134583, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1372.334417807364, 1544.9613759656, 1548.736714758491, 1562.2188905547227, 1572.0724772384292, 10137.189024878011, 10642.467876606168, 10662.573497060119, 10669.146468282539, 10671.679849604505, 10407.167426605873, 10609.416482093482, 10916.290441121342, 11092.223544351898, 11102.389368584725, 2387.808975281978, 2430.307362401716, 2434.4157402223427, 2434.9878250608813, 2435.5265064635905, 2325.790678838904, 2397.712274527059, 2422.9757702422926, 2423.418379588895, 2423.9515209695796, 2342.6603142544323, 2409.542186984467, 2415.7342692303832, 2420.225527831095, 2425.333033415807, 2319.3158018384615, 2397.2328854766392, 2397.6283676030243, 2402.2192787344184, 2408.0127147869466, 1978.5449346650207, 1987.8906660133687, 1993.6909778984282, 2266.7193035934642, 2380.3811009137576, 10155.441450720207, 10966.506507207196, 10983.780324393547, 10988.505517251742, 10990.780184396348, 5514.862128446821, 5579.860503487445, 5742.0, 5744.942550574483, 5773.509251713609, 3357.244619960514, 3538.681518663307, 3607.5851277103206, 3611.783293002402, 3619.0771353305004, 1126.594426006637, 1183.9052875769917, 1188.102982248397, 1191.195942738648, 1211.1219365959696, 13449.462021519128, 13815.309234538232, 14814.333354999158, 15167.0, 15192.924035379863, 4733.408323959505, 4941.703497790144, 4981.0, 5001.299818025406, 5007.84976450707, 12852.678683032998, 12862.421191046426, 12886.420111095023, 12889.0, 12906.612801615967, 4921.311016457699, 5935.7625694972585, 5971.313298970674, 5974.61165024272, 5989.0, 4164.9583504165175, 5313.282706834528, 5316.946830531722, 5320.095583750772, 5323.86690332737, 3308.3714094322004, 3803.201327721154, 3833.7316387852584, 3942.64516193544, 4023.959760402417, 972.3436680240951, 1121.0, 1128.7686024364984, 1136.8976792088752, 1149.5804031528432, 922.7001224602031, 936.7892224249625, 942.5522876633544, 945.664289177346, 993.2203220471895, 6340.619562826153, 6359.077933699587, 6439.8068057958335, 6500.252470965805, 6599.175103112111, 1009.3641006166215, 1018.567108978696, 1026.8037736037434, 1027.1012863744224, 1032.5921261101823, 3707.4994875691978, 4022.8189731462153, 4081.73463727634, 4143.295639741251, 4158.91682166361, 13066.150700204456, 13587.0, 13639.181649101085, 13650.726985460235, 13682.863171368159, 13494.98787590915, 13506.662333441742, 13512.662183445296, 13535.86464135346, 13550.86449135496, 9617.807643847083, 9972.152367048737, 9990.950045249727, 10225.693229203136, 10286.022827831195, 6443.355664433707, 6506.967465162644, 6512.144038948877, 6679.6994135265, 6680.3987641112535, 11944.581939632419, 12101.818472723093, 12139.696507587028, 12321.137520373488, 12321.383930803604, 12172.695682608006, 12263.9386803069, 12277.508899643914, 12298.954588860048, 12343.012558995079, 8720.694775683074, 8726.3891527595, 8823.250023747796, 8896.243819275434, 8927.687530936386, 9760.365576237522, 9918.851217231602, 9971.601135954481, 9991.700248992542, 9995.450250236307, 8417.200365965224, 8508.361872859685, 8535.786605334668, 8539.231469167804, 8553.486790792573, 8083.827844962565, 8206.22040906113, 8271.669133234604, 8277.296429803293, 8324.500529968222, 6431.356864313719, 7573.431992600687, 7578.924210758042, 7585.279398457138, 7587.734429294948, 5514.365847927379, 5550.195221692913, 5695.2311437957005, 5696.287964004499, 5765.682887441231, 5644.52021578154, 5675.375708671961, 5744.971275143765, 5752.827415177551, 5837.182794408871], "value": 5066.556393559705, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.1905042230614, 363.4403019350201, 368.8690514867222, 371.10378436076877, 385.2180074448869, 5059.418166910807, 5063.8480845574595, 5097.515736005078, 5098.31172791673, 5117.283580298761, 4227.577242275771, 4754.90490190196, 4878.585320247783, 4910.508949105088, 4952.876178095549, 3791.9431208531896, 4047.615476529725, 4305.806238719258, 4306.2033523798045, 4376.934345984812, 3942.206296135266, 4203.5376108628025, 4271.9359209611885, 4378.64970802335, 4401.779911004453, 3511.613722490524, 3648.5986541480415, 3844.2503711776217, 4396.450443694538, 4426.734395936254, 4315.525292217853, 4327.069680018811, 4332.72878976374, 4339.913201735963, 4355.564443555619, 3363.5963684358035, 3963.978371030766, 4142.565030671766, 4146.569433545422, 4154.792260386968, 4457.9969506861025, 4645.953540464619, 4977.975110124463, 5000.399952005746, 5069.518395752398, 4189.581041895816, 4300.61294483498, 4726.621870250372, 5014.398272207322, 5057.4689657585795, 5025.773840177201, 5396.892062158774, 5448.536874365684, 5453.754581043862, 5457.935702538007, 4239.554846741079, 4327.783610819446, 4576.880807273447, 4651.232546629802, 4667.556582124693, 5405.567524605944, 5701.0046483730775, 5709.343425506078, 5723.799667011676, 5763.769449221984, 4778.474367819537, 4780.948191397887, 4805.51029180955, 4807.567318941312, 4836.516348365135, 4052.176520565757, 4394.846180383735, 4889.339939108175, 4894.510548945077, 4901.632377571696, 4403.295472724413, 4706.529347065334, 4718.711424958892, 4733.242681170997, 4745.816924214831, 4572.8399506017795, 4931.027588964371, 5266.578673706094, 5309.36287645485, 5448.237246785453, 1374.3265799758046, 1418.4184484361363, 1520.908745475275, 1522.2921341575998, 1525.2831169350388, 1083.014456844268, 1088.7805657663469, 1135.8239472881717, 1137.1634809782142, 1151.8596589376457, 4319.4816622005565, 4536.296873984537, 4883.658143929962, 4944.90110197794, 4995.775190116381, 4630.86107416778, 4866.372159034153, 5088.837244171213, 5468.562514998791, 5484.533814625802, 4530.637548996073, 4577.885552861138, 4597.356370108188, 4701.48283688794, 4703.96594196748, 2502.524520341166, 2663.387420893205, 2774.820701201981, 2811.655991493107, 2858.0279607071056, 4641.286970423647, 5076.847694569169, 5313.671582104474, 5644.717764111778, 5679.829605111853, 4718.531879068882, 5122.081607570211, 5164.967273698746, 5580.776768929279, 5612.85967850807, 4911.673848061004, 5215.426303106655, 5431.805002899356, 5490.258815059916, 5690.902550163875, 5100.979804039282, 5101.821436249714, 5216.686998780086, 5337.6797392156595, 5402.000629883484, 4656.790444429941, 4857.587105096107, 4986.276989836381, 4997.675151115166, 5291.947080529146, 5279.868003299948, 5457.017736807413, 5535.11438169907, 5742.856428589319, 5797.710114494174, 3908.687305015535, 4128.8348466061025, 4205.873823785291, 4229.488358822758, 4294.613484786384, 5004.2493625956395, 5053.117137265877, 5111.565516931102, 5448.828501872116, 5520.806771762969, 4397.868063958086, 5171.741412929263, 5207.3230480037355, 5273.789048438173, 5364.8122315718765, 4586.656000799887, 4800.2876576873405, 4892.951070489249, 5267.262583238273, 5277.736113194402, 4866.8076321301505, 5123.487651234846, 5129.923051154161, 5156.303898973741, 5210.843674689765, 4809.0, 5201.817936372209, 5271.630985831109, 5272.860804268631, 5283.471652834686, 4404.7357158570585, 4928.67963582366, 5050.747462626928, 5202.658803538498, 5243.606729495227], "value": 4117.1796670316835, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.8083574927521, 734.4895297768053, 752.6575408189275, 818.9672413103475, 837.0917554453416, 15217.0, 15934.760978585331, 16308.103054332036, 16344.264508097134, 16357.427490037837, 14834.703305933901, 14946.701065978674, 14976.101433913946, 15002.0, 15435.768463473029, 941.3081385181903, 954.3891909178132, 958.832204364237, 960.8798900137483, 962.1533050915185, 958.0802429667527, 958.3818437108062, 959.0505399654327, 959.3428501476476, 959.6161535385867, 939.6945992552413, 967.9080487353725, 1000.9449480278582, 1002.1982414068752, 1004.036125319694, 989.0949780950422, 996.6013594562161, 998.3211416236956, 999.4153420249163, 999.4902599674145, 734.5262305812721, 737.1522748838825, 737.8266107464741, 738.3207449146798, 738.7857521318865, 14222.644433889236, 14613.561593152222, 15952.601184970468, 15986.96084754499, 16000.919995399949, 14139.293035348293, 14449.638759031108, 14537.636559086108, 14573.854261457356, 14599.854001459955, 11718.472668729843, 11747.29516229029, 11967.940160299142, 11971.102167337485, 12064.517419303218, 3619.1133172372674, 3740.812959352049, 3751.4372844073337, 3881.8059097045316, 3896.6687831534355, 99121.50439247758, 101528.49235753847, 107177.92822071757, 107203.46398268036, 107773.0, 10904.291221070605, 10923.235373523936, 10934.343939363664, 10969.341839489656, 11033.0, 23122.0, 23169.420764480685, 23393.7660623395, 23413.06347746071, 23494.412639684146, 7377.9631101845225, 7731.381489480828, 7820.0, 7822.530648161015, 7994.320482758914, 4157.605027522381, 4252.851150209728, 4260.637845783143, 4284.443022407129, 4302.935455968163, 3292.6378098409155, 3377.59468863733, 3381.6956473917467, 3389.508521264403, 3391.7117045050954, 605.1346574398652, 605.2222893581726, 609.4880300547472, 617.8084793713963, 628.2492421556196, 607.7629724407396, 613.2312608572299, 637.5027478566725, 641.3650486018901, 653.9247986481615, 9220.8616870747, 9679.516024198761, 9818.950905245429, 10759.946200269105, 11211.66365009051, 552.5524325296523, 592.5022980696236, 598.3896425645817, 630.9495240380759, 714.1430283659629, 4309.827606895752, 4496.033352829158, 4666.2767271073035, 5044.319016932741, 5061.493850614909, 73671.63164184253, 91841.0, 95626.04373956172, 96470.55294170593, 96829.0, 63110.36889631137, 64075.398115047494, 88853.55573222092, 89261.55369223242, 91479.08520914706, 53692.926141477736, 65467.67266163639, 69278.96081558781, 70773.29226707667, 71332.64333678299, 10848.349099054081, 11048.447577621248, 11050.226484146033, 11122.391090143827, 11187.160962927976, 40577.79711101425, 48314.275285871445, 48953.51046489632, 51051.97896042133, 53120.46879531155, 64553.35446645474, 64886.35113648803, 65772.67113664401, 66489.00266496104, 67503.66248168728, 12046.75906481883, 12111.273323600612, 12466.750664986468, 12527.503022854635, 12791.616251512469, 14251.429942802171, 14313.499027533986, 14530.418783248551, 14593.781093283396, 14609.931914298117, 12922.676933076747, 13128.277944713132, 13329.733405332034, 13669.521566745116, 13982.440702372198, 12956.35218239103, 12986.415611297703, 13595.320233988063, 13972.650683732989, 14082.507112251022, 12747.936260318638, 13241.271730054554, 13344.466221351426, 13385.397657105648, 13822.447102116204, 9217.018341056704, 9478.0, 9676.040517301455, 9877.40735555869, 10082.54628541732, 9087.0, 11116.277441966246, 11282.097432205568, 11393.772124557298, 11430.314181149157], "value": 7496.987533078307, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1467.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 115.0, 114.0, 114.0, 118.0, 92.0, 157.0, 172.0, 169.0], "value": 128.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 111.0, 110.0, 142.0, 111.0, 110.0, 108.0, 108.0, 128.0, 108.0], "value": 117.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 185.0, 184.0, 185.0, 185.0, 187.0, 184.0, 185.0, 185.0], "value": 185.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 61.0, 92.0, 63.0, 60.0, 63.0, 78.0, 101.0, 70.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 114.0, 62.0, 72.0, 100.0, 116.0, 82.0, 68.0, 83.0], "value": 85.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 93.0, 89.0, 90.0, 89.0, 90.0, 90.0, 90.0, 90.0], "value": 90.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 135.0, 96.0, 97.0, 97.0, 96.0, 97.0, 96.0, 97.0, 97.0], "value": 105.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 84.0, 86.0, 86.0, 86.0, 85.0, 84.0, 86.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 61.0, 61.0, 62.0, 63.0, 67.0, 65.0, 66.0, 64.0, 66.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 64.0, 63.0, 61.0, 58.0, 59.0, 59.0, 58.0, 62.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 82.0, 89.0, 85.0, 89.0, 101.0, 85.0, 88.0, 89.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 125.0, 135.0, 144.0, 141.0, 149.0, 131.0, 140.0, 172.0, 141.0], "value": 142.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 165.0, 221.0, 209.0, 164.0, 166.0, 164.0, 162.0, 171.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 63.0, 65.0, 65.0, 64.0, 67.0, 73.0, 81.0, 82.0], "value": 68.9, "unit": "score"}]}]} 10:05:11 INFO - Return code: 0 10:05:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:05:11 ERROR - # TBPL SUCCESS # 10:05:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:05:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:05:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:05:11 INFO - 2017-04-10 10:05:11.901 screenresolution[1650:9847] starting screenresolution argv=screenresolution get 10:05:11 INFO - 2017-04-10 10:05:11.904 screenresolution[1650:9847] Display 0: 1600x1200x32@60 10:05:11 INFO - 2017-04-10 10:05:11.910 screenresolution[1651:9850] starting screenresolution argv=screenresolution list 10:05:11 INFO - Available Modes on Display 0 10:05:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:05:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:05:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:05:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:05:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:05:12 INFO - Intel Iris: 10:05:12 INFO - Chipset Model: Intel Iris 10:05:12 INFO - Type: GPU 10:05:12 INFO - Bus: Built-In 10:05:12 INFO - VRAM (Dynamic, Max): 1536 MB 10:05:12 INFO - Vendor: Intel (0x8086) 10:05:12 INFO - Device ID: 0x0a2e 10:05:12 INFO - Revision ID: 0x0009 10:05:12 INFO - Displays: 10:05:12 INFO - Display: 10:05:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:05:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:05:12 INFO - Display Serial Number: 1600x1200 60 10:05:12 INFO - Main Display: Yes 10:05:12 INFO - Mirror: Off 10:05:12 INFO - Online: Yes 10:05:12 INFO - Rotation: Supported 10:05:12 INFO - Return code: 0 10:05:12 INFO - Running post-action listener: _resource_record_post_action 10:05:12 INFO - [mozharness: 2017-04-10 17:05:12.151076Z] Finished run-tests step (success) 10:05:12 INFO - Running post-run listener: _resource_record_post_run 10:05:12 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 12009472; Write bytes: 510713856; Read time: 127; Write time: 1583 10:05:12 INFO - TinderboxPrint: CPU usage
53.9% 10:05:12 INFO - TinderboxPrint: I/O read bytes / time
12,009,472 / 127 10:05:12 INFO - TinderboxPrint: I/O write bytes / time
510,713,856 / 1,583 10:05:12 INFO - TinderboxPrint: CPU idle
2,217.2 (46.1%) 10:05:12 INFO - TinderboxPrint: CPU system
961.3 (20.0%) 10:05:12 INFO - TinderboxPrint: CPU user
1,631.2 (33.9%) 10:05:12 INFO - TinderboxPrint: Swap in / out
246,878,208 / 0 10:05:12 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201961984; Write bytes: 199311360; Read time: 15620; Write time: 352 10:05:12 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 9969664; Write bytes: 300617728; Read time: 101; Write time: 1202 10:05:12 INFO - Running post-run listener: _upload_blobber_files 10:05:12 INFO - Blob upload gear active. 10:05:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:05:12 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 10:05:12 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:05:12 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:05:13 INFO - (blobuploader) - INFO - Open directory for files ... 10:05:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:05:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:05:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:05:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:05:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:05:15 INFO - (blobuploader) - INFO - Done attempting. 10:05:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:05:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:05:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:05:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:05:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:05:16 INFO - (blobuploader) - INFO - Done attempting. 10:05:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:05:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:05:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:05:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:05:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:05:17 INFO - (blobuploader) - INFO - Done attempting. 10:05:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:05:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:05:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:05:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:05:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:05:19 INFO - (blobuploader) - INFO - Done attempting. 10:05:19 INFO - (blobuploader) - INFO - Iteration through files over. 10:05:19 INFO - Return code: 0 10:05:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:05:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:05:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edaa0a30208cc6ad9ca2efad1bfcc7f5d53711aaf0e8fbe858f72ce7eca0cf598084904a180d2b9aa93a5e22fbb139e65bc52560a02531e69897367d24d7db94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d71571d1a7542343030a8b0543b53795657f67c1da3111afbf55b03bde2102ca31fcd1587cdb34b5b609e8dabe118a3b3a78217a0f1c8e816c6189fb51863bbb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d5b09aa9ef454c9a9141700c889e100b3e71535c061c70066b47ac62cbb5f46993359d70e2a6eb8fd714161d95a047246b5ea04cf62c29e31d2834c3840c7f42", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1af54ed52a72a2d5e736bf1131d20db70320a984daa3e0fb33a634bbf9aad7c66b62b8087a12a98ae294b73131d9f114113864a33c3da1db970dd66fc768da6c"} 10:05:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:05:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:05:19 INFO - Contents: 10:05:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edaa0a30208cc6ad9ca2efad1bfcc7f5d53711aaf0e8fbe858f72ce7eca0cf598084904a180d2b9aa93a5e22fbb139e65bc52560a02531e69897367d24d7db94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d71571d1a7542343030a8b0543b53795657f67c1da3111afbf55b03bde2102ca31fcd1587cdb34b5b609e8dabe118a3b3a78217a0f1c8e816c6189fb51863bbb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d5b09aa9ef454c9a9141700c889e100b3e71535c061c70066b47ac62cbb5f46993359d70e2a6eb8fd714161d95a047246b5ea04cf62c29e31d2834c3840c7f42", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1af54ed52a72a2d5e736bf1131d20db70320a984daa3e0fb33a634bbf9aad7c66b62b8087a12a98ae294b73131d9f114113864a33c3da1db970dd66fc768da6c"} 10:05:19 INFO - Running post-run listener: copy_logs_to_upload_dir 10:05:19 INFO - Copying logs to upload dir... 10:05:19 INFO - mkdir: /builds/slave/test/build/upload/logs 10:05:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.196167 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-10 10:05:19.088630) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 10:05:19.092544) ========= 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.yVePuqgP8F/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.cx7CHNroDC/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edaa0a30208cc6ad9ca2efad1bfcc7f5d53711aaf0e8fbe858f72ce7eca0cf598084904a180d2b9aa93a5e22fbb139e65bc52560a02531e69897367d24d7db94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d71571d1a7542343030a8b0543b53795657f67c1da3111afbf55b03bde2102ca31fcd1587cdb34b5b609e8dabe118a3b3a78217a0f1c8e816c6189fb51863bbb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d5b09aa9ef454c9a9141700c889e100b3e71535c061c70066b47ac62cbb5f46993359d70e2a6eb8fd714161d95a047246b5ea04cf62c29e31d2834c3840c7f42", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1af54ed52a72a2d5e736bf1131d20db70320a984daa3e0fb33a634bbf9aad7c66b62b8087a12a98ae294b73131d9f114113864a33c3da1db970dd66fc768da6c"} build_url:https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011217 build_url: 'https://queue.taskcluster.net/v1/task/GgtkDbNXSrS0DJabH-4X7w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edaa0a30208cc6ad9ca2efad1bfcc7f5d53711aaf0e8fbe858f72ce7eca0cf598084904a180d2b9aa93a5e22fbb139e65bc52560a02531e69897367d24d7db94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d71571d1a7542343030a8b0543b53795657f67c1da3111afbf55b03bde2102ca31fcd1587cdb34b5b609e8dabe118a3b3a78217a0f1c8e816c6189fb51863bbb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d5b09aa9ef454c9a9141700c889e100b3e71535c061c70066b47ac62cbb5f46993359d70e2a6eb8fd714161d95a047246b5ea04cf62c29e31d2834c3840c7f42", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1af54ed52a72a2d5e736bf1131d20db70320a984daa3e0fb33a634bbf9aad7c66b62b8087a12a98ae294b73131d9f114113864a33c3da1db970dd66fc768da6c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 10:05:19.127919) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 10:05:19.128286) ========= 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.yVePuqgP8F/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.cx7CHNroDC/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005129 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 10:05:19.178842) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 10:05:19.179261) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 10:05:19.179656) ========= ========= Total master_lag: 0.27 =========