builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0388 starttime: 1486202626.97 results: success (0) buildid: 20170204004003 builduid: a0b282ac65b7435ebb3df9b2c44e96c6 revision: 38f8808782c3209051b97de2ae3ea8c6a535e01a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-04 02:03:46.971497) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-04 02:03:46.971958) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-04 02:03:46.972242) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-04 02:03:47.008054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 02:03:47.008418) ========= 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.K8mVeOhn8K/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.yz6NthnOtp/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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-02-04 02:03:47-- 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% 18.3M=0.001s 2017-02-04 02:03:47 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.644680 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 02:03:47.681589) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 02:03:47.681934) ========= 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.K8mVeOhn8K/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.yz6NthnOtp/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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.025364 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 02:03:47.731581) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 02:03:47.731940) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 38f8808782c3209051b97de2ae3ea8c6a535e01a --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 38f8808782c3209051b97de2ae3ea8c6a535e01a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K8mVeOhn8K/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.yz6NthnOtp/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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-02-04 02:03:47,841 truncating revision to first 12 chars 2017-02-04 02:03:47,841 Setting DEBUG logging. 2017-02-04 02:03:47,841 attempt 1/10 2017-02-04 02:03:47,841 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/38f8808782c3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-04 02:03:48,477 unpacking tar archive at: mozilla-aurora-38f8808782c3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.911561 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 02:03:48.666960) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-04 02:03:48.667289) ========= script_repo_revision: 38f8808782c3209051b97de2ae3ea8c6a535e01a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-04 02:03:48.667669) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-04 02:03:48.667948) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-04 02:03:48.688164) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-04 02:03:48.688567) ========= /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.K8mVeOhn8K/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.yz6NthnOtp/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:03:48 INFO - MultiFileLogger online at 20170204 02:03:48 in /builds/slave/test 02:03:48 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 02:03:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:03:48 INFO - {'append_to_log': False, 02:03:48 INFO - 'base_work_dir': '/builds/slave/test', 02:03:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:03:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:03:48 INFO - 'branch': 'Mozilla-Aurora', 02:03:48 INFO - 'buildbot_json_path': 'buildprops.json', 02:03:48 INFO - 'config_files': ('talos/mac_config.py',), 02:03:48 INFO - 'default_actions': ('clobber', 02:03:48 INFO - 'read-buildbot-config', 02:03:48 INFO - 'download-and-extract', 02:03:48 INFO - 'populate-webroot', 02:03:48 INFO - 'create-virtualenv', 02:03:48 INFO - 'install', 02:03:48 INFO - 'run-tests'), 02:03:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:03:48 INFO - 'download_minidump_stackwalk': True, 02:03:48 INFO - 'download_symbols': 'ondemand', 02:03:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:03:48 INFO - 'tooltool.py': '/tools/tooltool.py', 02:03:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:03:48 INFO - '/tools/misc-python/virtualenv.py')}, 02:03:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:03:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:03:48 INFO - 'installer_path': 'installer.exe', 02:03:48 INFO - 'log_level': 'info', 02:03:48 INFO - 'log_name': 'talos', 02:03:48 INFO - 'log_to_console': True, 02:03:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:03:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:03:48 INFO - 'opt_config_files': (), 02:03:48 INFO - 'pip_index': False, 02:03:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:03:48 INFO - 'cmd': ('bash', 02:03:48 INFO - '-c', 02:03:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:03:48 INFO - 'enabled': True, 02:03:48 INFO - 'halt_on_failure': False, 02:03:48 INFO - 'name': 'check_screen_resolution'},), 02:03:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:03:48 INFO - 'cmd': ('bash', 02:03:48 INFO - '-c', 02:03:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:03:48 INFO - 'enabled': True, 02:03:48 INFO - 'halt_on_failure': False, 02:03:48 INFO - 'name': 'check_screen_resolution'},), 02:03:48 INFO - 'run_cmd_checks_enabled': True, 02:03:48 INFO - 'sps_profile': False, 02:03:48 INFO - 'sps_profile_interval': 0, 02:03:48 INFO - 'suite': 'dromaeojs', 02:03:48 INFO - 'system_bits': '32', 02:03:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:03:48 INFO - 'title': 't-yosemite-r7-0388', 02:03:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:03:48 INFO - 'use_talos_json': True, 02:03:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:03:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:03:48 INFO - 'work_dir': 'build'} 02:03:48 INFO - [mozharness: 2017-02-04 10:03:48.906674Z] Running clobber step. 02:03:48 INFO - Running pre-action listener: _resource_record_pre_action 02:03:48 INFO - Running main action method: clobber 02:03:48 INFO - rmtree: /builds/slave/test/build 02:03:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:03:51 INFO - Running post-action listener: _resource_record_post_action 02:03:51 INFO - [mozharness: 2017-02-04 10:03:51.261045Z] Finished clobber step (success) 02:03:51 INFO - [mozharness: 2017-02-04 10:03:51.261238Z] Running read-buildbot-config step. 02:03:51 INFO - Running pre-action listener: _resource_record_pre_action 02:03:51 INFO - Running main action method: read_buildbot_config 02:03:51 INFO - Using buildbot properties: 02:03:51 INFO - { 02:03:51 INFO - "project": "", 02:03:51 INFO - "product": "firefox", 02:03:51 INFO - "script_repo_revision": "production", 02:03:51 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:03:51 INFO - "repository": "", 02:03:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:03:51 INFO - "buildid": "20170204004003", 02:03:51 INFO - "stage_platform": "macosx64", 02:03:51 INFO - "basedir": "/builds/slave/test", 02:03:51 INFO - "nightly_build": "True", 02:03:51 INFO - "buildnumber": 16, 02:03:51 INFO - "slavename": "t-yosemite-r7-0388", 02:03:51 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:03:51 INFO - "platform": "yosemite_r7", 02:03:51 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:03:51 INFO - "branch": "mozilla-aurora", 02:03:51 INFO - "repo_path": "releases/mozilla-aurora", 02:03:51 INFO - "revision": "38f8808782c3209051b97de2ae3ea8c6a535e01a", 02:03:51 INFO - "pgo_build": "False", 02:03:51 INFO - "builduid": "a0b282ac65b7435ebb3df9b2c44e96c6", 02:03:51 INFO - "slavebuilddir": "test" 02:03:51 INFO - } 02:03:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 02:03:51 INFO - Running post-action listener: _resource_record_post_action 02:03:51 INFO - [mozharness: 2017-02-04 10:03:51.264805Z] Finished read-buildbot-config step (success) 02:03:51 INFO - [mozharness: 2017-02-04 10:03:51.264951Z] Running download-and-extract step. 02:03:51 INFO - Running pre-action listener: _resource_record_pre_action 02:03:51 INFO - Running main action method: download_and_extract 02:03:51 INFO - mkdir: /builds/slave/test/build/tests 02:03:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:51 INFO - trying https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 02:03:51 INFO - Downloading https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 02:03:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 02:03:52 INFO - Downloaded 1199 bytes. 02:03:52 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 02:03:52 INFO - Using the following test package requirements: 02:03:52 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 02:03:52 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:03:52 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 02:03:52 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:03:52 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 02:03:52 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:03:52 INFO - u'jsshell-mac.zip'], 02:03:52 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:03:52 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 02:03:52 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 02:03:52 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:03:52 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 02:03:52 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:03:52 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 02:03:52 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:03:52 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 02:03:52 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:03:52 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 02:03:52 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:03:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 02:03:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:03:52 INFO - Fetch https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:03:54 INFO - Expected file size: 16356436 02:03:54 INFO - Obtained file size: 16356436 02:04:00 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:04:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 02:04:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:04:00 INFO - Fetch https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:04:01 INFO - Expected file size: 16356436 02:04:01 INFO - Obtained file size: 16356436 02:04:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 02:04:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:04:07 INFO - Fetch https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 02:04:08 INFO - Expected file size: 14403485 02:04:08 INFO - Obtained file size: 14403485 02:04:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:09 INFO - trying https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:04:09 INFO - Downloading https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 02:04:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 02:04:11 INFO - Downloaded 73961476 bytes. 02:04:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:04:11 INFO - mkdir: /builds/slave/test/properties 02:04:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:04:11 INFO - Writing to file /builds/slave/test/properties/build_url 02:04:11 INFO - Contents: 02:04:11 INFO - build_url:https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:04:12 INFO - Running post-action listener: _resource_record_post_action 02:04:12 INFO - Running post-action listener: set_extra_try_arguments 02:04:12 INFO - [mozharness: 2017-02-04 10:04:12.306243Z] Finished download-and-extract step (success) 02:04:12 INFO - [mozharness: 2017-02-04 10:04:12.306394Z] Running populate-webroot step. 02:04:12 INFO - Running pre-action listener: _resource_record_pre_action 02:04:12 INFO - Running main action method: populate_webroot 02:04:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:04:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:04:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:04:12 INFO - '--tppagecycles', 02:04:12 INFO - '7'], 02:04:12 INFO - 'tests': ['tsvgm']}}, 02:04:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:04:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:04:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:04:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:04:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:04:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:04:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:04:12 INFO - 'talos_options': ['--disable-e10s'], 02:04:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:04:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:04:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:04:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:04:12 INFO - 'talos_options': ['--disable-e10s'], 02:04:12 INFO - 'tests': ['damp', 'tps']}, 02:04:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:04:12 INFO - 'tests': ['damp', 'tps']}, 02:04:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:04:12 INFO - 'tests': ['dromaeo_dom']}, 02:04:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:04:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:04:12 INFO - 'tests': ['basic_compositor_video']}, 02:04:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:04:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:04:12 INFO - 'tests': ['a11yr', 02:04:12 INFO - 'ts_paint', 02:04:12 INFO - 'tpaint', 02:04:12 INFO - 'sessionrestore', 02:04:12 INFO - 'sessionrestore_no_auto_restore', 02:04:12 INFO - 'tabpaint']}, 02:04:12 INFO - 'other-e10s': {'tests': ['a11yr', 02:04:12 INFO - 'ts_paint', 02:04:12 INFO - 'tpaint', 02:04:12 INFO - 'sessionrestore', 02:04:12 INFO - 'sessionrestore_no_auto_restore', 02:04:12 INFO - 'tabpaint']}, 02:04:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:04:12 INFO - 'ts_paint', 02:04:12 INFO - 'tpaint', 02:04:12 INFO - 'sessionrestore', 02:04:12 INFO - 'sessionrestore_no_auto_restore', 02:04:12 INFO - 'tabpaint']}, 02:04:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:04:12 INFO - 'ts_paint', 02:04:12 INFO - 'tpaint', 02:04:12 INFO - 'sessionrestore', 02:04:12 INFO - 'sessionrestore_no_auto_restore', 02:04:12 INFO - 'tabpaint']}, 02:04:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:04:12 INFO - 'tests': ['a11yr', 02:04:12 INFO - 'ts_paint', 02:04:12 INFO - 'tpaint', 02:04:12 INFO - 'sessionrestore', 02:04:12 INFO - 'sessionrestore_no_auto_restore', 02:04:12 INFO - 'tabpaint']}, 02:04:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:04:12 INFO - 'tests': ['a11yr', 02:04:12 INFO - 'ts_paint', 02:04:12 INFO - 'tpaint', 02:04:12 INFO - 'sessionrestore', 02:04:12 INFO - 'sessionrestore_no_auto_restore', 02:04:12 INFO - 'tabpaint']}, 02:04:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:04:12 INFO - 'tests': ['tsvgx', 02:04:12 INFO - 'tsvgr_opacity', 02:04:12 INFO - 'tart', 02:04:12 INFO - 'tscrollx', 02:04:12 INFO - 'cart', 02:04:12 INFO - 'tsvg_static']}, 02:04:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:04:12 INFO - 'tsvgr_opacity', 02:04:12 INFO - 'tart', 02:04:12 INFO - 'tscrollx', 02:04:12 INFO - 'cart', 02:04:12 INFO - 'tsvg_static']}, 02:04:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:04:12 INFO - 'talos_options': ['--disable-e10s'], 02:04:12 INFO - 'tests': ['tp5o']}, 02:04:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:04:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:04:12 INFO - 'talos_options': ['--disable-e10s', 02:04:12 INFO - '--xperf_path', 02:04:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:04:12 INFO - 'tests': ['tp5n']}, 02:04:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:04:12 INFO - 'talos_options': ['--xperf_path', 02:04:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:04:12 INFO - 'tests': ['tp5n']}}, 02:04:12 INFO - 'talos.zip': {'path': '', 02:04:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:04:12 INFO - Running post-action listener: _resource_record_post_action 02:04:12 INFO - [mozharness: 2017-02-04 10:04:12.324622Z] Finished populate-webroot step (success) 02:04:12 INFO - [mozharness: 2017-02-04 10:04:12.324737Z] Running create-virtualenv step. 02:04:12 INFO - Running pre-action listener: _resource_record_pre_action 02:04:12 INFO - Running main action method: create_virtualenv 02:04:12 INFO - Creating virtualenv /builds/slave/test/build/venv 02:04:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:04:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:04:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:04:12 INFO - Using real prefix '/tools/python27' 02:04:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:04:13 INFO - Installing distribute.............................................................................................................................................................................................done. 02:04:16 INFO - Installing pip.................done. 02:04:16 INFO - Return code: 0 02:04:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:04:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:04:16 INFO - Reading from file tmpfile_stdout 02:04:16 INFO - Output received: 02:04:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:04:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:04:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f11b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f14768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d0c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cefd30>, '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 0x100c16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ce4be8>, '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.K8mVeOhn8K/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.yz6NthnOtp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:04:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:04:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:04:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K8mVeOhn8K/Render', 02:04:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:16 INFO - 'HOME': '/Users/cltbld', 02:04:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:16 INFO - 'LOGNAME': 'cltbld', 02:04:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:16 INFO - 'MOZ_NO_REMOTE': '1', 02:04:16 INFO - 'NO_EM_RESTART': '1', 02:04:16 INFO - 'PAGER': '/bin/cat', 02:04:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:16 INFO - 'PWD': '/builds/slave/test', 02:04:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:16 INFO - 'SHELL': '/bin/bash', 02:04:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yz6NthnOtp/Listeners', 02:04:16 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 02:04:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:16 INFO - 'USER': 'cltbld', 02:04:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:16 INFO - 'XPC_FLAGS': '0x0', 02:04:16 INFO - 'XPC_SERVICE_NAME': '0', 02:04:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:04:16 INFO - Cleaning up... 02:04:16 INFO - Return code: 0 02:04:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:04:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100f11b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f14768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d0c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cefd30>, '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 0x100c16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ce4be8>, '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.K8mVeOhn8K/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.yz6NthnOtp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:04:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:04:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:04:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K8mVeOhn8K/Render', 02:04:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:16 INFO - 'HOME': '/Users/cltbld', 02:04:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:16 INFO - 'LOGNAME': 'cltbld', 02:04:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:16 INFO - 'MOZ_NO_REMOTE': '1', 02:04:16 INFO - 'NO_EM_RESTART': '1', 02:04:16 INFO - 'PAGER': '/bin/cat', 02:04:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:16 INFO - 'PWD': '/builds/slave/test', 02:04:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:16 INFO - 'SHELL': '/bin/bash', 02:04:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yz6NthnOtp/Listeners', 02:04:16 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 02:04:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:16 INFO - 'USER': 'cltbld', 02:04:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:16 INFO - 'XPC_FLAGS': '0x0', 02:04:16 INFO - 'XPC_SERVICE_NAME': '0', 02:04:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:17 INFO - Downloading/unpacking psutil>=3.1.1 02:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:04:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:04:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:04:21 INFO - Installing collected packages: psutil 02:04:21 INFO - Running setup.py install for psutil 02:04:21 INFO - building 'psutil._psutil_osx' extension 02:04:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:04:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:04:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:04:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:04:21 INFO - building 'psutil._psutil_posix' extension 02:04:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:04:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:04:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:04:22 INFO - ^ 02:04:22 INFO - 1 warning generated. 02:04:22 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:04:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:04:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:04:22 INFO - Successfully installed psutil 02:04:22 INFO - Cleaning up... 02:04:22 INFO - Return code: 0 02:04:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:04:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:22 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 0x100f11b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f14768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d0c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cefd30>, '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 0x100c16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ce4be8>, '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.K8mVeOhn8K/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.yz6NthnOtp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:04:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:04:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:04:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K8mVeOhn8K/Render', 02:04:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:22 INFO - 'HOME': '/Users/cltbld', 02:04:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:22 INFO - 'LOGNAME': 'cltbld', 02:04:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:22 INFO - 'MOZ_NO_REMOTE': '1', 02:04:22 INFO - 'NO_EM_RESTART': '1', 02:04:22 INFO - 'PAGER': '/bin/cat', 02:04:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:22 INFO - 'PWD': '/builds/slave/test', 02:04:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:22 INFO - 'SHELL': '/bin/bash', 02:04:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yz6NthnOtp/Listeners', 02:04:22 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 02:04:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:22 INFO - 'USER': 'cltbld', 02:04:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:22 INFO - 'XPC_FLAGS': '0x0', 02:04:22 INFO - 'XPC_SERVICE_NAME': '0', 02:04:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:04:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:04:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:04:25 INFO - Installing collected packages: mozsystemmonitor 02:04:25 INFO - Running setup.py install for mozsystemmonitor 02:04:25 INFO - Successfully installed mozsystemmonitor 02:04:25 INFO - Cleaning up... 02:04:25 INFO - Return code: 0 02:04:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:04:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:25 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 0x100f11b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f14768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d0c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cefd30>, '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 0x100c16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ce4be8>, '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.K8mVeOhn8K/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.yz6NthnOtp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:04:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:04:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:04:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K8mVeOhn8K/Render', 02:04:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:25 INFO - 'HOME': '/Users/cltbld', 02:04:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:25 INFO - 'LOGNAME': 'cltbld', 02:04:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:25 INFO - 'MOZ_NO_REMOTE': '1', 02:04:25 INFO - 'NO_EM_RESTART': '1', 02:04:25 INFO - 'PAGER': '/bin/cat', 02:04:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:25 INFO - 'PWD': '/builds/slave/test', 02:04:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:25 INFO - 'SHELL': '/bin/bash', 02:04:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yz6NthnOtp/Listeners', 02:04:25 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 02:04:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:25 INFO - 'USER': 'cltbld', 02:04:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:25 INFO - 'XPC_FLAGS': '0x0', 02:04:25 INFO - 'XPC_SERVICE_NAME': '0', 02:04:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:25 INFO - Downloading/unpacking jsonschema==2.5.1 02:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:04:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:28 INFO - Downloading functools32-3.2.3-2.tar.gz 02:04:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:04:28 INFO - warning: no files found matching '*.txt' 02:04:28 INFO - no previously-included directories found matching 'build' 02:04:28 INFO - no previously-included directories found matching 'dist' 02:04:28 INFO - no previously-included directories found matching '.git*' 02:04:28 INFO - Installing collected packages: jsonschema, functools32 02:04:28 INFO - Running setup.py install for functools32 02:04:29 INFO - warning: no files found matching '*.txt' 02:04:29 INFO - no previously-included directories found matching 'build' 02:04:29 INFO - no previously-included directories found matching 'dist' 02:04:29 INFO - no previously-included directories found matching '.git*' 02:04:29 INFO - Successfully installed jsonschema functools32 02:04:29 INFO - Cleaning up... 02:04:29 INFO - Return code: 0 02:04:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:29 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 0x100f11b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f14768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d0c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cefd30>, '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 0x100c16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ce4be8>, '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.K8mVeOhn8K/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.yz6NthnOtp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:04:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:04:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K8mVeOhn8K/Render', 02:04:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:29 INFO - 'HOME': '/Users/cltbld', 02:04:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:29 INFO - 'LOGNAME': 'cltbld', 02:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:29 INFO - 'MOZ_NO_REMOTE': '1', 02:04:29 INFO - 'NO_EM_RESTART': '1', 02:04:29 INFO - 'PAGER': '/bin/cat', 02:04:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:29 INFO - 'PWD': '/builds/slave/test', 02:04:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:29 INFO - 'SHELL': '/bin/bash', 02:04:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yz6NthnOtp/Listeners', 02:04:29 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 02:04:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:29 INFO - 'USER': 'cltbld', 02:04:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:29 INFO - 'XPC_FLAGS': '0x0', 02:04:29 INFO - 'XPC_SERVICE_NAME': '0', 02:04:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:04:29 INFO - Cleaning up... 02:04:29 INFO - Return code: 0 02:04:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:29 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 0x100f11b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f14768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d0c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cefd30>, '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 0x100c16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ce4be8>, '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.K8mVeOhn8K/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.yz6NthnOtp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:04:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:04:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K8mVeOhn8K/Render', 02:04:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:29 INFO - 'HOME': '/Users/cltbld', 02:04:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:29 INFO - 'LOGNAME': 'cltbld', 02:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:29 INFO - 'MOZ_NO_REMOTE': '1', 02:04:29 INFO - 'NO_EM_RESTART': '1', 02:04:29 INFO - 'PAGER': '/bin/cat', 02:04:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:29 INFO - 'PWD': '/builds/slave/test', 02:04:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:29 INFO - 'SHELL': '/bin/bash', 02:04:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yz6NthnOtp/Listeners', 02:04:29 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 02:04:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:29 INFO - 'USER': 'cltbld', 02:04:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:29 INFO - 'XPC_FLAGS': '0x0', 02:04:29 INFO - 'XPC_SERVICE_NAME': '0', 02:04:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:29 INFO - Downloading/unpacking blobuploader==1.2.4 02:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:32 INFO - Downloading blobuploader-1.2.4.tar.gz 02:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:04:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:04:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:33 INFO - Downloading docopt-0.6.1.tar.gz 02:04:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:04:33 INFO - Installing collected packages: blobuploader, requests, docopt 02:04:33 INFO - Running setup.py install for blobuploader 02:04:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:04:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:04:33 INFO - Running setup.py install for requests 02:04:34 INFO - Running setup.py install for docopt 02:04:34 INFO - Successfully installed blobuploader requests docopt 02:04:34 INFO - Cleaning up... 02:04:34 INFO - Return code: 0 02:04:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:34 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 0x100f11b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f14768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d0c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cefd30>, '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 0x100c16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ce4be8>, '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.K8mVeOhn8K/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.yz6NthnOtp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:04:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:04:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:04:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K8mVeOhn8K/Render', 02:04:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:34 INFO - 'HOME': '/Users/cltbld', 02:04:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:34 INFO - 'LOGNAME': 'cltbld', 02:04:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:34 INFO - 'MOZ_NO_REMOTE': '1', 02:04:34 INFO - 'NO_EM_RESTART': '1', 02:04:34 INFO - 'PAGER': '/bin/cat', 02:04:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:34 INFO - 'PWD': '/builds/slave/test', 02:04:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:34 INFO - 'SHELL': '/bin/bash', 02:04:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yz6NthnOtp/Listeners', 02:04:34 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 02:04:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:34 INFO - 'USER': 'cltbld', 02:04:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:34 INFO - 'XPC_FLAGS': '0x0', 02:04:34 INFO - 'XPC_SERVICE_NAME': '0', 02:04:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:04:34 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-9INh_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:04:34 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-4ctYY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:04:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-ZMSKt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:04:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-22tvh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:04:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-k3Fj7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:04:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-1dP1Lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:04:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-9WeyWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:04:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-5RbEHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:04:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-knKE1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:04:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-SPZYBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:04:35 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-XGsO6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:04:36 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-2W7YrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:04:36 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-xRUM1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:04:36 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-BwWvvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:04:36 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-TO0J6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:04:36 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-s6WMQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:04:36 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-XwSlm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:04:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:04:36 INFO - Running setup.py install for manifestparser 02:04:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:04:36 INFO - Running setup.py install for mozcrash 02:04:36 INFO - Running setup.py install for mozdebug 02:04:37 INFO - Running setup.py install for mozdevice 02:04:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:04:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:04:37 INFO - Running setup.py install for mozfile 02:04:37 INFO - Running setup.py install for mozhttpd 02:04:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:04:37 INFO - Running setup.py install for mozinfo 02:04:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:04:37 INFO - Running setup.py install for mozInstall 02:04:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:04:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:04:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:04:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:04:37 INFO - Running setup.py install for mozleak 02:04:37 INFO - Running setup.py install for mozlog 02:04:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:04:38 INFO - Running setup.py install for moznetwork 02:04:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:04:38 INFO - Running setup.py install for mozprocess 02:04:38 INFO - Running setup.py install for mozprofile 02:04:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:04:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:04:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:04:38 INFO - Running setup.py install for mozrunner 02:04:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:04:38 INFO - Running setup.py install for mozscreenshot 02:04:38 INFO - Running setup.py install for moztest 02:04:39 INFO - Running setup.py install for mozversion 02:04:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:04:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:04:39 INFO - Cleaning up... 02:04:39 INFO - Return code: 0 02:04:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:04: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')]} 02:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04: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')]} 02:04:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:39 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 0x100f11b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f14768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d0c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cefd30>, '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 0x100c16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ce4be8>, '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.K8mVeOhn8K/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.yz6NthnOtp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:04:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:04:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:04:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K8mVeOhn8K/Render', 02:04:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:39 INFO - 'HOME': '/Users/cltbld', 02:04:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:39 INFO - 'LOGNAME': 'cltbld', 02:04:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:39 INFO - 'MOZ_NO_REMOTE': '1', 02:04:39 INFO - 'NO_EM_RESTART': '1', 02:04:39 INFO - 'PAGER': '/bin/cat', 02:04:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:39 INFO - 'PWD': '/builds/slave/test', 02:04:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:39 INFO - 'SHELL': '/bin/bash', 02:04:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yz6NthnOtp/Listeners', 02:04:39 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 02:04:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:39 INFO - 'USER': 'cltbld', 02:04:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:39 INFO - 'XPC_FLAGS': '0x0', 02:04:39 INFO - 'XPC_SERVICE_NAME': '0', 02:04:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:04:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-UA1fYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:04:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:04:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-bvmk85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:04:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:04:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-OnGbbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:04:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:04:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-bbNLBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:04:39 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)) 02:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:04:39 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-cIGF5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:04:40 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-OG9Y3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:04:40 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-hwIK_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:04:40 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-B_Zwg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:04:40 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-ix5OC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:04:40 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-Sljch8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:04:40 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-dpnAoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:04:40 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-YoP35p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:04:40 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-whVu8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:04:40 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-aA4dhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:04:41 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-ipRZre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:04:41 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-xA9Syg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:04:41 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-hk2nr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:04:41 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)) 02:04:41 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)) 02:04:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:04: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 02:04: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 02:04: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 02:04: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 02:04: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 02:04: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 02:04:44 INFO - Downloading blessings-1.6.tar.gz 02:04:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:04:44 INFO - Installing collected packages: blessings 02:04:44 INFO - Running setup.py install for blessings 02:04:44 INFO - Successfully installed blessings 02:04:44 INFO - Cleaning up... 02:04:44 INFO - Return code: 0 02:04:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:04:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:04:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:04:44 INFO - Reading from file tmpfile_stdout 02:04:44 INFO - Current package versions: 02:04:44 INFO - blessings == 1.6 02:04:44 INFO - blobuploader == 1.2.4 02:04:44 INFO - docopt == 0.6.1 02:04:44 INFO - functools32 == 3.2.3-2 02:04:44 INFO - jsonschema == 2.5.1 02:04:44 INFO - manifestparser == 1.1 02:04:44 INFO - mozInstall == 1.12 02:04:44 INFO - mozcrash == 1.0 02:04:44 INFO - mozdebug == 0.1 02:04:44 INFO - mozdevice == 0.49 02:04:44 INFO - mozfile == 1.2 02:04:44 INFO - mozhttpd == 0.7 02:04:44 INFO - mozinfo == 0.9 02:04:44 INFO - mozleak == 0.1 02:04:44 INFO - mozlog == 3.4 02:04:44 INFO - moznetwork == 0.27 02:04:44 INFO - mozprocess == 0.24 02:04:44 INFO - mozprofile == 0.28 02:04:44 INFO - mozrunner == 6.13 02:04:44 INFO - mozscreenshot == 0.1 02:04:44 INFO - mozsystemmonitor == 0.3 02:04:44 INFO - moztest == 0.8 02:04:44 INFO - mozversion == 1.4 02:04:44 INFO - psutil == 3.1.1 02:04:44 INFO - requests == 1.2.3 02:04:44 INFO - wsgiref == 0.1.2 02:04:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:44 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 0x100f11b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f14768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d0c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cefd30>, '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 0x100c16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ce4be8>, '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.K8mVeOhn8K/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.yz6NthnOtp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:04:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:04:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:04:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K8mVeOhn8K/Render', 02:04:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:44 INFO - 'HOME': '/Users/cltbld', 02:04:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:44 INFO - 'LOGNAME': 'cltbld', 02:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:44 INFO - 'MOZ_NO_REMOTE': '1', 02:04:44 INFO - 'NO_EM_RESTART': '1', 02:04:44 INFO - 'PAGER': '/bin/cat', 02:04:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:44 INFO - 'PWD': '/builds/slave/test', 02:04:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:44 INFO - 'SHELL': '/bin/bash', 02:04:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yz6NthnOtp/Listeners', 02:04:44 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 02:04:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:44 INFO - 'USER': 'cltbld', 02:04:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:44 INFO - 'XPC_FLAGS': '0x0', 02:04:44 INFO - 'XPC_SERVICE_NAME': '0', 02:04:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:04:44 INFO - Cleaning up... 02:04:44 INFO - Return code: 0 02:04:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:44 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 0x100f11b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f14768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6d0c5e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cefd30>, '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 0x100c16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ce4be8>, '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.K8mVeOhn8K/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.yz6NthnOtp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:04:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:04:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:04:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K8mVeOhn8K/Render', 02:04:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:44 INFO - 'HOME': '/Users/cltbld', 02:04:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:44 INFO - 'LOGNAME': 'cltbld', 02:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:44 INFO - 'MOZ_NO_REMOTE': '1', 02:04:44 INFO - 'NO_EM_RESTART': '1', 02:04:44 INFO - 'PAGER': '/bin/cat', 02:04:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:44 INFO - 'PWD': '/builds/slave/test', 02:04:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:44 INFO - 'SHELL': '/bin/bash', 02:04:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yz6NthnOtp/Listeners', 02:04:44 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 02:04:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:44 INFO - 'USER': 'cltbld', 02:04:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:44 INFO - 'XPC_FLAGS': '0x0', 02:04:44 INFO - 'XPC_SERVICE_NAME': '0', 02:04:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:04:45 INFO - Cleaning up... 02:04:45 INFO - Return code: 0 02:04:45 INFO - Running post-action listener: _resource_record_post_action 02:04:45 INFO - Running post-action listener: _start_resource_monitoring 02:04:45 INFO - Starting resource monitoring. 02:04:45 INFO - [mozharness: 2017-02-04 10:04:45.262709Z] Finished create-virtualenv step (success) 02:04:45 INFO - [mozharness: 2017-02-04 10:04:45.263728Z] Running install step. 02:04:45 INFO - Running pre-action listener: _resource_record_pre_action 02:04:45 INFO - Running main action method: install 02:04:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:04:45 INFO - Reading from file tmpfile_stdout 02:04:45 INFO - Detecting whether we're running mozinstall >=1.0... 02:04:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:04:45 INFO - Reading from file tmpfile_stdout 02:04:45 INFO - Output received: 02:04:45 INFO - Usage: mozinstall [options] installer 02:04:45 INFO - Options: 02:04:45 INFO - -h, --help show this help message and exit 02:04:45 INFO - -d DEST, --destination=DEST 02:04:45 INFO - Directory to install application into. [default: 02:04:45 INFO - "/builds/slave/test"] 02:04:45 INFO - --app=APP Application being installed. [default: firefox] 02:04:45 INFO - mkdir: /builds/slave/test/build/application 02:04:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:05:05 INFO - Reading from file tmpfile_stdout 02:05:05 INFO - Output received: 02:05:05 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:05:05 INFO - Running post-action listener: _resource_record_post_action 02:05:05 INFO - [mozharness: 2017-02-04 10:05:05.585887Z] Finished install step (success) 02:05:05 INFO - [mozharness: 2017-02-04 10:05:05.586059Z] Running run-tests step. 02:05:05 INFO - Running pre-action listener: _resource_record_pre_action 02:05:05 INFO - Running main action method: run_tests 02:05:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:05:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:05:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:05:05 INFO - 2017-02-04 02:05:05.600 screenresolution[1634:8373] starting screenresolution argv=screenresolution get 02:05:05 INFO - 2017-02-04 02:05:05.603 screenresolution[1634:8373] Display 0: 1600x1200x32@60 02:05:05 INFO - 2017-02-04 02:05:05.608 screenresolution[1635:8376] starting screenresolution argv=screenresolution list 02:05:05 INFO - Available Modes on Display 0 02:05:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:05:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:05:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:05:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:05:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:05:05 INFO - Intel Iris: 02:05:05 INFO - Chipset Model: Intel Iris 02:05:05 INFO - Type: GPU 02:05:05 INFO - Bus: Built-In 02:05:05 INFO - VRAM (Dynamic, Max): 1536 MB 02:05:05 INFO - Vendor: Intel (0x8086) 02:05:05 INFO - Device ID: 0x0a2e 02:05:05 INFO - Revision ID: 0x0009 02:05:05 INFO - Displays: 02:05:05 INFO - Display: 02:05:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:05:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:05:05 INFO - Display Serial Number: 1600x1200 60 02:05:05 INFO - Main Display: Yes 02:05:05 INFO - Mirror: Off 02:05:05 INFO - Online: Yes 02:05:05 INFO - Rotation: Supported 02:05:05 INFO - Return code: 0 02:05:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:05:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:05:05 INFO - Python 2.7.3 02:05:05 INFO - Return code: 0 02:05:05 INFO - grabbing minidump binary from tooltool 02:05:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:05:05 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 0x100cefd30>, '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 0x100c16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ce4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:05:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:05:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:05:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:05:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:05:05 INFO - Return code: 0 02:05:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:05:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:05:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:05:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:05:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:05:05 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/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0388', '--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 02:05:05 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/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0388 --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 02:05:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K8mVeOhn8K/Render', 02:05:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:05:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:05:05 INFO - 'HOME': '/Users/cltbld', 02:05:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:05:05 INFO - 'LOGNAME': 'cltbld', 02:05:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:05:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:05:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:05:05 INFO - 'MOZ_NO_REMOTE': '1', 02:05:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:05:05 INFO - 'NO_EM_RESTART': '1', 02:05:05 INFO - 'PAGER': '/bin/cat', 02:05:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:05:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:05:05 INFO - 'PWD': '/builds/slave/test', 02:05:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:05:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:05:05 INFO - 'SHELL': '/bin/bash', 02:05:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yz6NthnOtp/Listeners', 02:05:05 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 02:05:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:05:05 INFO - 'USER': 'cltbld', 02:05:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:05:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:05:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:05:05 INFO - 'XPC_FLAGS': '0x0', 02:05:05 INFO - 'XPC_SERVICE_NAME': '0', 02:05:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:05:05 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/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0388', '--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 02:05:06 INFO - mozversion application_buildid: 20170204004003 02:05:06 INFO - mozversion application_changeset: 38f8808782c3209051b97de2ae3ea8c6a535e01a 02:05:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:05:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:05:06 INFO - mozversion application_name: Firefox 02:05:06 INFO - mozversion application_remotingname: firefox-dev 02:05:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:05:06 INFO - mozversion application_vendor: Mozilla 02:05:06 INFO - mozversion application_version: 53.0a2 02:05:06 INFO - mozversion platform_buildid: 20170204004003 02:05:06 INFO - mozversion platform_changeset: 38f8808782c3209051b97de2ae3ea8c6a535e01a 02:05:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:05:06 INFO - mozversion platform_version: 53.0a2 02:05:06 INFO - using testdate: 1486202706 02:05:06 INFO - actual date: 1486202706 02:05:06 INFO - starting webserver on 'localhost:49241' 02:05:06 INFO - SUITE-START | Running 2 tests 02:05:06 INFO - TEST-START | dromaeo_css 02:05:06 INFO - operating with platform_type : mac_ 02:05:06 INFO - Initialising browser for dromaeo_css test... 02:05:06 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmptH5mdF/profile http://localhost:49241/getInfo.html) 02:05:09 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 02:05:09 INFO - PROCESS | 1645 | colorDepth:24 02:05:09 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 02:05:09 INFO - PROCESS | 1645 | __metrics 02:05:09 INFO - PROCESS | 1645 | 1486202709150 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 02:05:09 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" 02:05:09 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] 02:05:09 INFO - TEST-INFO | 1645: exit 0 02:05:09 INFO - Browser initialized. 02:05:09 INFO - Running cycle 1/1 for dromaeo_css test... 02:05:09 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmptH5mdF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:05:22 INFO - PROCESS | 1650 | RSS: Main: 213614592 02:05:22 INFO - PROCESS | 1650 | 02:08:26 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 02:08:27 INFO - PROCESS | 1650 | RSS: Main: 348184576 02:08:27 INFO - PROCESS | 1650 | 02:10:10 INFO - PROCESS | 1650 | ************************* 02:10:10 INFO - PROCESS | 1650 | A coding exception was thrown and uncaught in a Task. 02:10:10 INFO - PROCESS | 1650 | 02:10:10 ERROR - PROCESS | 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:10:10 INFO - PROCESS | 1650 | Full stack: 02:10:10 INFO - PROCESS | 1650 | ************************* 02:11:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 02:11:31 INFO - PROCESS | 1650 | RSS: Main: 344698880 02:11:31 INFO - PROCESS | 1650 | 02:14:33 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 02:14:34 INFO - PROCESS | 1650 | RSS: Main: 323448832 02:14:34 INFO - PROCESS | 1650 | 02:17:36 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 02:17:36 INFO - PROCESS | 1650 | RSS: Main: 330158080 02:17:36 INFO - PROCESS | 1650 | 02:20:40 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 02:20:41 INFO - PROCESS | 1650 | RSS: Main: 317800448 02:20:41 INFO - PROCESS | 1650 | 02:23:44 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 02:23:44 INFO - PROCESS | 1650 | RSS: Main: 492314624 02:23:44 INFO - PROCESS | 1650 | 02:23:44 INFO - PROCESS | 1650 | __start_tp_report 02:23:44 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 02:23:44 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 02:23:44 INFO - PROCESS | 1650 | |i|pagename|runs| 02:23:44 INFO - PROCESS | 1650 | |0;dojo.html;3072.9705548641205;3342.582177227846;3346.6653334666535;3373.1229880231162;3405.3359594878993;5707.2009918611375;5711.543076553876;5726.883257764737;5742.994975879225;5743.597948143632;5604.915926261109;5646.802361917334;5733.369329373765;5744.655320680762;5758.942410575893;5091.210862316338;5255.237990491377;5261.684298942076;5268.92096618551;5302.072137375963;5169.534741873231;5186.092433824085;5187.221916712486;5318.521333080022;5322.4411436799155;5252.395974462947;5268.5521730652745;5279.313689220396;5283.4188239293835;5285.312909321802;5277.287566178555;5283.735813209324;5284.339457567804;5286.471352864722;5306.124489459235;5129.820456284049;5231.921521177185;5237.921431178535;5241.31862857826;5265.289185959885;4759.833405830814;5787.594868359217;5791.449812267828;5795.855103622401;5820.44705752957;6131.101423540794;6139.229124622885;6178.320384757673;6183.041628547582;6197.721102550351;6260.561760676755;6325.399087086719;6332.556721029529;6337.936620633781;6340.985442329205;5726.3414707308775;5776.42235776419;5777.855553611152;5797.275340582427;5833.32916714579;6262.499000079983;6331.398517140864;6338.461230795434;6344.175257216532;6359.554831161774;6099.512039036867;6118.816435506853;6137.662428566472;6177.536684748662;6216.813495595139;6291.6854157291955;6293.590916590407;6294.402031806971;6302.432781049728;6322.36776322364;6239.313675495692;6243.96878015607;6245;6273.65494897785;6281.057841323746;5061.164907790173;6050.334463209044;6140.293866205444;6156.815295541141;6204.317525072239;5047.167217409086;5048.141815891234;5054.620903432257;5072.949270507322;5078.365204349457;4420.557944205554;4435.3125265583885;4453.866384008485;4483.237849565582;4484.304932735431;5550.111982082847;5695.088785794336;5700.37295897451;5779;5807.680577568274;6157.907631385533;6257.2804127525005;6264.154339164245;6264.874702505925;6272.780452684134;5764.942350576524;5766.88466230673;5775.884482310415;5779.942200578024;5791.768329266866;5423.728813559306;5441.755121019563;5447.400785913546;5447.4552544746;5448.291722076184;6236.501079913596;6316.557840951089;6336.58812177207;6341.270753863365;6350.841228969312;6126.029144987285;6154.353792851686;6247.718852651551;6250.281217659845;6264.937350626617;6315.400036996478;6335;6350.523710721622;6360.522960778053;6381.649009304533;6270.717817698306;6277.183966084563;6280.7487700491465;6283.403076707706;6298.0552917062805;6116.974953844038;6154.138420621027;6190.783322583688;6209.192804935329;6224.719887604978;4577.633789296915;5095.592352611856;6117.827404800828;6135.171422439487;6135.7839197735275;6113.510919126548;6130.448259656474;6132.693365331805;6133.202683651097;6139.386061393825;5753.367129615823;5818.8254352369495;5832.795852145325;5855.853603659943;5914;6132.938670613236;6139.584134579286;6140.565599444434;6145.66198859067;6147.877042459215;5080.618953578422;5723.513501352431;5881.470667639933;5900.1149827526215;5939.138824870283 02:23:44 INFO - PROCESS | 1650 | |1;ext.html;447.9473237890956;465.23701130146554;488.1433084935937;498.40938487891873;502.264182971946;30734.692653073464;39952.600473995284;42001;42250.36624450628;42348.57651423489;43405.565944340546;43738.34392484115;43778.34332485015;43787.46743863965;43858;9211.815763684723;9434.764130896749;9439.669611563597;9442.339036267464;9446.81106377872;8150.144234855314;8365.247127758501;10989;11258.887411125866;11309.547618095266;11316.660500185008;11353.375564343958;11363.090952723804;11381.430928453585;11384.032357249644;11204.775904481905;11285;11291.322520648706;11308.604198853083;11316.490757915913;8197.426180167391;8408.411411201218;10975.890241097566;11251.43742812856;11273.985341319238;41191.97020074492;41437.79281103605;41563;41584.96037599084;41667.58332416668;3991.261616600938;4016.096378314885;4021.477207962975;4036.2936486114813;4037.97981010096;28621.998230062058;29160;30159.69840301613;30341.24146896323;31478.685213148034;3272.852721627509;3346.498025296201;3376.864925402981;3381.9830900845586;3384.4077286475012;467158.6642066802;467655;472740.99999999657;473227;473978;58817.70591147017;60677;61133;61275;61291.38708612857;63580;63633;63807.680961594895;63815;63859;16317.510474685541;16620.418285360196;16629.3348266068;16634.6673066538;16638.83361166397;11613.535458581731;12656.6835829105;12907.096503244871;12983.675408114872;12985.675358115934;14046.227457489937;14319.427222910967;20791.8960405197;22371;22397.55204895893;8651.740447786577;8896.822063558822;8961.103889611117;8988.685396011108;9051.864222036675;754.8150703077671;773.8490994256179;866.1641515937151;877.859542473214;888.8800011998427;29405.85297073501;29608.407831843244;29772;30163.944261951165;30245.243868903453;718.1848601836953;856.5845564901018;966.6906589891308;1145;1183.721825371037;11280;14595.343209555596;14766.677497562963;15102.320395582225;15720.764188537181;605871;610242;611818;613274;613328;53726;54332.45667543353;54456.45543544593;54484.63788405321;54518.727406363505;1383.9584812455641;1407.366684991758;1413.6960553481051;1416.129080615414;1418.7942748301439;27610;27885.721142788312;27984.860075700304;28736.568951465328;28823.423531529672;46107;46487.30269045899;46574;46643.53356466392;46649.3002604968;607422;609657;610348;611506;613994;25258.221595624098;25441;25722.871385642953;26252.343691407867;33490.665093348754;35186.824065879504;36835.07912302214;42311.576884230766;42574;43117;35726.28547429089;36148.81925590355;36258.27483450369;36280.27439451249;36295.818520907225;35739.82130089333;36088.27823443569;36903.81548092242;37232.06919827026;37330.386872918636;34026;35080.82459587686;35091.29817403587;35239.295214096084;35766;30142.84928575343;32071.679283206868;32164.713411464214;32198.678013219567;32343.676563235007;24154.75845241525;24308.451867851734;24404.75595244096;24575;24625.50748985046 02:23:44 INFO - PROCESS | 1650 | |2;jquery.html;360.20934049760774;387.2062272341699;393.36008181889696;395.1642276585023;420.15968063872253;13676.589702308922;20256.69614955775;21323.786762132375;21517.67723484149;21528.89235553822;21963.780362196372;22059.77940220597;22147.889260553675;22681.319560413212;22990.08039678411;1692.1200975492736;1698;2252.5044490212126;5942.584019118674;6217.968910155466;5884.235049443566;6064.272287325527;6154.046122850965;6196.225471816023;6199.132121502993;6019.247594050744;6065.150878876961;6085.695715214244;6093.969530152343;6102.11519329697;5809.419058094199;5935.673537955411;6039.245094363205;6074;6104.114903339013;5242.6330156889035;5315.867103322409;5316.016536940679;5327.334083239595;5331.386890507563;26139.60790588143;27433.31416714578;27552.173434796987;27553.897844086212;27736.722632773817;3102.534619807047;3115.735162511189;3124.937501249985;3140.905772826819;3147;7209.243029481934;7304.817379565553;9823.821141462999;10193.388396696222;16668.74996875048;1726.1886913150925;1814.5917168636952;1819.199552197029;1839.4297767691924;1843.5759775251768;118102.40948795201;144279;145934;148935;148944.25527872398;12006.699832504257;12019.158658893793;12127.33299668509;12154.209976351509;12288.692782680504;14055.718885622231;14655.26723663834;15890.126043067743;15909.761353579706;15937.840621593867;6748.628825414551;7740.148583655793;7858.960705196438;8838.29293656506;8960.8655870162;7133.857322853618;8662.220400164015;8679;8690.174433428814;8866.911330886609;3865.864694735727;4844.442889067803;4853.563179313879;4913.263010548375;4934.925976110361;1999.2702663527707;2034.0777964823396;2053.1274208461346;2055.9486012849497;2065.9586808263753;1006.8955882206078;1022;1037.725002874239;1038.787048655039;1063.4682658670665;17571.912140439472;21662.783372166075;22129.889350553465;22154.88922555377;22202.444938876335;890.7639475538988;1054.2040759226734;1067.7811048735146;1071.614218881202;1088.9019988200941;11134.610288639858;11495.827562586568;11526.538938442369;11590.826137607943;11598.246114002563;756149;762557;766546;773591.1320443362;796071;108543;135155;151662;157526.2123689374;167853;974.45917515778;992.0525897767615;1006.3861044762689;1008.2337423558002;1014.3609525998573;18287.177077031833;18939.90530047341;19202.615947681246;19292.710609341153;19293.324733634254;57837;59311.70344148252;59621;61071.47321317003;61677.38322616716;87186.69219961579;87309;88460.5576972111;90531;91148;5722.055860782924;7081.575105493687;7429.479936404616;8366.288865446506;8502.277306429023;8382;12976.740465190454;13100.427948646224;13253.204807711569;13497.730045399234;10769.192310576582;11151.386673733023;11292.378919159524;11443.427828608703;11488.253263537843;9680.419174849532;10928.508217130408;10990.395528246023;11469.483873225558;11517.53929842797;11229.27009744364;11308.660740177807;11564;11566.65300041;11677.941610292233;7158.355747982707;7533.209013053551;8400.285975692135;8580.05619381855;8647.437916535406;10587.20595955322;11423.200375973602;11474.25417347903;11598.014168795748;11813.409329533317 02:23:44 INFO - PROCESS | 1650 | |3;mootools.html;1428.7571112910805;1434.626996980785;1443.5308524729453;1446.8336141343748;1454.8545145485452;7993.2806047455715;8396.03445603755;8396.874046889301;8415.579221038939;8445.788855278606;6932.410745086674;7179.676914538845;7210.495265331416;8000.319972802319;8249.010118785754;2287.176616418088;2317.142657216832;2322.9651555226687;2324.140068174769;2324.4537533679572;2249.257744944173;2343.133040774907;2351.9764802352015;2367.6922000139957;2369.1707902234166;2032;2115.471132216946;2126.0964090261655;2246.2025980776834;2328.52265285616;2298.7011688480475;2321.013569233086;2321.5589038082794;2327.8719670418122;2330.417395651087;2268.9092436302526;2356.988215058931;2357.3752955466816;2357.4106473381653;2358.1982126077046;8286.83426331476;8350.958245208796;8353.624086916103;8358.958205208995;8362.331013518964;5054.721990290533;5205.427402985669;5207.739613019334;5209.140491818884;5211.7133557654315;3238.0771480128287;3271.96728032719;3461.7403694723;3465.5148279240925;3478.3217272631846;1179.0744265751343;1185.1170877696172;1217.7503611759657;1217.9878201217964;1231.667449788561;10706.892931070668;10847.945760271226;10850.24048316626;10854.67435976922;10859.239853210278;4437.467503899488;4584.060267645124;4669.066186762661;4741.691790033637;4767.6662633615315;8111.986001749781;9336.4864932428;9452.243820494481;9469.95830458649;9481.33630645862;5241.842744717665;5250.65870718402;5251.396089449686;5253.737313134328;5285.762140703678;4610.262358022767;4692.319613656001;4737.478877323491;4790.377250957423;4838.177509823338;3467.7689420255447;3663.285659296465;3834.8657796976972;3883.708721845873;3910.3547914593773;1259.1123258103016;1262.3498898067503;1262.4382149943187;1275.1392809853312;1279.1877158004547;751.4589495563185;784.541043489565;789.3882241263067;796.2793671727096;806.4999700185922;5525.889482210333;5615.9157612635845;5777.884442311214;5797.304323481209;5908.615939963888;1001.439194051319;1003.894591067942;1008.8940661230612;1009.9395634583805;1043.879953805307;3808.5239345081864;3840.7887566184127;3858.1897801461446;3914.7651140931066;3957.703172262092;7630.7329243476215;10042.447665378328;10046.14607758334;10055.798884022279;10081.243906706879;9983.400995940121;9996.3002589818;9997.000299969945;10000.149987251165;10036.243657359933;6002.309734380602;7705.653245604073;7745.186755390716;7861;7946.960265198637;5770.394108618535;5803.071508558611;5906.350301466921;5962.642241465526;5996.100584912124;8758.167974042455;9095.727128186165;9293;9295.163435290857;9367.156955874005;9221.446713197229;9442.433453992782;9540.045995400404;9541.570629321559;9545.427274363561;7236.529625574321;7251.383632391095;7263.273672632906;7302.56184628924;7388.556686598821;6668.83327916806;7791.92208077912;7871.48835325702;7901.367890568856;7906.960465197867;6007.669578173242;6988.126484189476;7076.504644674823;7094.822629434306;7271.781846544613;7020.157581090098;7044.682989265598;7046.647667616701;7121.287871212838;7131.893021604784;6362.141110949962;6439.967800160969;6504.1219435375615;6513.804585862432;6524.510661700297;4210.263203939359;4943.65394422387;4980.825671101494;5033.521815427529;5183.974080129726;4778.976105119591;4807.519248075165;4822.276658501253;4858.562729354234;4867.561919427269 02:23:44 INFO - PROCESS | 1650 | |4;prototype.html;298.3198307858084;311.4160948222084;356.0661574855041;360.18238598381674;372.1496380769941;4226.450561427017;4529.569690879365;4922.778474968626;5132.076226279269;5219.164933610623;3954.307996100678;4788.616910647149;5063.797448102081;5105;5120.283160357543;4060.167665628554;4068.735532190413;4076.714629975903;4079.7765217956135;4087.89780255493;3921.4313924480934;4094.283500387435;4097.979510102446;4098.549159592458;4106.342985122381;3861.4168191041676;4054.283629967914;4064.390341448777;4065.7763822989723;4104.6716262699065;3733.8879833605033;3962.4650672159178;4028.9597104028876;4035.152617950234;4041.019900248886;3610.205754733954;3615.4215325547784;3619.6199399063244;3628.9455658165148;3638.599754027055;3754.8310326035394;4737.24204127338;5007.873228523589;5024.547790698854;5102.953894451629;4013.3311347953577;4535.002299494106;4617.907641847178;4636.559526844944;4648.930266046012;4520.751358675271;4783.827962149261;5201.505856943622;5404.91892621611;5435.918461223085;3462.9653703462895;3712.981435092834;3933.9016524586827;3988.541317748467;4005;5146.948530514647;5653.406392328829;5662.2639056922335;5662.37713851476;5672.574556908248;3643.3988391915036;3687.967369136646;3788.7726736395352;3790.6543177172257;3819.560750513671;3256.5929258842643;3342.1644588852787;3383.695467407945;3804.2201348723447;3897.376419772823;3921.2941670499586;4278.422412974271;4398.295048545122;4421.180912805058;4462.924739995918;5377.4084850666395;5377.569794416438;5381.408045115034;5390.97304513475;5460.645058071292;1000.9699709008742;1064.9307794993301;1107.3023994883172;1217.2818037357824;1234.6049264235537;755.8261599832069;838.4675730910916;854.7820305821975;856.195176534056;860.3891237221643;4770.42397105566;4771.928421073687;4780.593649539758;4799.952030460613;4804.25534042224;4557.567031132037;4809.615230781529;5054.9241761373605;5300.761465733974;5372.167314066326;4256.8297268109545;4780.665353425296;5086.262491938648;5089.847304580869;5125.641205115606;2506.6490691303234;2767.7370649788236;2790.330320723012;2801.4257077299103;2814.14168678555;4720.244760838249;4769.642276829251;4962.478939711351;5169.966006798655;5380.762424642354;5637.942151114896;5739.540836733051;5748.770049198069;5753.712314384264;5764.149388370807;5796.681182535001;5818.44724751148;5821.563382746311;5842.8539286517325;5881.73532191061;4707.929381059218;4740;5232.921506177334;5583.637063590853;5606.495415412633;4969.956309174969;5281.837995641106;5536.280283563111;5563.109902415594;5599.188117722826;5279.155335146358;5769.740361683818;5782.472691658169;5799.246098007232;5848.795292164754;3411.778234414755;3640.4721315409647;3652.593751405675;4127.628513433805;4129.876103716893;5143.819966301162;5383.488568585978;5415.810446634349;5601.215829783752;5646.661200327993;4876.537348638914;5140.408852981955;5502.477264659851;5509.898020395856;5534.08687566547;4628.930566041579;4843.564079232745;5062.594992400525;5185.325907631984;5363.436839131991;4505.44562126066;5014.623903207201;5179.2390587199025;5339.67486336069;5344.4922732340365;4932.8520144395725;5178.456262092577;5283.196098808851;5390.97304513475;5413.945860541344;5300.879648140774;5301.310829592128;5308.708021058879;5323.920141197807;5332.813351532833 02:23:44 INFO - PROCESS | 1650 | |5;yui.html;696.4115322552443;698.7728988078876;722.5123041946688;768.665630450754;803.5058439059976;14718.63203419915;15717;15732.685346293067;15751.369945202207;15788.131652759093;14490.637734056654;14492.927535362334;14497.130172189649;14498.492552760656;14519.927400363009;676.8240257533052;698.9440844732435;701.2111374703459;846.4624963148384;856.6145234644404;867.2628265973934;869.148234729965;882.7042940614901;883.779055236191;885.2740752582885;933.7525555727703;934.3552948465571;934.8223837470858;938.9952750556907;939.3189937295474;720.5784615999594;747.8691229034897;934.6588495199271;937.2548823685153;937.7889974755659;665.8701553197128;684.7774473296199;689.7068745783072;689.9103116594861;693.3482526425145;15101.791856651442;15340.76988845157;15438.684197370372;15440.922795386063;15447.150406727626;13740.93129534356;13783.655408614764;13788.310584470737;13791.655208619764;13828.446862125504;10077.093061624415;11883.465244064038;12003.219790713576;12300.138990270685;12559.686007849785;4336.566343365638;4436.622887054604;4463.24124898768;4489.932651010237;4514.255147900592;93798.53100734521;95288.9999999993;96531.51734241354;97003;97066.02933970641;10715.624953126604;10789.514471848786;10853.131749460024;11069.667909962553;11258.268212566158;21253;21403.929803509764;21417.57164856694;21482.355529334145;21810.781892181192;7609.923900760921;7636.31273185416;7698.191689872595;7847.921520784833;7892.881606775903;3567.6075631680496;4089.5501494835544;4237.745735255895;4252.340887162495;4400.4939431965095;3401.438762604192;3496.003638962934;3500.212452198286;3502.842372093262;3505.404081306133;627.8838414893168;644.5552568727563;650.1093501902329;659.6042374575312;659.6965395917836;631.135344577922;652.2368828470701;660.4287291492824;673.700203409478;677.1467950382554;9394.62421503146;9834.459104749165;10033.197344212445;10124.645637402802;10198.33710808795;626.0734113512028;642.9453496452854;665.4643012374988;674.2313762310871;680.3026897430075;3838.481804956295;4426.712263702849;4663.836765713184;4699.876345180728;4736.739479328601;81332;93073;98853;101456.49271753593;102956.48521757343;71544;88464;88809;88858.55570722105;89576.760580986;65093.37266568374;67426.66286668535;78678.6066069666;80306;85117.57441212879;9989.400635961865;10878.292910960763;11020.834687479855;11345.149113816331;11361.43192840338;53088;53567.73216133894;54247.457525425816;55662.721686391305;56031.43968560262;72467.63766181347;74512;75497;75697.62151189208;77759;13054.801573614524;13320.93339533296;13647.976401769709;13742.519011834538;14404;14274.286285685881;14820;14950.850491494946;15066.54800355991;15421.641309633456;12440.253584784941;13192.274424906722;13300.135491193041;13351.79972300397;13364.198148111145;12539.37303134858;13193.472261109831;13457.259850708306;13797.241151736349;13934.303284835514;13271.535496257584;13927;14129.293535323399;14274.357653905807;14292.499762508261;10884.673459796219;11136.777264454504;11233.775324493628;11398.715032124263;11540.82688759686;10004.599816007569;10818.729531761453;11095.180765022851;11271.380074096003;11780.76438471243 02:23:44 INFO - PROCESS | 1650 | __end_tp_report 02:23:44 INFO - PROCESS | 1650 | __start_cc_report 02:23:44 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1251 02:23:44 INFO - PROCESS | 1650 | __end_cc_report 02:23:44 INFO - PROCESS | 1650 | __startTimestamp1486203824545__endTimestamp 02:23:44 INFO - PROCESS | 1650 | 02:23:44 INFO - PROCESS | 1650 | ------- Summary: start ------- 02:23:44 INFO - PROCESS | 1650 | Number of tests: 6 02:23:44 INFO - PROCESS | 1650 | 02:23:44 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5734.70 Median:5833.33 stddev:651.86 (11.2%) stddev-sans-first:619.52 02:23:44 INFO - PROCESS | 1650 | Values: 3073.0 3342.6 3346.7 3373.1 3405.3 5707.2 5711.5 5726.9 5743.0 5743.6 5604.9 5646.8 5733.4 5744.7 5758.9 5091.2 5255.2 5261.7 5268.9 5302.1 5169.5 5186.1 5187.2 5318.5 5322.4 5252.4 5268.6 5279.3 5283.4 5285.3 5277.3 5283.7 5284.3 5286.5 5306.1 5129.8 5231.9 5237.9 5241.3 5265.3 4759.8 5787.6 5791.4 5795.9 5820.4 6131.1 6139.2 6178.3 6183.0 6197.7 6260.6 6325.4 6332.6 6337.9 6341.0 5726.3 5776.4 5777.9 5797.3 5833.3 6262.5 6331.4 6338.5 6344.2 6359.6 6099.5 6118.8 6137.7 6177.5 6216.8 6291.7 6293.6 6294.4 6302.4 6322.4 6239.3 6244.0 6245.0 6273.7 6281.1 5061.2 6050.3 6140.3 6156.8 6204.3 5047.2 5048.1 5054.6 5072.9 5078.4 4420.6 4435.3 4453.9 4483.2 4484.3 5550.1 5695.1 5700.4 5779.0 5807.7 6157.9 6257.3 6264.2 6264.9 6272.8 5764.9 5766.9 5775.9 5779.9 5791.8 5423.7 5441.8 5447.4 5447.5 5448.3 6236.5 6316.6 6336.6 6341.3 6350.8 6126.0 6154.4 6247.7 6250.3 6264.9 6315.4 6335.0 6350.5 6360.5 6381.6 6270.7 6277.2 6280.7 6283.4 6298.1 6117.0 6154.1 6190.8 6209.2 6224.7 4577.6 5095.6 6117.8 6135.2 6135.8 6113.5 6130.4 6132.7 6133.2 6139.4 5753.4 5818.8 5832.8 5855.9 5914.0 6132.9 6139.6 6140.6 6145.7 6147.9 5080.6 5723.5 5881.5 5900.1 5939.1 02:23:44 INFO - PROCESS | 1650 | 02:23:44 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:69330.01 Median:36288.05 stddev:151801.13 (418.3%) stddev-sans-first:152138.87 02:23:44 INFO - PROCESS | 1650 | Values: 447.9 465.2 488.1 498.4 502.3 30734.7 39952.6 42001.0 42250.4 42348.6 43405.6 43738.3 43778.3 43787.5 43858.0 9211.8 9434.8 9439.7 9442.3 9446.8 8150.1 8365.2 10989.0 11258.9 11309.5 11316.7 11353.4 11363.1 11381.4 11384.0 11204.8 11285.0 11291.3 11308.6 11316.5 8197.4 8408.4 10975.9 11251.4 11274.0 41192.0 41437.8 41563.0 41585.0 41667.6 3991.3 4016.1 4021.5 4036.3 4038.0 28622.0 29160.0 30159.7 30341.2 31478.7 3272.9 3346.5 3376.9 3382.0 3384.4 467158.7 467655.0 472741.0 473227.0 473978.0 58817.7 60677.0 61133.0 61275.0 61291.4 63580.0 63633.0 63807.7 63815.0 63859.0 16317.5 16620.4 16629.3 16634.7 16638.8 11613.5 12656.7 12907.1 12983.7 12985.7 14046.2 14319.4 20791.9 22371.0 22397.6 8651.7 8896.8 8961.1 8988.7 9051.9 754.8 773.8 866.2 877.9 888.9 29405.9 29608.4 29772.0 30163.9 30245.2 718.2 856.6 966.7 1145.0 1183.7 11280.0 14595.3 14766.7 15102.3 15720.8 605871.0 610242.0 611818.0 613274.0 613328.0 53726.0 54332.5 54456.5 54484.6 54518.7 1384.0 1407.4 1413.7 1416.1 1418.8 27610.0 27885.7 27984.9 28736.6 28823.4 46107.0 46487.3 46574.0 46643.5 46649.3 607422.0 609657.0 610348.0 611506.0 613994.0 25258.2 25441.0 25722.9 26252.3 33490.7 35186.8 36835.1 42311.6 42574.0 43117.0 35726.3 36148.8 36258.3 36280.3 36295.8 35739.8 36088.3 36903.8 37232.1 37330.4 34026.0 35080.8 35091.3 35239.3 35766.0 30142.8 32071.7 32164.7 32198.7 32343.7 24154.8 24308.5 24404.8 24575.0 24625.5 02:23:44 INFO - PROCESS | 1650 | 02:23:44 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:41530.15 Median:22178.67 stddev:128322.35 (578.6%) stddev-sans-first:128645.09 02:23:44 INFO - PROCESS | 1650 | Values: 360.2 387.2 393.4 395.2 420.2 13676.6 20256.7 21323.8 21517.7 21528.9 21963.8 22059.8 22147.9 22681.3 22990.1 1692.1 1698.0 2252.5 5942.6 6218.0 5884.2 6064.3 6154.0 6196.2 6199.1 6019.2 6065.2 6085.7 6094.0 6102.1 5809.4 5935.7 6039.2 6074.0 6104.1 5242.6 5315.9 5316.0 5327.3 5331.4 26139.6 27433.3 27552.2 27553.9 27736.7 3102.5 3115.7 3124.9 3140.9 3147.0 7209.2 7304.8 9823.8 10193.4 16668.7 1726.2 1814.6 1819.2 1839.4 1843.6 118102.4 144279.0 145934.0 148935.0 148944.3 12006.7 12019.2 12127.3 12154.2 12288.7 14055.7 14655.3 15890.1 15909.8 15937.8 6748.6 7740.1 7859.0 8838.3 8960.9 7133.9 8662.2 8679.0 8690.2 8866.9 3865.9 4844.4 4853.6 4913.3 4934.9 1999.3 2034.1 2053.1 2055.9 2066.0 1006.9 1022.0 1037.7 1038.8 1063.5 17571.9 21662.8 22129.9 22154.9 22202.4 890.8 1054.2 1067.8 1071.6 1088.9 11134.6 11495.8 11526.5 11590.8 11598.2 756149.0 762557.0 766546.0 773591.1 796071.0 108543.0 135155.0 151662.0 157526.2 167853.0 974.5 992.1 1006.4 1008.2 1014.4 18287.2 18939.9 19202.6 19292.7 19293.3 57837.0 59311.7 59621.0 61071.5 61677.4 87186.7 87309.0 88460.6 90531.0 91148.0 5722.1 7081.6 7429.5 8366.3 8502.3 8382.0 12976.7 13100.4 13253.2 13497.7 10769.2 11151.4 11292.4 11443.4 11488.3 9680.4 10928.5 10990.4 11469.5 11517.5 11229.3 11308.7 11564.0 11566.7 11677.9 7158.4 7533.2 8400.3 8580.1 8647.4 10587.2 11423.2 11474.3 11598.0 11813.4 02:23:44 INFO - PROCESS | 1650 | 02:23:44 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5402.73 Median:5119.35 stddev:2925.69 (57.1%) stddev-sans-first:2918.65 02:23:44 INFO - PROCESS | 1650 | Values: 1428.8 1434.6 1443.5 1446.8 1454.9 7993.3 8396.0 8396.9 8415.6 8445.8 6932.4 7179.7 7210.5 8000.3 8249.0 2287.2 2317.1 2323.0 2324.1 2324.5 2249.3 2343.1 2352.0 2367.7 2369.2 2032.0 2115.5 2126.1 2246.2 2328.5 2298.7 2321.0 2321.6 2327.9 2330.4 2268.9 2357.0 2357.4 2357.4 2358.2 8286.8 8351.0 8353.6 8359.0 8362.3 5054.7 5205.4 5207.7 5209.1 5211.7 3238.1 3272.0 3461.7 3465.5 3478.3 1179.1 1185.1 1217.8 1218.0 1231.7 10706.9 10847.9 10850.2 10854.7 10859.2 4437.5 4584.1 4669.1 4741.7 4767.7 8112.0 9336.5 9452.2 9470.0 9481.3 5241.8 5250.7 5251.4 5253.7 5285.8 4610.3 4692.3 4737.5 4790.4 4838.2 3467.8 3663.3 3834.9 3883.7 3910.4 1259.1 1262.3 1262.4 1275.1 1279.2 751.5 784.5 789.4 796.3 806.5 5525.9 5615.9 5777.9 5797.3 5908.6 1001.4 1003.9 1008.9 1009.9 1043.9 3808.5 3840.8 3858.2 3914.8 3957.7 7630.7 10042.4 10046.1 10055.8 10081.2 9983.4 9996.3 9997.0 10000.1 10036.2 6002.3 7705.7 7745.2 7861.0 7947.0 5770.4 5803.1 5906.4 5962.6 5996.1 8758.2 9095.7 9293.0 9295.2 9367.2 9221.4 9442.4 9540.0 9541.6 9545.4 7236.5 7251.4 7263.3 7302.6 7388.6 6668.8 7791.9 7871.5 7901.4 7907.0 6007.7 6988.1 7076.5 7094.8 7271.8 7020.2 7044.7 7046.6 7121.3 7131.9 6362.1 6440.0 6504.1 6513.8 6524.5 4210.3 4943.7 4980.8 5033.5 5184.0 4779.0 4807.5 4822.3 4858.6 4867.6 02:23:44 INFO - PROCESS | 1650 | 02:23:44 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4381.23 Median:4802.10 stddev:1330.36 (27.7%) stddev-sans-first:1298.31 02:23:44 INFO - PROCESS | 1650 | Values: 298.3 311.4 356.1 360.2 372.1 4226.5 4529.6 4922.8 5132.1 5219.2 3954.3 4788.6 5063.8 5105.0 5120.3 4060.2 4068.7 4076.7 4079.8 4087.9 3921.4 4094.3 4098.0 4098.5 4106.3 3861.4 4054.3 4064.4 4065.8 4104.7 3733.9 3962.5 4029.0 4035.2 4041.0 3610.2 3615.4 3619.6 3628.9 3638.6 3754.8 4737.2 5007.9 5024.5 5103.0 4013.3 4535.0 4617.9 4636.6 4648.9 4520.8 4783.8 5201.5 5404.9 5435.9 3463.0 3713.0 3933.9 3988.5 4005.0 5146.9 5653.4 5662.3 5662.4 5672.6 3643.4 3688.0 3788.8 3790.7 3819.6 3256.6 3342.2 3383.7 3804.2 3897.4 3921.3 4278.4 4398.3 4421.2 4462.9 5377.4 5377.6 5381.4 5391.0 5460.6 1001.0 1064.9 1107.3 1217.3 1234.6 755.8 838.5 854.8 856.2 860.4 4770.4 4771.9 4780.6 4800.0 4804.3 4557.6 4809.6 5054.9 5300.8 5372.2 4256.8 4780.7 5086.3 5089.8 5125.6 2506.6 2767.7 2790.3 2801.4 2814.1 4720.2 4769.6 4962.5 5170.0 5380.8 5637.9 5739.5 5748.8 5753.7 5764.1 5796.7 5818.4 5821.6 5842.9 5881.7 4707.9 4740.0 5232.9 5583.6 5606.5 4970.0 5281.8 5536.3 5563.1 5599.2 5279.2 5769.7 5782.5 5799.2 5848.8 3411.8 3640.5 3652.6 4127.6 4129.9 5143.8 5383.5 5415.8 5601.2 5646.7 4876.5 5140.4 5502.5 5509.9 5534.1 4628.9 4843.6 5062.6 5185.3 5363.4 4505.4 5014.6 5179.2 5339.7 5344.5 4932.9 5178.5 5283.2 5391.0 5413.9 5300.9 5301.3 5308.7 5323.9 5332.8 02:23:44 INFO - PROCESS | 1650 | 02:23:44 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:20235.43 Median:4413.60 stddev:28224.94 (639.5%) stddev-sans-first:28265.98 02:23:44 INFO - PROCESS | 1650 | Values: 696.4 698.8 722.5 768.7 803.5 14718.6 15717.0 15732.7 15751.4 15788.1 14490.6 14492.9 14497.1 14498.5 14519.9 676.8 698.9 701.2 846.5 856.6 867.3 869.1 882.7 883.8 885.3 933.8 934.4 934.8 939.0 939.3 720.6 747.9 934.7 937.3 937.8 665.9 684.8 689.7 689.9 693.3 15101.8 15340.8 15438.7 15440.9 15447.2 13740.9 13783.7 13788.3 13791.7 13828.4 10077.1 11883.5 12003.2 12300.1 12559.7 4336.6 4436.6 4463.2 4489.9 4514.3 93798.5 95289.0 96531.5 97003.0 97066.0 10715.6 10789.5 10853.1 11069.7 11258.3 21253.0 21403.9 21417.6 21482.4 21810.8 7609.9 7636.3 7698.2 7847.9 7892.9 3567.6 4089.6 4237.7 4252.3 4400.5 3401.4 3496.0 3500.2 3502.8 3505.4 627.9 644.6 650.1 659.6 659.7 631.1 652.2 660.4 673.7 677.1 9394.6 9834.5 10033.2 10124.6 10198.3 626.1 642.9 665.5 674.2 680.3 3838.5 4426.7 4663.8 4699.9 4736.7 81332.0 93073.0 98853.0 101456.5 102956.5 71544.0 88464.0 88809.0 88858.6 89576.8 65093.4 67426.7 78678.6 80306.0 85117.6 9989.4 10878.3 11020.8 11345.1 11361.4 53088.0 53567.7 54247.5 55662.7 56031.4 72467.6 74512.0 75497.0 75697.6 77759.0 13054.8 13320.9 13648.0 13742.5 14404.0 14274.3 14820.0 14950.9 15066.5 15421.6 12440.3 13192.3 13300.1 13351.8 13364.2 12539.4 13193.5 13457.3 13797.2 13934.3 13271.5 13927.0 14129.3 14274.4 14292.5 10884.7 11136.8 11233.8 11398.7 11540.8 10004.6 10818.7 11095.2 11271.4 11780.8 02:23:44 INFO - PROCESS | 1650 | -------- Summary: end -------- 02:23:44 INFO - PROCESS | 1650 | 02:23:45 INFO - TEST-INFO | 1650: exit 0 02:23:45 INFO - TEST-OK | dromaeo_css | took 1119030ms 02:23:45 INFO - TEST-START | kraken 02:23:45 INFO - operating with platform_type : mac_ 02:23:45 INFO - Initialising browser for kraken test... 02:23:45 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpXjbYpn/profile http://localhost:49241/getInfo.html) 02:23:47 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 02:23:47 INFO - PROCESS | 1656 | colorDepth:24 02:23:47 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 02:23:47 INFO - PROCESS | 1656 | __metrics 02:23:47 INFO - PROCESS | 1656 | 1486203827875 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 02:23:47 INFO - PROCESS | 1656 | 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" 02:23:47 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:23:48 INFO - TEST-INFO | 1656: exit 0 02:23:48 INFO - Browser initialized. 02:23:48 INFO - Running cycle 1/1 for kraken test... 02:23:48 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpXjbYpn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:24:00 INFO - PROCESS | 1658 | RSS: Main: 213262336 02:24:00 INFO - PROCESS | 1658 | 02:24:03 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 02:24:04 INFO - PROCESS | 1658 | RSS: Main: 265895936 02:24:04 INFO - PROCESS | 1658 | 02:24:07 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 02:24:07 INFO - PROCESS | 1658 | RSS: Main: 275058688 02:24:07 INFO - PROCESS | 1658 | 02:24:11 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 02:24:11 INFO - PROCESS | 1658 | RSS: Main: 372277248 02:24:11 INFO - PROCESS | 1658 | 02:24:13 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 02:24:14 INFO - PROCESS | 1658 | RSS: Main: 276291584 02:24:14 INFO - PROCESS | 1658 | 02:24:16 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:24:16 INFO - PROCESS | 1658 | RSS: Main: 277569536 02:24:16 INFO - PROCESS | 1658 | 02:24:19 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 02:24:20 INFO - PROCESS | 1658 | RSS: Main: 317874176 02:24:20 INFO - PROCESS | 1658 | 02:24:23 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 02:24:23 INFO - PROCESS | 1658 | RSS: Main: 318038016 02:24:23 INFO - PROCESS | 1658 | 02:24:26 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 02:24:27 INFO - PROCESS | 1658 | RSS: Main: 317181952 02:24:27 INFO - PROCESS | 1658 | 02:24:28 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:24:29 INFO - PROCESS | 1658 | RSS: Main: 294858752 02:24:29 INFO - PROCESS | 1658 | 02:24:31 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:24:32 INFO - PROCESS | 1658 | RSS: Main: 297013248 02:24:32 INFO - PROCESS | 1658 | 02:24:34 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:24:34 INFO - PROCESS | 1658 | RSS: Main: 299646976 02:24:34 INFO - PROCESS | 1658 | 02:24:37 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:24:38 INFO - PROCESS | 1658 | RSS: Main: 317505536 02:24:38 INFO - PROCESS | 1658 | 02:24:41 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:24:42 INFO - PROCESS | 1658 | RSS: Main: 311365632 02:24:42 INFO - PROCESS | 1658 | 02:24:43 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 02:24:44 INFO - PROCESS | 1658 | RSS: Main: 356184064 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | __start_tp_report 02:24:44 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 02:24:44 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 02:24:44 INFO - PROCESS | 1658 | |i|pagename|runs| 02:24:44 INFO - PROCESS | 1658 | |0;ai-astar;116;112;114;114;112;114;113;151;168;123 02:24:44 INFO - PROCESS | 1658 | |1;audio-beat-detection;139;114;130;174;115;111;116;138;161;112 02:24:44 INFO - PROCESS | 1658 | |2;audio-dft;183;185;181;184;181;183;184;184;184;181 02:24:44 INFO - PROCESS | 1658 | |3;audio-fft;83;58;58;88;60;60;59;61;74;58 02:24:44 INFO - PROCESS | 1658 | |4;audio-oscillator;88;70;87;70;68;68;83;68;68;83 02:24:44 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;90;87;90;87;89;85;91;87;90;86 02:24:44 INFO - PROCESS | 1658 | |6;imaging-darkroom;98;97;98;98;99;97;98;99;99;99 02:24:44 INFO - PROCESS | 1658 | |7;imaging-desaturate;86;85;85;87;84;84;86;85;86;85 02:24:44 INFO - PROCESS | 1658 | |8;json-parse-financial;58;57;56;57;58;59;59;58;58;58 02:24:44 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;67;72;68;77;73;70;70;71;70;71 02:24:44 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;81;83;87;84;89;84;97;100;84;84 02:24:44 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;138;141;134;138;149;152;150;142;179;138 02:24:44 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;167;228;220;169;167;169;167;166;155;204 02:24:44 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;67;66;66;65;64;65;64;65;64;68 02:24:44 INFO - PROCESS | 1658 | __end_tp_report 02:24:44 INFO - PROCESS | 1658 | __start_cc_report 02:24:44 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1993 02:24:44 INFO - PROCESS | 1658 | __end_cc_report 02:24:44 INFO - PROCESS | 1658 | __startTimestamp1486203884191__endTimestamp 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | ------- Summary: start ------- 02:24:44 INFO - PROCESS | 1658 | Number of tests: 14 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:123.70 Median:115.00 stddev:19.55 (17.0%) stddev-sans-first:20.53 02:24:44 INFO - PROCESS | 1658 | Values: 116.0 112.0 114.0 114.0 112.0 114.0 113.0 151.0 168.0 123.0 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:131.00 Median:134.00 stddev:22.10 (16.5%) stddev-sans-first:23.25 02:24:44 INFO - PROCESS | 1658 | Values: 139.0 114.0 130.0 174.0 115.0 111.0 116.0 138.0 161.0 112.0 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:183.00 Median:184.00 stddev:1.49 (0.8%) stddev-sans-first:1.58 02:24:44 INFO - PROCESS | 1658 | Values: 183.0 185.0 181.0 184.0 181.0 183.0 184.0 184.0 184.0 181.0 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:65.90 Median:60.50 stddev:11.43 (18.9%) stddev-sans-first:10.31 02:24:44 INFO - PROCESS | 1658 | Values: 83.0 58.0 58.0 88.0 60.0 60.0 59.0 61.0 74.0 58.0 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:75.30 Median:76.50 stddev:8.73 (11.4%) stddev-sans-first:7.96 02:24:44 INFO - PROCESS | 1658 | Values: 88.0 70.0 87.0 70.0 68.0 68.0 83.0 68.0 68.0 83.0 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:88.20 Median:89.50 stddev:2.04 (2.3%) stddev-sans-first:2.06 02:24:44 INFO - PROCESS | 1658 | Values: 90.0 87.0 90.0 87.0 89.0 85.0 91.0 87.0 90.0 86.0 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:98.20 Median:98.50 stddev:0.79 (0.8%) stddev-sans-first:0.83 02:24:44 INFO - PROCESS | 1658 | Values: 98.0 97.0 98.0 98.0 99.0 97.0 98.0 99.0 99.0 99.0 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:0.95 (1.1%) stddev-sans-first:0.97 02:24:44 INFO - PROCESS | 1658 | Values: 86.0 85.0 85.0 87.0 84.0 84.0 86.0 85.0 86.0 85.0 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:57.80 Median:58.00 stddev:0.92 (1.6%) stddev-sans-first:0.97 02:24:44 INFO - PROCESS | 1658 | Values: 58.0 57.0 56.0 57.0 58.0 59.0 59.0 58.0 58.0 58.0 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:2.77 (3.9%) stddev-sans-first:2.55 02:24:44 INFO - PROCESS | 1658 | Values: 67.0 72.0 68.0 77.0 73.0 70.0 70.0 71.0 70.0 71.0 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:84.00 stddev:6.33 (7.5%) stddev-sans-first:6.28 02:24:44 INFO - PROCESS | 1658 | Values: 81.0 83.0 87.0 84.0 89.0 84.0 97.0 100.0 84.0 84.0 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:145.50 stddev:13.03 (9.0%) stddev-sans-first:13.48 02:24:44 INFO - PROCESS | 1658 | Values: 138.0 141.0 134.0 138.0 149.0 152.0 150.0 142.0 179.0 138.0 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.20 Median:169.00 stddev:25.90 (15.3%) stddev-sans-first:26.95 02:24:44 INFO - PROCESS | 1658 | Values: 167.0 228.0 220.0 169.0 167.0 169.0 167.0 166.0 155.0 204.0 02:24:44 INFO - PROCESS | 1658 | 02:24:44 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:65.50 stddev:1.35 (2.1%) stddev-sans-first:1.30 02:24:44 INFO - PROCESS | 1658 | Values: 67.0 66.0 66.0 65.0 64.0 65.0 64.0 65.0 64.0 68.0 02:24:44 INFO - PROCESS | 1658 | -------- Summary: end -------- 02:24:44 INFO - PROCESS | 1658 | 02:24:45 INFO - TEST-INFO | 1658: exit 0 02:24:45 INFO - TEST-OK | kraken | took 60045ms 02:24:45 INFO - SUITE-END | took 1179s 02:24:45 INFO - Completed test suite (00:19:39) 02:24:45 INFO - javascript benchmark 02:24:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3072.9705548641205, 3342.582177227846, 3346.6653334666535, 3373.1229880231162, 3405.3359594878993, 5707.2009918611375, 5711.543076553876, 5726.883257764737, 5742.994975879225, 5743.597948143632, 5604.915926261109, 5646.802361917334, 5733.369329373765, 5744.655320680762, 5758.942410575893, 5091.210862316338, 5255.237990491377, 5261.684298942076, 5268.92096618551, 5302.072137375963, 5169.534741873231, 5186.092433824085, 5187.221916712486, 5318.521333080022, 5322.4411436799155, 5252.395974462947, 5268.5521730652745, 5279.313689220396, 5283.4188239293835, 5285.312909321802, 5277.287566178555, 5283.735813209324, 5284.339457567804, 5286.471352864722, 5306.124489459235, 5129.820456284049, 5231.921521177185, 5237.921431178535, 5241.31862857826, 5265.289185959885, 4759.833405830814, 5787.594868359217, 5791.449812267828, 5795.855103622401, 5820.44705752957, 6131.101423540794, 6139.229124622885, 6178.320384757673, 6183.041628547582, 6197.721102550351, 6260.561760676755, 6325.399087086719, 6332.556721029529, 6337.936620633781, 6340.985442329205, 5726.3414707308775, 5776.42235776419, 5777.855553611152, 5797.275340582427, 5833.32916714579, 6262.499000079983, 6331.398517140864, 6338.461230795434, 6344.175257216532, 6359.554831161774, 6099.512039036867, 6118.816435506853, 6137.662428566472, 6177.536684748662, 6216.813495595139, 6291.6854157291955, 6293.590916590407, 6294.402031806971, 6302.432781049728, 6322.36776322364, 6239.313675495692, 6243.96878015607, 6245.0, 6273.65494897785, 6281.057841323746, 5061.164907790173, 6050.334463209044, 6140.293866205444, 6156.815295541141, 6204.317525072239, 5047.167217409086, 5048.141815891234, 5054.620903432257, 5072.949270507322, 5078.365204349457, 4420.557944205554, 4435.3125265583885, 4453.866384008485, 4483.237849565582, 4484.304932735431, 5550.111982082847, 5695.088785794336, 5700.37295897451, 5779.0, 5807.680577568274, 6157.907631385533, 6257.2804127525005, 6264.154339164245, 6264.874702505925, 6272.780452684134, 5764.942350576524, 5766.88466230673, 5775.884482310415, 5779.942200578024, 5791.768329266866, 5423.728813559306, 5441.755121019563, 5447.400785913546, 5447.4552544746, 5448.291722076184, 6236.501079913596, 6316.557840951089, 6336.58812177207, 6341.270753863365, 6350.841228969312, 6126.029144987285, 6154.353792851686, 6247.718852651551, 6250.281217659845, 6264.937350626617, 6315.400036996478, 6335.0, 6350.523710721622, 6360.522960778053, 6381.649009304533, 6270.717817698306, 6277.183966084563, 6280.7487700491465, 6283.403076707706, 6298.0552917062805, 6116.974953844038, 6154.138420621027, 6190.783322583688, 6209.192804935329, 6224.719887604978, 4577.633789296915, 5095.592352611856, 6117.827404800828, 6135.171422439487, 6135.7839197735275, 6113.510919126548, 6130.448259656474, 6132.693365331805, 6133.202683651097, 6139.386061393825, 5753.367129615823, 5818.8254352369495, 5832.795852145325, 5855.853603659943, 5914.0, 6132.938670613236, 6139.584134579286, 6140.565599444434, 6145.66198859067, 6147.877042459215, 5080.618953578422, 5723.513501352431, 5881.470667639933, 5900.1149827526215, 5939.138824870283], "value": 5693.50880134172, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [447.9473237890956, 465.23701130146554, 488.1433084935937, 498.40938487891873, 502.264182971946, 30734.692653073464, 39952.600473995284, 42001.0, 42250.36624450628, 42348.57651423489, 43405.565944340546, 43738.34392484115, 43778.34332485015, 43787.46743863965, 43858.0, 9211.815763684723, 9434.764130896749, 9439.669611563597, 9442.339036267464, 9446.81106377872, 8150.144234855314, 8365.247127758501, 10989.0, 11258.887411125866, 11309.547618095266, 11316.660500185008, 11353.375564343958, 11363.090952723804, 11381.430928453585, 11384.032357249644, 11204.775904481905, 11285.0, 11291.322520648706, 11308.604198853083, 11316.490757915913, 8197.426180167391, 8408.411411201218, 10975.890241097566, 11251.43742812856, 11273.985341319238, 41191.97020074492, 41437.79281103605, 41563.0, 41584.96037599084, 41667.58332416668, 3991.261616600938, 4016.096378314885, 4021.477207962975, 4036.2936486114813, 4037.97981010096, 28621.998230062058, 29160.0, 30159.69840301613, 30341.24146896323, 31478.685213148034, 3272.852721627509, 3346.498025296201, 3376.864925402981, 3381.9830900845586, 3384.4077286475012, 467158.6642066802, 467655.0, 472740.99999999657, 473227.0, 473978.0, 58817.70591147017, 60677.0, 61133.0, 61275.0, 61291.38708612857, 63580.0, 63633.0, 63807.680961594895, 63815.0, 63859.0, 16317.510474685541, 16620.418285360196, 16629.3348266068, 16634.6673066538, 16638.83361166397, 11613.535458581731, 12656.6835829105, 12907.096503244871, 12983.675408114872, 12985.675358115934, 14046.227457489937, 14319.427222910967, 20791.8960405197, 22371.0, 22397.55204895893, 8651.740447786577, 8896.822063558822, 8961.103889611117, 8988.685396011108, 9051.864222036675, 754.8150703077671, 773.8490994256179, 866.1641515937151, 877.859542473214, 888.8800011998427, 29405.85297073501, 29608.407831843244, 29772.0, 30163.944261951165, 30245.243868903453, 718.1848601836953, 856.5845564901018, 966.6906589891308, 1145.0, 1183.721825371037, 11280.0, 14595.343209555596, 14766.677497562963, 15102.320395582225, 15720.764188537181, 605871.0, 610242.0, 611818.0, 613274.0, 613328.0, 53726.0, 54332.45667543353, 54456.45543544593, 54484.63788405321, 54518.727406363505, 1383.9584812455641, 1407.366684991758, 1413.6960553481051, 1416.129080615414, 1418.7942748301439, 27610.0, 27885.721142788312, 27984.860075700304, 28736.568951465328, 28823.423531529672, 46107.0, 46487.30269045899, 46574.0, 46643.53356466392, 46649.3002604968, 607422.0, 609657.0, 610348.0, 611506.0, 613994.0, 25258.221595624098, 25441.0, 25722.871385642953, 26252.343691407867, 33490.665093348754, 35186.824065879504, 36835.07912302214, 42311.576884230766, 42574.0, 43117.0, 35726.28547429089, 36148.81925590355, 36258.27483450369, 36280.27439451249, 36295.818520907225, 35739.82130089333, 36088.27823443569, 36903.81548092242, 37232.06919827026, 37330.386872918636, 34026.0, 35080.82459587686, 35091.29817403587, 35239.295214096084, 35766.0, 30142.84928575343, 32071.679283206868, 32164.713411464214, 32198.678013219567, 32343.676563235007, 24154.75845241525, 24308.451867851734, 24404.75595244096, 24575.0, 24625.50748985046], "value": 19872.06255873405, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.20934049760774, 387.2062272341699, 393.36008181889696, 395.1642276585023, 420.15968063872253, 13676.589702308922, 20256.69614955775, 21323.786762132375, 21517.67723484149, 21528.89235553822, 21963.780362196372, 22059.77940220597, 22147.889260553675, 22681.319560413212, 22990.08039678411, 1692.1200975492736, 1698.0, 2252.5044490212126, 5942.584019118674, 6217.968910155466, 5884.235049443566, 6064.272287325527, 6154.046122850965, 6196.225471816023, 6199.132121502993, 6019.247594050744, 6065.150878876961, 6085.695715214244, 6093.969530152343, 6102.11519329697, 5809.419058094199, 5935.673537955411, 6039.245094363205, 6074.0, 6104.114903339013, 5242.6330156889035, 5315.867103322409, 5316.016536940679, 5327.334083239595, 5331.386890507563, 26139.60790588143, 27433.31416714578, 27552.173434796987, 27553.897844086212, 27736.722632773817, 3102.534619807047, 3115.735162511189, 3124.937501249985, 3140.905772826819, 3147.0, 7209.243029481934, 7304.817379565553, 9823.821141462999, 10193.388396696222, 16668.74996875048, 1726.1886913150925, 1814.5917168636952, 1819.199552197029, 1839.4297767691924, 1843.5759775251768, 118102.40948795201, 144279.0, 145934.0, 148935.0, 148944.25527872398, 12006.699832504257, 12019.158658893793, 12127.33299668509, 12154.209976351509, 12288.692782680504, 14055.718885622231, 14655.26723663834, 15890.126043067743, 15909.761353579706, 15937.840621593867, 6748.628825414551, 7740.148583655793, 7858.960705196438, 8838.29293656506, 8960.8655870162, 7133.857322853618, 8662.220400164015, 8679.0, 8690.174433428814, 8866.911330886609, 3865.864694735727, 4844.442889067803, 4853.563179313879, 4913.263010548375, 4934.925976110361, 1999.2702663527707, 2034.0777964823396, 2053.1274208461346, 2055.9486012849497, 2065.9586808263753, 1006.8955882206078, 1022.0, 1037.725002874239, 1038.787048655039, 1063.4682658670665, 17571.912140439472, 21662.783372166075, 22129.889350553465, 22154.88922555377, 22202.444938876335, 890.7639475538988, 1054.2040759226734, 1067.7811048735146, 1071.614218881202, 1088.9019988200941, 11134.610288639858, 11495.827562586568, 11526.538938442369, 11590.826137607943, 11598.246114002563, 756149.0, 762557.0, 766546.0, 773591.1320443362, 796071.0, 108543.0, 135155.0, 151662.0, 157526.2123689374, 167853.0, 974.45917515778, 992.0525897767615, 1006.3861044762689, 1008.2337423558002, 1014.3609525998573, 18287.177077031833, 18939.90530047341, 19202.615947681246, 19292.710609341153, 19293.324733634254, 57837.0, 59311.70344148252, 59621.0, 61071.47321317003, 61677.38322616716, 87186.69219961579, 87309.0, 88460.5576972111, 90531.0, 91148.0, 5722.055860782924, 7081.575105493687, 7429.479936404616, 8366.288865446506, 8502.277306429023, 8382.0, 12976.740465190454, 13100.427948646224, 13253.204807711569, 13497.730045399234, 10769.192310576582, 11151.386673733023, 11292.378919159524, 11443.427828608703, 11488.253263537843, 9680.419174849532, 10928.508217130408, 10990.395528246023, 11469.483873225558, 11517.53929842797, 11229.27009744364, 11308.660740177807, 11564.0, 11566.65300041, 11677.941610292233, 7158.355747982707, 7533.209013053551, 8400.285975692135, 8580.05619381855, 8647.437916535406, 10587.20595955322, 11423.200375973602, 11474.25417347903, 11598.014168795748, 11813.409329533317], "value": 9885.968605832839, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.7571112910805, 1434.626996980785, 1443.5308524729453, 1446.8336141343748, 1454.8545145485452, 7993.2806047455715, 8396.03445603755, 8396.874046889301, 8415.579221038939, 8445.788855278606, 6932.410745086674, 7179.676914538845, 7210.495265331416, 8000.319972802319, 8249.010118785754, 2287.176616418088, 2317.142657216832, 2322.9651555226687, 2324.140068174769, 2324.4537533679572, 2249.257744944173, 2343.133040774907, 2351.9764802352015, 2367.6922000139957, 2369.1707902234166, 2032.0, 2115.471132216946, 2126.0964090261655, 2246.2025980776834, 2328.52265285616, 2298.7011688480475, 2321.013569233086, 2321.5589038082794, 2327.8719670418122, 2330.417395651087, 2268.9092436302526, 2356.988215058931, 2357.3752955466816, 2357.4106473381653, 2358.1982126077046, 8286.83426331476, 8350.958245208796, 8353.624086916103, 8358.958205208995, 8362.331013518964, 5054.721990290533, 5205.427402985669, 5207.739613019334, 5209.140491818884, 5211.7133557654315, 3238.0771480128287, 3271.96728032719, 3461.7403694723, 3465.5148279240925, 3478.3217272631846, 1179.0744265751343, 1185.1170877696172, 1217.7503611759657, 1217.9878201217964, 1231.667449788561, 10706.892931070668, 10847.945760271226, 10850.24048316626, 10854.67435976922, 10859.239853210278, 4437.467503899488, 4584.060267645124, 4669.066186762661, 4741.691790033637, 4767.6662633615315, 8111.986001749781, 9336.4864932428, 9452.243820494481, 9469.95830458649, 9481.33630645862, 5241.842744717665, 5250.65870718402, 5251.396089449686, 5253.737313134328, 5285.762140703678, 4610.262358022767, 4692.319613656001, 4737.478877323491, 4790.377250957423, 4838.177509823338, 3467.7689420255447, 3663.285659296465, 3834.8657796976972, 3883.708721845873, 3910.3547914593773, 1259.1123258103016, 1262.3498898067503, 1262.4382149943187, 1275.1392809853312, 1279.1877158004547, 751.4589495563185, 784.541043489565, 789.3882241263067, 796.2793671727096, 806.4999700185922, 5525.889482210333, 5615.9157612635845, 5777.884442311214, 5797.304323481209, 5908.615939963888, 1001.439194051319, 1003.894591067942, 1008.8940661230612, 1009.9395634583805, 1043.879953805307, 3808.5239345081864, 3840.7887566184127, 3858.1897801461446, 3914.7651140931066, 3957.703172262092, 7630.7329243476215, 10042.447665378328, 10046.14607758334, 10055.798884022279, 10081.243906706879, 9983.400995940121, 9996.3002589818, 9997.000299969945, 10000.149987251165, 10036.243657359933, 6002.309734380602, 7705.653245604073, 7745.186755390716, 7861.0, 7946.960265198637, 5770.394108618535, 5803.071508558611, 5906.350301466921, 5962.642241465526, 5996.100584912124, 8758.167974042455, 9095.727128186165, 9293.0, 9295.163435290857, 9367.156955874005, 9221.446713197229, 9442.433453992782, 9540.045995400404, 9541.570629321559, 9545.427274363561, 7236.529625574321, 7251.383632391095, 7263.273672632906, 7302.56184628924, 7388.556686598821, 6668.83327916806, 7791.92208077912, 7871.48835325702, 7901.367890568856, 7906.960465197867, 6007.669578173242, 6988.126484189476, 7076.504644674823, 7094.822629434306, 7271.781846544613, 7020.157581090098, 7044.682989265598, 7046.647667616701, 7121.287871212838, 7131.893021604784, 6362.141110949962, 6439.967800160969, 6504.1219435375615, 6513.804585862432, 6524.510661700297, 4210.263203939359, 4943.65394422387, 4980.825671101494, 5033.521815427529, 5183.974080129726, 4778.976105119591, 4807.519248075165, 4822.276658501253, 4858.562729354234, 4867.561919427269], "value": 4400.647824712302, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.3198307858084, 311.4160948222084, 356.0661574855041, 360.18238598381674, 372.1496380769941, 4226.450561427017, 4529.569690879365, 4922.778474968626, 5132.076226279269, 5219.164933610623, 3954.307996100678, 4788.616910647149, 5063.797448102081, 5105.0, 5120.283160357543, 4060.167665628554, 4068.735532190413, 4076.714629975903, 4079.7765217956135, 4087.89780255493, 3921.4313924480934, 4094.283500387435, 4097.979510102446, 4098.549159592458, 4106.342985122381, 3861.4168191041676, 4054.283629967914, 4064.390341448777, 4065.7763822989723, 4104.6716262699065, 3733.8879833605033, 3962.4650672159178, 4028.9597104028876, 4035.152617950234, 4041.019900248886, 3610.205754733954, 3615.4215325547784, 3619.6199399063244, 3628.9455658165148, 3638.599754027055, 3754.8310326035394, 4737.24204127338, 5007.873228523589, 5024.547790698854, 5102.953894451629, 4013.3311347953577, 4535.002299494106, 4617.907641847178, 4636.559526844944, 4648.930266046012, 4520.751358675271, 4783.827962149261, 5201.505856943622, 5404.91892621611, 5435.918461223085, 3462.9653703462895, 3712.981435092834, 3933.9016524586827, 3988.541317748467, 4005.0, 5146.948530514647, 5653.406392328829, 5662.2639056922335, 5662.37713851476, 5672.574556908248, 3643.3988391915036, 3687.967369136646, 3788.7726736395352, 3790.6543177172257, 3819.560750513671, 3256.5929258842643, 3342.1644588852787, 3383.695467407945, 3804.2201348723447, 3897.376419772823, 3921.2941670499586, 4278.422412974271, 4398.295048545122, 4421.180912805058, 4462.924739995918, 5377.4084850666395, 5377.569794416438, 5381.408045115034, 5390.97304513475, 5460.645058071292, 1000.9699709008742, 1064.9307794993301, 1107.3023994883172, 1217.2818037357824, 1234.6049264235537, 755.8261599832069, 838.4675730910916, 854.7820305821975, 856.195176534056, 860.3891237221643, 4770.42397105566, 4771.928421073687, 4780.593649539758, 4799.952030460613, 4804.25534042224, 4557.567031132037, 4809.615230781529, 5054.9241761373605, 5300.761465733974, 5372.167314066326, 4256.8297268109545, 4780.665353425296, 5086.262491938648, 5089.847304580869, 5125.641205115606, 2506.6490691303234, 2767.7370649788236, 2790.330320723012, 2801.4257077299103, 2814.14168678555, 4720.244760838249, 4769.642276829251, 4962.478939711351, 5169.966006798655, 5380.762424642354, 5637.942151114896, 5739.540836733051, 5748.770049198069, 5753.712314384264, 5764.149388370807, 5796.681182535001, 5818.44724751148, 5821.563382746311, 5842.8539286517325, 5881.73532191061, 4707.929381059218, 4740.0, 5232.921506177334, 5583.637063590853, 5606.495415412633, 4969.956309174969, 5281.837995641106, 5536.280283563111, 5563.109902415594, 5599.188117722826, 5279.155335146358, 5769.740361683818, 5782.472691658169, 5799.246098007232, 5848.795292164754, 3411.778234414755, 3640.4721315409647, 3652.593751405675, 4127.628513433805, 4129.876103716893, 5143.819966301162, 5383.488568585978, 5415.810446634349, 5601.215829783752, 5646.661200327993, 4876.537348638914, 5140.408852981955, 5502.477264659851, 5509.898020395856, 5534.08687566547, 4628.930566041579, 4843.564079232745, 5062.594992400525, 5185.325907631984, 5363.436839131991, 4505.44562126066, 5014.623903207201, 5179.2390587199025, 5339.67486336069, 5344.4922732340365, 4932.8520144395725, 5178.456262092577, 5283.196098808851, 5390.97304513475, 5413.945860541344, 5300.879648140774, 5301.310829592128, 5308.708021058879, 5323.920141197807, 5332.813351532833], "value": 3959.386484415746, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.4115322552443, 698.7728988078876, 722.5123041946688, 768.665630450754, 803.5058439059976, 14718.63203419915, 15717.0, 15732.685346293067, 15751.369945202207, 15788.131652759093, 14490.637734056654, 14492.927535362334, 14497.130172189649, 14498.492552760656, 14519.927400363009, 676.8240257533052, 698.9440844732435, 701.2111374703459, 846.4624963148384, 856.6145234644404, 867.2628265973934, 869.148234729965, 882.7042940614901, 883.779055236191, 885.2740752582885, 933.7525555727703, 934.3552948465571, 934.8223837470858, 938.9952750556907, 939.3189937295474, 720.5784615999594, 747.8691229034897, 934.6588495199271, 937.2548823685153, 937.7889974755659, 665.8701553197128, 684.7774473296199, 689.7068745783072, 689.9103116594861, 693.3482526425145, 15101.791856651442, 15340.76988845157, 15438.684197370372, 15440.922795386063, 15447.150406727626, 13740.93129534356, 13783.655408614764, 13788.310584470737, 13791.655208619764, 13828.446862125504, 10077.093061624415, 11883.465244064038, 12003.219790713576, 12300.138990270685, 12559.686007849785, 4336.566343365638, 4436.622887054604, 4463.24124898768, 4489.932651010237, 4514.255147900592, 93798.53100734521, 95288.9999999993, 96531.51734241354, 97003.0, 97066.02933970641, 10715.624953126604, 10789.514471848786, 10853.131749460024, 11069.667909962553, 11258.268212566158, 21253.0, 21403.929803509764, 21417.57164856694, 21482.355529334145, 21810.781892181192, 7609.923900760921, 7636.31273185416, 7698.191689872595, 7847.921520784833, 7892.881606775903, 3567.6075631680496, 4089.5501494835544, 4237.745735255895, 4252.340887162495, 4400.4939431965095, 3401.438762604192, 3496.003638962934, 3500.212452198286, 3502.842372093262, 3505.404081306133, 627.8838414893168, 644.5552568727563, 650.1093501902329, 659.6042374575312, 659.6965395917836, 631.135344577922, 652.2368828470701, 660.4287291492824, 673.700203409478, 677.1467950382554, 9394.62421503146, 9834.459104749165, 10033.197344212445, 10124.645637402802, 10198.33710808795, 626.0734113512028, 642.9453496452854, 665.4643012374988, 674.2313762310871, 680.3026897430075, 3838.481804956295, 4426.712263702849, 4663.836765713184, 4699.876345180728, 4736.739479328601, 81332.0, 93073.0, 98853.0, 101456.49271753593, 102956.48521757343, 71544.0, 88464.0, 88809.0, 88858.55570722105, 89576.760580986, 65093.37266568374, 67426.66286668535, 78678.6066069666, 80306.0, 85117.57441212879, 9989.400635961865, 10878.292910960763, 11020.834687479855, 11345.149113816331, 11361.43192840338, 53088.0, 53567.73216133894, 54247.457525425816, 55662.721686391305, 56031.43968560262, 72467.63766181347, 74512.0, 75497.0, 75697.62151189208, 77759.0, 13054.801573614524, 13320.93339533296, 13647.976401769709, 13742.519011834538, 14404.0, 14274.286285685881, 14820.0, 14950.850491494946, 15066.54800355991, 15421.641309633456, 12440.253584784941, 13192.274424906722, 13300.135491193041, 13351.79972300397, 13364.198148111145, 12539.37303134858, 13193.472261109831, 13457.259850708306, 13797.241151736349, 13934.303284835514, 13271.535496257584, 13927.0, 14129.293535323399, 14274.357653905807, 14292.499762508261, 10884.673459796219, 11136.777264454504, 11233.775324493628, 11398.715032124263, 11540.82688759686, 10004.599816007569, 10818.729531761453, 11095.180765022851, 11271.380074096003, 11780.76438471243], "value": 7524.472005019776, "unit": "score"}], "name": "dromaeo_css", "value": 7261.929104084612, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 112.0, 114.0, 114.0, 112.0, 114.0, 113.0, 151.0, 168.0, 123.0], "value": 123.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 114.0, 130.0, 174.0, 115.0, 111.0, 116.0, 138.0, 161.0, 112.0], "value": 131.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 181.0, 184.0, 181.0, 183.0, 184.0, 184.0, 184.0, 181.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 58.0, 88.0, 60.0, 60.0, 59.0, 61.0, 74.0, 58.0], "value": 65.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 87.0, 70.0, 68.0, 68.0, 83.0, 68.0, 68.0, 83.0], "value": 75.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 87.0, 89.0, 85.0, 91.0, 87.0, 90.0, 86.0], "value": 88.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 98.0, 98.0, 99.0, 97.0, 98.0, 99.0, 99.0, 99.0], "value": 98.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 87.0, 84.0, 84.0, 86.0, 85.0, 86.0, 85.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 56.0, 57.0, 58.0, 59.0, 59.0, 58.0, 58.0, 58.0], "value": 57.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 68.0, 77.0, 73.0, 70.0, 70.0, 71.0, 70.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 87.0, 84.0, 89.0, 84.0, 97.0, 100.0, 84.0, 84.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 134.0, 138.0, 149.0, 152.0, 150.0, 142.0, 179.0, 138.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 228.0, 220.0, 169.0, 167.0, 169.0, 167.0, 166.0, 155.0, 204.0], "value": 181.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 65.0, 64.0, 65.0, 64.0, 65.0, 64.0, 68.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1459.3, "alertThreshold": 2.0}]} 02:24:45 INFO - Return code: 0 02:24:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:24:45 ERROR - # TBPL SUCCESS # 02:24:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:24:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:24:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:24:45 INFO - 2017-02-04 02:24:45.504 screenresolution[1662:10548] starting screenresolution argv=screenresolution get 02:24:45 INFO - 2017-02-04 02:24:45.507 screenresolution[1662:10548] Display 0: 1600x1200x32@60 02:24:45 INFO - 2017-02-04 02:24:45.513 screenresolution[1663:10551] starting screenresolution argv=screenresolution list 02:24:45 INFO - Available Modes on Display 0 02:24:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:24:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:24:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:24:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:24:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:24:45 INFO - Intel Iris: 02:24:45 INFO - Chipset Model: Intel Iris 02:24:45 INFO - Type: GPU 02:24:45 INFO - Bus: Built-In 02:24:45 INFO - VRAM (Dynamic, Max): 1536 MB 02:24:45 INFO - Vendor: Intel (0x8086) 02:24:45 INFO - Device ID: 0x0a2e 02:24:45 INFO - Revision ID: 0x0009 02:24:45 INFO - Displays: 02:24:45 INFO - Display: 02:24:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:24:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:24:45 INFO - Display Serial Number: 1600x1200 60 02:24:45 INFO - Main Display: Yes 02:24:45 INFO - Mirror: Off 02:24:45 INFO - Online: Yes 02:24:45 INFO - Rotation: Supported 02:24:45 INFO - Return code: 0 02:24:45 INFO - Running post-action listener: _resource_record_post_action 02:24:45 INFO - [mozharness: 2017-02-04 10:24:45.756942Z] Finished run-tests step (success) 02:24:45 INFO - Running post-run listener: _resource_record_post_run 02:24:45 INFO - Total resource usage - Wall time: 1200s; CPU: 53.0%; Read bytes: 32590848; Write bytes: 510281216; Read time: 196; Write time: 1604 02:24:45 INFO - TinderboxPrint: CPU usage
53.4% 02:24:45 INFO - TinderboxPrint: I/O read bytes / time
32,590,848 / 196 02:24:45 INFO - TinderboxPrint: I/O write bytes / time
510,281,216 / 1,604 02:24:45 INFO - TinderboxPrint: CPU idle
2,236.2 (46.6%) 02:24:45 INFO - TinderboxPrint: CPU system
959.6 (20.0%) 02:24:45 INFO - TinderboxPrint: CPU user
1,605.9 (33.4%) 02:24:45 INFO - TinderboxPrint: Swap in / out
250,728,448 / 0 02:24:45 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205332992; Write bytes: 200505344; Read time: 15773; Write time: 430 02:24:45 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 30485504; Write bytes: 296697344; Read time: 177; Write time: 1132 02:24:46 INFO - Running post-run listener: _upload_blobber_files 02:24:46 INFO - Blob upload gear active. 02:24:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:24:46 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:24:46 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'] 02:24:46 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 02:24:46 INFO - (blobuploader) - INFO - Open directory for files ... 02:24:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:24:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:24:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:24:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:47 INFO - (blobuploader) - INFO - Done attempting. 02:24:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:24:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:24:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:24:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:48 INFO - (blobuploader) - INFO - Done attempting. 02:24:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:24:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:24:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:24:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:49 INFO - (blobuploader) - INFO - Done attempting. 02:24:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:24:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:24:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:24:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:52 INFO - (blobuploader) - INFO - Done attempting. 02:24:52 INFO - (blobuploader) - INFO - Iteration through files over. 02:24:52 INFO - Return code: 0 02:24:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:24:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:24:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e148869055fd0a9225463d1984db17256ec72247d576a638e19686fec98381161473e605d312b1470addb9ac2431038aee640190cc831083c461631a464e233a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ad4f9505fac869b3096955049668b26b3e5b60d8df91b84937aa50f7f496195b55b1538d0df972d2fff4d93f70284830e13692269105bcb55852b5537a836eb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27de9ee09a39da098a5cfdf7d26d644787458f7dffb4a1587703f4a6856f8c2f34a5947d036fbf77924978673a93fa4edb8c62297e32dbee4334222e8e99912f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9c2ac45120fe8492ef9e20abe97abb3cfcc27027cd704f95ef664a1cc8fcd05fdfbd63eb9a20a7de4923987068845a5422f547fa35d5a4a97e99863301878549"} 02:24:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:24:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:24:52 INFO - Contents: 02:24:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e148869055fd0a9225463d1984db17256ec72247d576a638e19686fec98381161473e605d312b1470addb9ac2431038aee640190cc831083c461631a464e233a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ad4f9505fac869b3096955049668b26b3e5b60d8df91b84937aa50f7f496195b55b1538d0df972d2fff4d93f70284830e13692269105bcb55852b5537a836eb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27de9ee09a39da098a5cfdf7d26d644787458f7dffb4a1587703f4a6856f8c2f34a5947d036fbf77924978673a93fa4edb8c62297e32dbee4334222e8e99912f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9c2ac45120fe8492ef9e20abe97abb3cfcc27027cd704f95ef664a1cc8fcd05fdfbd63eb9a20a7de4923987068845a5422f547fa35d5a4a97e99863301878549"} 02:24:52 INFO - Running post-run listener: copy_logs_to_upload_dir 02:24:52 INFO - Copying logs to upload dir... 02:24:52 INFO - mkdir: /builds/slave/test/build/upload/logs 02:24:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.505711 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-04 02:24:52.233677) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-04 02:24:52.237308) ========= 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.K8mVeOhn8K/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.yz6NthnOtp/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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/e148869055fd0a9225463d1984db17256ec72247d576a638e19686fec98381161473e605d312b1470addb9ac2431038aee640190cc831083c461631a464e233a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ad4f9505fac869b3096955049668b26b3e5b60d8df91b84937aa50f7f496195b55b1538d0df972d2fff4d93f70284830e13692269105bcb55852b5537a836eb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27de9ee09a39da098a5cfdf7d26d644787458f7dffb4a1587703f4a6856f8c2f34a5947d036fbf77924978673a93fa4edb8c62297e32dbee4334222e8e99912f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9c2ac45120fe8492ef9e20abe97abb3cfcc27027cd704f95ef664a1cc8fcd05fdfbd63eb9a20a7de4923987068845a5422f547fa35d5a4a97e99863301878549"} build_url:https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011315 build_url: 'https://queue.taskcluster.net/v1/task/WpcjcH3HSb6TxeQM0webRA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e148869055fd0a9225463d1984db17256ec72247d576a638e19686fec98381161473e605d312b1470addb9ac2431038aee640190cc831083c461631a464e233a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ad4f9505fac869b3096955049668b26b3e5b60d8df91b84937aa50f7f496195b55b1538d0df972d2fff4d93f70284830e13692269105bcb55852b5537a836eb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27de9ee09a39da098a5cfdf7d26d644787458f7dffb4a1587703f4a6856f8c2f34a5947d036fbf77924978673a93fa4edb8c62297e32dbee4334222e8e99912f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9c2ac45120fe8492ef9e20abe97abb3cfcc27027cd704f95ef664a1cc8fcd05fdfbd63eb9a20a7de4923987068845a5422f547fa35d5a4a97e99863301878549"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-04 02:24:52.272309) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 02:24:52.272633) ========= 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.K8mVeOhn8K/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.yz6NthnOtp/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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.005136 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-04 02:24:52.322711) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-04 02:24:52.323259) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-04 02:24:52.323794) ========= ========= Total master_lag: 0.18 =========