builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0236 starttime: 1488904029.31 results: success (0) buildid: 20170307064827 builduid: 145383adfff444028c35dab02bbafdf0 revision: 2b89485cf946cdfdd69e5d11aa6af2563fa7520d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-07 08:27:09.311783) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-07 08:27:09.312212) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-07 08:27:09.312496) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-07 08:27:09.338841) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 08:27:09.339211) ========= 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.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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-03-07 08:27:09-- 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% 22.6M=0.001s 2017-03-07 08:27:09 (22.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.096686 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 08:27:09.457140) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 08:27:09.457452) ========= 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.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.025414 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 08:27:09.505646) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-07 08:27:09.506020) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2b89485cf946cdfdd69e5d11aa6af2563fa7520d --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-beta --rev 2b89485cf946cdfdd69e5d11aa6af2563fa7520d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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-03-07 08:27:09,612 truncating revision to first 12 chars 2017-03-07 08:27:09,612 Setting DEBUG logging. 2017-03-07 08:27:09,612 attempt 1/10 2017-03-07 08:27:09,612 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2b89485cf946?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-07 08:27:11,982 unpacking tar archive at: mozilla-beta-2b89485cf946/testing/mozharness/ program finished with exit code 0 elapsedTime=2.644103 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-07 08:27:12.174254) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-07 08:27:12.174590) ========= script_repo_revision: 2b89485cf946cdfdd69e5d11aa6af2563fa7520d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-07 08:27:12.174996) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-07 08:27:12.175258) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-07 08:27:12.195481) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-07 08:27:12.196048) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:27:12 INFO - MultiFileLogger online at 20170307 08:27:12 in /builds/slave/test 08:27:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 08:27:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:27:12 INFO - {'append_to_log': False, 08:27:12 INFO - 'base_work_dir': '/builds/slave/test', 08:27:12 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:27:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:27:12 INFO - 'branch': 'Mozilla-Beta', 08:27:12 INFO - 'buildbot_json_path': 'buildprops.json', 08:27:12 INFO - 'config_files': ('talos/mac_config.py',), 08:27:12 INFO - 'default_actions': ('clobber', 08:27:12 INFO - 'read-buildbot-config', 08:27:12 INFO - 'download-and-extract', 08:27:12 INFO - 'populate-webroot', 08:27:12 INFO - 'create-virtualenv', 08:27:12 INFO - 'install', 08:27:12 INFO - 'run-tests'), 08:27:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:27:12 INFO - 'download_minidump_stackwalk': True, 08:27:12 INFO - 'download_symbols': 'ondemand', 08:27:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:27:12 INFO - 'tooltool.py': '/tools/tooltool.py', 08:27:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:27:12 INFO - '/tools/misc-python/virtualenv.py')}, 08:27:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:27:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:27:12 INFO - 'installer_path': 'installer.exe', 08:27:12 INFO - 'log_level': 'info', 08:27:12 INFO - 'log_name': 'talos', 08:27:12 INFO - 'log_to_console': True, 08:27:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:27:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:27:12 INFO - 'opt_config_files': (), 08:27:12 INFO - 'pip_index': False, 08:27:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:27:12 INFO - 'cmd': ('bash', 08:27:12 INFO - '-c', 08:27:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:27:12 INFO - 'enabled': True, 08:27:12 INFO - 'halt_on_failure': False, 08:27:12 INFO - 'name': 'check_screen_resolution'},), 08:27:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:27:12 INFO - 'cmd': ('bash', 08:27:12 INFO - '-c', 08:27:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:27:12 INFO - 'enabled': True, 08:27:12 INFO - 'halt_on_failure': False, 08:27:12 INFO - 'name': 'check_screen_resolution'},), 08:27:12 INFO - 'run_cmd_checks_enabled': True, 08:27:12 INFO - 'sps_profile': False, 08:27:12 INFO - 'sps_profile_interval': 0, 08:27:12 INFO - 'suite': 'dromaeojs-e10s', 08:27:12 INFO - 'system_bits': '32', 08:27:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:27:12 INFO - 'title': 't-yosemite-r7-0236', 08:27:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:27:12 INFO - 'use_talos_json': True, 08:27:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:27:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:27:12 INFO - 'work_dir': 'build'} 08:27:12 INFO - [mozharness: 2017-03-07 16:27:12.406731Z] Running clobber step. 08:27:12 INFO - Running pre-action listener: _resource_record_pre_action 08:27:12 INFO - Running main action method: clobber 08:27:12 INFO - rmtree: /builds/slave/test/build 08:27:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:27:14 INFO - Running post-action listener: _resource_record_post_action 08:27:14 INFO - [mozharness: 2017-03-07 16:27:14.960991Z] Finished clobber step (success) 08:27:14 INFO - [mozharness: 2017-03-07 16:27:14.961272Z] Running read-buildbot-config step. 08:27:14 INFO - Running pre-action listener: _resource_record_pre_action 08:27:14 INFO - Running main action method: read_buildbot_config 08:27:14 INFO - Using buildbot properties: 08:27:14 INFO - { 08:27:14 INFO - "project": "", 08:27:14 INFO - "product": "firefox", 08:27:14 INFO - "script_repo_revision": "production", 08:27:14 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 08:27:14 INFO - "repository": "", 08:27:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 08:27:14 INFO - "buildid": "20170307064827", 08:27:14 INFO - "pgo_build": "False", 08:27:14 INFO - "basedir": "/builds/slave/test", 08:27:14 INFO - "buildnumber": 0, 08:27:14 INFO - "slavename": "t-yosemite-r7-0236", 08:27:14 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:27:14 INFO - "platform": "yosemite_r7", 08:27:14 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 08:27:14 INFO - "branch": "mozilla-beta", 08:27:14 INFO - "repo_path": "releases/mozilla-beta", 08:27:14 INFO - "revision": "2b89485cf946cdfdd69e5d11aa6af2563fa7520d", 08:27:14 INFO - "stage_platform": "macosx64", 08:27:14 INFO - "builduid": "145383adfff444028c35dab02bbafdf0", 08:27:14 INFO - "slavebuilddir": "test" 08:27:14 INFO - } 08:27:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 08:27:14 INFO - Running post-action listener: _resource_record_post_action 08:27:14 INFO - [mozharness: 2017-03-07 16:27:14.966991Z] Finished read-buildbot-config step (success) 08:27:14 INFO - [mozharness: 2017-03-07 16:27:14.967213Z] Running download-and-extract step. 08:27:14 INFO - Running pre-action listener: _resource_record_pre_action 08:27:14 INFO - Running main action method: download_and_extract 08:27:14 INFO - mkdir: /builds/slave/test/build/tests 08:27:14 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')]} 08:27:14 INFO - trying https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 08:27:14 INFO - Downloading https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 08:27:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 08:27:16 INFO - Downloaded 1165 bytes. 08:27:16 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 08:27:16 INFO - Using the following test package requirements: 08:27:16 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 08:27:16 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:27:16 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 08:27:16 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:27:16 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 08:27:16 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:27:16 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:27:16 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 08:27:16 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 08:27:16 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:27:16 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 08:27:16 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:27:16 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 08:27:16 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:27:16 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 08:27:16 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:27:16 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 08:27:16 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 08:27:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 08:27:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 08:27:16 INFO - Fetch https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 08:27:17 INFO - Expected file size: 16103465 08:27:17 INFO - Obtained file size: 16103465 08:27:23 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 08:27:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 08:27:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 08:27:23 INFO - Fetch https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 08:27:24 INFO - Expected file size: 16103465 08:27:24 INFO - Obtained file size: 16103465 08:27:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 08:27:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 08:27:31 INFO - Fetch https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 08:27:32 INFO - Expected file size: 14403650 08:27:32 INFO - Obtained file size: 14403650 08:27:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:32 INFO - trying https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg 08:27:32 INFO - Downloading https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 08:27:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 08:27:34 INFO - Downloaded 58692621 bytes. 08:27:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg 08:27:34 INFO - mkdir: /builds/slave/test/properties 08:27:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:27:34 INFO - Writing to file /builds/slave/test/properties/build_url 08:27:34 INFO - Contents: 08:27:34 INFO - build_url:https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg 08:27:34 INFO - Running post-action listener: _resource_record_post_action 08:27:34 INFO - Running post-action listener: set_extra_try_arguments 08:27:34 INFO - [mozharness: 2017-03-07 16:27:34.788690Z] Finished download-and-extract step (success) 08:27:34 INFO - [mozharness: 2017-03-07 16:27:34.788865Z] Running populate-webroot step. 08:27:34 INFO - Running pre-action listener: _resource_record_pre_action 08:27:34 INFO - Running main action method: populate_webroot 08:27:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:27:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:27:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:27:34 INFO - '--tppagecycles', 08:27:34 INFO - '7'], 08:27:34 INFO - 'tests': ['tsvgm']}}, 08:27:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:27:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:27:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:27:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:27:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:27:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:27:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:27:34 INFO - 'talos_options': ['--disable-e10s'], 08:27:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:27:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:27:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:27:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:27:34 INFO - 'talos_options': ['--disable-e10s'], 08:27:34 INFO - 'tests': ['damp', 'tps']}, 08:27:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:27:34 INFO - 'tests': ['damp', 'tps']}, 08:27:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:27:34 INFO - 'tests': ['dromaeo_dom']}, 08:27:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:27:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:27:34 INFO - 'tests': ['basic_compositor_video']}, 08:27:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:27:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:27:34 INFO - 'tests': ['a11yr', 08:27:34 INFO - 'ts_paint', 08:27:34 INFO - 'tpaint', 08:27:34 INFO - 'sessionrestore', 08:27:34 INFO - 'sessionrestore_no_auto_restore', 08:27:34 INFO - 'tabpaint']}, 08:27:34 INFO - 'other-e10s': {'tests': ['a11yr', 08:27:34 INFO - 'ts_paint', 08:27:34 INFO - 'tpaint', 08:27:34 INFO - 'sessionrestore', 08:27:34 INFO - 'sessionrestore_no_auto_restore', 08:27:34 INFO - 'tabpaint']}, 08:27:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:27:34 INFO - 'ts_paint', 08:27:34 INFO - 'tpaint', 08:27:34 INFO - 'sessionrestore', 08:27:34 INFO - 'sessionrestore_no_auto_restore', 08:27:34 INFO - 'tabpaint']}, 08:27:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:27:34 INFO - 'ts_paint', 08:27:34 INFO - 'tpaint', 08:27:34 INFO - 'sessionrestore', 08:27:34 INFO - 'sessionrestore_no_auto_restore', 08:27:34 INFO - 'tabpaint']}, 08:27:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:27:34 INFO - 'tests': ['a11yr', 08:27:34 INFO - 'ts_paint', 08:27:34 INFO - 'tpaint', 08:27:34 INFO - 'sessionrestore', 08:27:34 INFO - 'sessionrestore_no_auto_restore', 08:27:34 INFO - 'tabpaint']}, 08:27:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:27:34 INFO - 'tests': ['a11yr', 08:27:34 INFO - 'ts_paint', 08:27:34 INFO - 'tpaint', 08:27:34 INFO - 'sessionrestore', 08:27:34 INFO - 'sessionrestore_no_auto_restore', 08:27:34 INFO - 'tabpaint']}, 08:27:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:27:34 INFO - 'tests': ['tsvgx', 08:27:34 INFO - 'tsvgr_opacity', 08:27:34 INFO - 'tart', 08:27:34 INFO - 'tscrollx', 08:27:34 INFO - 'cart', 08:27:34 INFO - 'tsvg_static']}, 08:27:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:27:34 INFO - 'tsvgr_opacity', 08:27:34 INFO - 'tart', 08:27:34 INFO - 'tscrollx', 08:27:34 INFO - 'cart', 08:27:34 INFO - 'tsvg_static']}, 08:27:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:27:34 INFO - 'talos_options': ['--disable-e10s'], 08:27:34 INFO - 'tests': ['tp5o']}, 08:27:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:27:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:27:34 INFO - 'talos_options': ['--disable-e10s', 08:27:34 INFO - '--xperf_path', 08:27:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:27:34 INFO - 'tests': ['tp5n']}, 08:27:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:27:34 INFO - 'talos_options': ['--xperf_path', 08:27:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:27:34 INFO - 'tests': ['tp5n']}}, 08:27:34 INFO - 'talos.zip': {'path': '', 08:27:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:27:34 INFO - Running post-action listener: _resource_record_post_action 08:27:34 INFO - [mozharness: 2017-03-07 16:27:34.807679Z] Finished populate-webroot step (success) 08:27:34 INFO - [mozharness: 2017-03-07 16:27:34.807813Z] Running create-virtualenv step. 08:27:34 INFO - Running pre-action listener: _resource_record_pre_action 08:27:34 INFO - Running main action method: create_virtualenv 08:27:34 INFO - Creating virtualenv /builds/slave/test/build/venv 08:27:34 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 08:27:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:27:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:27:34 INFO - Using real prefix '/tools/python27' 08:27:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:27:35 INFO - Installing distribute.............................................................................................................................................................................................done. 08:27:38 INFO - Installing pip.................done. 08:27:38 INFO - Return code: 0 08:27:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:27:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:27:38 INFO - Reading from file tmpfile_stdout 08:27:38 INFO - Output received: 08:27:38 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) 08:27:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:38 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 0x10b14bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f939bc95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ae50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af1ebe8>, '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.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:27:38 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 08:27:38 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 08:27:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBdh5Yb1nU/Render', 08:27:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:38 INFO - 'HOME': '/Users/cltbld', 08:27:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:38 INFO - 'LOGNAME': 'cltbld', 08:27:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:38 INFO - 'MOZ_NO_REMOTE': '1', 08:27:38 INFO - 'NO_EM_RESTART': '1', 08:27:38 INFO - 'PAGER': '/bin/cat', 08:27:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:38 INFO - 'PWD': '/builds/slave/test', 08:27:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:38 INFO - 'SHELL': '/bin/bash', 08:27:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ljfo6lkHo9/Listeners', 08:27:38 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:27:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:38 INFO - 'USER': 'cltbld', 08:27:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:38 INFO - 'XPC_FLAGS': '0x0', 08:27:38 INFO - 'XPC_SERVICE_NAME': '0', 08:27:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:39 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 08:27:39 INFO - Cleaning up... 08:27:39 INFO - Return code: 0 08:27:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:27: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')]} 08:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27: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')]} 08:27:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:39 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 0x10b14bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f939bc95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ae50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af1ebe8>, '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.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:27:39 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 08:27:39 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 08:27:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBdh5Yb1nU/Render', 08:27:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:39 INFO - 'HOME': '/Users/cltbld', 08:27:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:39 INFO - 'LOGNAME': 'cltbld', 08:27:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:39 INFO - 'MOZ_NO_REMOTE': '1', 08:27:39 INFO - 'NO_EM_RESTART': '1', 08:27:39 INFO - 'PAGER': '/bin/cat', 08:27:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:39 INFO - 'PWD': '/builds/slave/test', 08:27:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:39 INFO - 'SHELL': '/bin/bash', 08:27:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ljfo6lkHo9/Listeners', 08:27:39 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:27:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:39 INFO - 'USER': 'cltbld', 08:27:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:39 INFO - 'XPC_FLAGS': '0x0', 08:27:39 INFO - 'XPC_SERVICE_NAME': '0', 08:27:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:39 INFO - Downloading/unpacking psutil>=3.1.1 08:27:39 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 08:27:39 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 08:27:39 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 08:27:39 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 08:27:39 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 08:27:39 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 08:27:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:27:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:27:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:27:43 INFO - Installing collected packages: psutil 08:27:43 INFO - Running setup.py install for psutil 08:27:43 INFO - building 'psutil._psutil_osx' extension 08:27:43 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 08:27:43 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 08:27:43 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 08:27:43 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 08:27:43 INFO - building 'psutil._psutil_posix' extension 08:27:43 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 08:27:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:27:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:27:44 INFO - ^ 08:27:44 INFO - 1 warning generated. 08:27:44 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 08:27:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:27:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:27:44 INFO - Successfully installed psutil 08:27:44 INFO - Cleaning up... 08:27:44 INFO - Return code: 0 08:27:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:27: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')]} 08:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27: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')]} 08:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b14bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f939bc95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ae50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af1ebe8>, '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.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:27: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:27: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 mozsystemmonitor==0.3 08:27:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBdh5Yb1nU/Render', 08:27:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:44 INFO - 'HOME': '/Users/cltbld', 08:27:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:44 INFO - 'LOGNAME': 'cltbld', 08:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:44 INFO - 'MOZ_NO_REMOTE': '1', 08:27:44 INFO - 'NO_EM_RESTART': '1', 08:27:44 INFO - 'PAGER': '/bin/cat', 08:27:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:44 INFO - 'PWD': '/builds/slave/test', 08:27:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:44 INFO - 'SHELL': '/bin/bash', 08:27:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ljfo6lkHo9/Listeners', 08:27:44 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:27:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:44 INFO - 'USER': 'cltbld', 08:27:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:44 INFO - 'XPC_FLAGS': '0x0', 08:27:44 INFO - 'XPC_SERVICE_NAME': '0', 08:27:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:27:44 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 08:27:44 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 08:27:44 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 08:27:44 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 08:27:44 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 08:27:44 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 08:27:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:27:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:27:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:27:47 INFO - Installing collected packages: mozsystemmonitor 08:27:47 INFO - Running setup.py install for mozsystemmonitor 08:27:47 INFO - Successfully installed mozsystemmonitor 08:27:47 INFO - Cleaning up... 08:27:47 INFO - Return code: 0 08:27:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:27:47 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')]} 08:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:47 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')]} 08:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:47 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 0x10b14bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f939bc95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ae50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af1ebe8>, '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.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:27:47 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 08:27:47 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 08:27:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBdh5Yb1nU/Render', 08:27:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:47 INFO - 'HOME': '/Users/cltbld', 08:27:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:47 INFO - 'LOGNAME': 'cltbld', 08:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:47 INFO - 'MOZ_NO_REMOTE': '1', 08:27:47 INFO - 'NO_EM_RESTART': '1', 08:27:47 INFO - 'PAGER': '/bin/cat', 08:27:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:47 INFO - 'PWD': '/builds/slave/test', 08:27:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:47 INFO - 'SHELL': '/bin/bash', 08:27:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ljfo6lkHo9/Listeners', 08:27:47 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:27:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:47 INFO - 'USER': 'cltbld', 08:27:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:47 INFO - 'XPC_FLAGS': '0x0', 08:27:47 INFO - 'XPC_SERVICE_NAME': '0', 08:27:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:47 INFO - Downloading/unpacking jsonschema==2.5.1 08:27:47 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 08:27:47 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 08:27:47 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 08:27:47 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 08:27:47 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 08:27:47 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 08:27:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:27:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:27:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:27:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:27:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:27:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:27:50 INFO - Downloading functools32-3.2.3-2.tar.gz 08:27:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:27:50 INFO - warning: no files found matching '*.txt' 08:27:50 INFO - no previously-included directories found matching 'build' 08:27:50 INFO - no previously-included directories found matching 'dist' 08:27:50 INFO - no previously-included directories found matching '.git*' 08:27:50 INFO - Installing collected packages: jsonschema, functools32 08:27:50 INFO - Running setup.py install for functools32 08:27:51 INFO - warning: no files found matching '*.txt' 08:27:51 INFO - no previously-included directories found matching 'build' 08:27:51 INFO - no previously-included directories found matching 'dist' 08:27:51 INFO - no previously-included directories found matching '.git*' 08:27:51 INFO - Successfully installed jsonschema functools32 08:27:51 INFO - Cleaning up... 08:27:51 INFO - Return code: 0 08:27:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:27: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')]} 08:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27: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')]} 08:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b14bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f939bc95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ae50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af1ebe8>, '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.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:27:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:27:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBdh5Yb1nU/Render', 08:27:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:51 INFO - 'HOME': '/Users/cltbld', 08:27:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:51 INFO - 'LOGNAME': 'cltbld', 08:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:51 INFO - 'MOZ_NO_REMOTE': '1', 08:27:51 INFO - 'NO_EM_RESTART': '1', 08:27:51 INFO - 'PAGER': '/bin/cat', 08:27:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:51 INFO - 'PWD': '/builds/slave/test', 08:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:51 INFO - 'SHELL': '/bin/bash', 08:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ljfo6lkHo9/Listeners', 08:27:51 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:51 INFO - 'USER': 'cltbld', 08:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:51 INFO - 'XPC_FLAGS': '0x0', 08:27:51 INFO - 'XPC_SERVICE_NAME': '0', 08:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:27:51 INFO - Cleaning up... 08:27:51 INFO - Return code: 0 08:27:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:27: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')]} 08:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27: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')]} 08:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b14bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f939bc95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ae50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af1ebe8>, '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.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:27:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:27:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBdh5Yb1nU/Render', 08:27:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:51 INFO - 'HOME': '/Users/cltbld', 08:27:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:51 INFO - 'LOGNAME': 'cltbld', 08:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:51 INFO - 'MOZ_NO_REMOTE': '1', 08:27:51 INFO - 'NO_EM_RESTART': '1', 08:27:51 INFO - 'PAGER': '/bin/cat', 08:27:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:51 INFO - 'PWD': '/builds/slave/test', 08:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:51 INFO - 'SHELL': '/bin/bash', 08:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ljfo6lkHo9/Listeners', 08:27:51 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:51 INFO - 'USER': 'cltbld', 08:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:51 INFO - 'XPC_FLAGS': '0x0', 08:27:51 INFO - 'XPC_SERVICE_NAME': '0', 08:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:51 INFO - Downloading/unpacking blobuploader==1.2.4 08:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:27:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:27:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:27:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:27:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:27:54 INFO - Downloading blobuploader-1.2.4.tar.gz 08:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:27:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:27:54 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 08:27:54 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 08:27:54 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 08:27:54 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 08:27:54 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 08:27:54 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 08:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:27:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:27:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:27:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:27:55 INFO - Downloading docopt-0.6.1.tar.gz 08:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:27:55 INFO - Installing collected packages: blobuploader, requests, docopt 08:27:55 INFO - Running setup.py install for blobuploader 08:27:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:27:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:27:55 INFO - Running setup.py install for requests 08:27:56 INFO - Running setup.py install for docopt 08:27:56 INFO - Successfully installed blobuploader requests docopt 08:27:56 INFO - Cleaning up... 08:27:56 INFO - Return code: 0 08:27:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:27:56 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 0x10b14bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f939bc95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ae50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af1ebe8>, '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.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:27:56 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 08:27:56 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 08:27:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBdh5Yb1nU/Render', 08:27:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:56 INFO - 'HOME': '/Users/cltbld', 08:27:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:56 INFO - 'LOGNAME': 'cltbld', 08:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:56 INFO - 'MOZ_NO_REMOTE': '1', 08:27:56 INFO - 'NO_EM_RESTART': '1', 08:27:56 INFO - 'PAGER': '/bin/cat', 08:27:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:56 INFO - 'PWD': '/builds/slave/test', 08:27:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:56 INFO - 'SHELL': '/bin/bash', 08:27:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ljfo6lkHo9/Listeners', 08:27:56 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:27:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:56 INFO - 'USER': 'cltbld', 08:27:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:56 INFO - 'XPC_FLAGS': '0x0', 08:27:56 INFO - 'XPC_SERVICE_NAME': '0', 08:27:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:27:56 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-LqRS1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:27:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-pzF_tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:27:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Nrmgq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:27:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-1ySkNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:27:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-K5usWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:27:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-4fOONy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:27:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-BKk8p5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:27:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-RNRUSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:27:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-KflOd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:27:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-naVvfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:27:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-rJ4BBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:27:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Aif6jR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:27:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Cgpaew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:27:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-df3GGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:27:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Wz2MK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:27:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-PKGr7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:27:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-tq1OwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:27:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:27:58 INFO - Running setup.py install for manifestparser 08:27:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:27:58 INFO - Running setup.py install for mozcrash 08:27:58 INFO - Running setup.py install for mozdebug 08:27:59 INFO - Running setup.py install for mozdevice 08:27:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:27:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:27:59 INFO - Running setup.py install for mozfile 08:27:59 INFO - Running setup.py install for mozhttpd 08:27:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:27:59 INFO - Running setup.py install for mozinfo 08:27:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:27:59 INFO - Running setup.py install for mozInstall 08:27:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:27:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:27:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:27:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:27:59 INFO - Running setup.py install for mozleak 08:27:59 INFO - Running setup.py install for mozlog 08:28:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:28:00 INFO - Running setup.py install for moznetwork 08:28:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:28:00 INFO - Running setup.py install for mozprocess 08:28:00 INFO - Running setup.py install for mozprofile 08:28:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:28:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:28:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:28:00 INFO - Running setup.py install for mozrunner 08:28:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:28:00 INFO - Running setup.py install for mozscreenshot 08:28:00 INFO - Running setup.py install for moztest 08:28:01 INFO - Running setup.py install for mozversion 08:28:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:28:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:28:01 INFO - Cleaning up... 08:28:01 INFO - Return code: 0 08:28:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:28:01 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')]} 08:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:01 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')]} 08:28:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:01 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 0x10b14bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f939bc95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ae50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af1ebe8>, '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.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:28:01 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 08:28:01 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 08:28:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBdh5Yb1nU/Render', 08:28:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:01 INFO - 'HOME': '/Users/cltbld', 08:28:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:01 INFO - 'LOGNAME': 'cltbld', 08:28:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:01 INFO - 'MOZ_NO_REMOTE': '1', 08:28:01 INFO - 'NO_EM_RESTART': '1', 08:28:01 INFO - 'PAGER': '/bin/cat', 08:28:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:01 INFO - 'PWD': '/builds/slave/test', 08:28:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:01 INFO - 'SHELL': '/bin/bash', 08:28:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ljfo6lkHo9/Listeners', 08:28:01 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:28:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:01 INFO - 'USER': 'cltbld', 08:28:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:01 INFO - 'XPC_FLAGS': '0x0', 08:28:01 INFO - 'XPC_SERVICE_NAME': '0', 08:28:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:28:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-qLlB2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:28:01 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)) 08:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:28:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-57S2Hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:28:01 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)) 08:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:28:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-_2sAOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:28:01 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)) 08:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:28:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-LGU94M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:28:01 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)) 08:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:28:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-QDuMCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:28:02 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)) 08:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:28:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-8nGrwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:28:02 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)) 08:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:28:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-L1U5Fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:28:02 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)) 08:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:28:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-0JuAHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:28:02 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)) 08:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:28:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-cmzVKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:28:02 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)) 08:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:28:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-QKHITG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:28:02 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)) 08:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:28:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-EBU0A_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:28:02 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)) 08:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:28:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-aH1isT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:28:02 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)) 08:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:28:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-9Q49ki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:28:02 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)) 08:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:28:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-lsT816-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:28:03 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)) 08:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:28:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Tm5PKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:28:03 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)) 08:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:28:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-DhLjV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:28:03 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)) 08:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:28:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-0Yow6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:28:03 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)) 08:28:03 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)) 08:28:03 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)) 08:28:03 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)) 08:28:03 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)) 08:28:03 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)) 08:28:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:28:05 INFO - Downloading blessings-1.6.tar.gz 08:28:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:28:06 INFO - Installing collected packages: blessings 08:28:06 INFO - Running setup.py install for blessings 08:28:06 INFO - Successfully installed blessings 08:28:06 INFO - Cleaning up... 08:28:06 INFO - Return code: 0 08:28:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:28:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:28:06 INFO - Reading from file tmpfile_stdout 08:28:06 INFO - Current package versions: 08:28:06 INFO - blessings == 1.6 08:28:06 INFO - blobuploader == 1.2.4 08:28:06 INFO - docopt == 0.6.1 08:28:06 INFO - functools32 == 3.2.3-2 08:28:06 INFO - jsonschema == 2.5.1 08:28:06 INFO - manifestparser == 1.1 08:28:06 INFO - mozInstall == 1.12 08:28:06 INFO - mozcrash == 1.0 08:28:06 INFO - mozdebug == 0.1 08:28:06 INFO - mozdevice == 0.49 08:28:06 INFO - mozfile == 1.2 08:28:06 INFO - mozhttpd == 0.7 08:28:06 INFO - mozinfo == 0.9 08:28:06 INFO - mozleak == 0.1 08:28:06 INFO - mozlog == 3.4 08:28:06 INFO - moznetwork == 0.27 08:28:06 INFO - mozprocess == 0.24 08:28:06 INFO - mozprofile == 0.28 08:28:06 INFO - mozrunner == 6.13 08:28:06 INFO - mozscreenshot == 0.1 08:28:06 INFO - mozsystemmonitor == 0.3 08:28:06 INFO - moztest == 0.8 08:28:06 INFO - mozversion == 1.4 08:28:06 INFO - psutil == 3.1.1 08:28:06 INFO - requests == 1.2.3 08:28:06 INFO - wsgiref == 0.1.2 08:28:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b14bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f939bc95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ae50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af1ebe8>, '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.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:28:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:28:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:28:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBdh5Yb1nU/Render', 08:28:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:07 INFO - 'HOME': '/Users/cltbld', 08:28:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:07 INFO - 'LOGNAME': 'cltbld', 08:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:07 INFO - 'MOZ_NO_REMOTE': '1', 08:28:07 INFO - 'NO_EM_RESTART': '1', 08:28:07 INFO - 'PAGER': '/bin/cat', 08:28:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:07 INFO - 'PWD': '/builds/slave/test', 08:28:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:07 INFO - 'SHELL': '/bin/bash', 08:28:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ljfo6lkHo9/Listeners', 08:28:07 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:28:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:07 INFO - 'USER': 'cltbld', 08:28:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:07 INFO - 'XPC_FLAGS': '0x0', 08:28:07 INFO - 'XPC_SERVICE_NAME': '0', 08:28:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:28:07 INFO - Cleaning up... 08:28:07 INFO - Return code: 0 08:28:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b14bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f939bc95710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af29d30>, '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 0x10ae50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af1ebe8>, '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.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:28:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:28:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:28:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBdh5Yb1nU/Render', 08:28:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:07 INFO - 'HOME': '/Users/cltbld', 08:28:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:07 INFO - 'LOGNAME': 'cltbld', 08:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:07 INFO - 'MOZ_NO_REMOTE': '1', 08:28:07 INFO - 'NO_EM_RESTART': '1', 08:28:07 INFO - 'PAGER': '/bin/cat', 08:28:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:07 INFO - 'PWD': '/builds/slave/test', 08:28:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:07 INFO - 'SHELL': '/bin/bash', 08:28:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ljfo6lkHo9/Listeners', 08:28:07 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:28:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:07 INFO - 'USER': 'cltbld', 08:28:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:07 INFO - 'XPC_FLAGS': '0x0', 08:28:07 INFO - 'XPC_SERVICE_NAME': '0', 08:28:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:28:08 INFO - Cleaning up... 08:28:08 INFO - Return code: 0 08:28:08 INFO - Running post-action listener: _resource_record_post_action 08:28:08 INFO - Running post-action listener: _start_resource_monitoring 08:28:08 INFO - Starting resource monitoring. 08:28:08 INFO - [mozharness: 2017-03-07 16:28:08.290661Z] Finished create-virtualenv step (success) 08:28:08 INFO - [mozharness: 2017-03-07 16:28:08.291557Z] Running install step. 08:28:08 INFO - Running pre-action listener: _resource_record_pre_action 08:28:08 INFO - Running main action method: install 08:28:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:28:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:28:08 INFO - Reading from file tmpfile_stdout 08:28:08 INFO - Detecting whether we're running mozinstall >=1.0... 08:28:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:28:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:28:08 INFO - Reading from file tmpfile_stdout 08:28:08 INFO - Output received: 08:28:08 INFO - Usage: mozinstall [options] installer 08:28:08 INFO - Options: 08:28:08 INFO - -h, --help show this help message and exit 08:28:08 INFO - -d DEST, --destination=DEST 08:28:08 INFO - Directory to install application into. [default: 08:28:08 INFO - "/builds/slave/test"] 08:28:08 INFO - --app=APP Application being installed. [default: firefox] 08:28:08 INFO - mkdir: /builds/slave/test/build/application 08:28:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:28:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 08:28:23 INFO - Reading from file tmpfile_stdout 08:28:23 INFO - Output received: 08:28:23 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:28:23 INFO - Running post-action listener: _resource_record_post_action 08:28:23 INFO - [mozharness: 2017-03-07 16:28:23.404335Z] Finished install step (success) 08:28:23 INFO - [mozharness: 2017-03-07 16:28:23.404481Z] Running run-tests step. 08:28:23 INFO - Running pre-action listener: _resource_record_pre_action 08:28:23 INFO - Running main action method: run_tests 08:28:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:28:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:28:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:28:23 INFO - 2017-03-07 08:28:23.419 screenresolution[1624:7244] starting screenresolution argv=screenresolution get 08:28:23 INFO - 2017-03-07 08:28:23.421 screenresolution[1624:7244] Display 0: 1600x1200x32@60 08:28:23 INFO - 2017-03-07 08:28:23.427 screenresolution[1625:7246] starting screenresolution argv=screenresolution list 08:28:23 INFO - Available Modes on Display 0 08:28:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:28:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:28:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:28:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:28:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:28:23 INFO - Intel Iris: 08:28:23 INFO - Chipset Model: Intel Iris 08:28:23 INFO - Type: GPU 08:28:23 INFO - Bus: Built-In 08:28:23 INFO - VRAM (Dynamic, Max): 1536 MB 08:28:23 INFO - Vendor: Intel (0x8086) 08:28:23 INFO - Device ID: 0x0a2e 08:28:23 INFO - Revision ID: 0x0009 08:28:23 INFO - Displays: 08:28:23 INFO - Display: 08:28:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:28:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:28:23 INFO - Display Serial Number: 1600x1200 60 08:28:23 INFO - Main Display: Yes 08:28:23 INFO - Mirror: Off 08:28:23 INFO - Online: Yes 08:28:23 INFO - Rotation: Supported 08:28:23 INFO - Return code: 0 08:28:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:28:23 INFO - Python 2.7.3 08:28:23 INFO - Return code: 0 08:28:23 INFO - grabbing minidump binary from tooltool 08:28:23 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')]} 08:28:23 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 0x10af29d30>, '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 0x10ae50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af1ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:28:23 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 08:28:23 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 08:28:23 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 08:28:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:28:23 INFO - Return code: 0 08:28:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:28:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:28:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:28:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:28:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:28:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0236 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:28:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YBdh5Yb1nU/Render', 08:28:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:23 INFO - 'HOME': '/Users/cltbld', 08:28:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:23 INFO - 'LOGNAME': 'cltbld', 08:28:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:28:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:28:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:23 INFO - 'MOZ_NO_REMOTE': '1', 08:28:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:28:23 INFO - 'NO_EM_RESTART': '1', 08:28:23 INFO - 'PAGER': '/bin/cat', 08:28:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:23 INFO - 'PWD': '/builds/slave/test', 08:28:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:28:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:23 INFO - 'SHELL': '/bin/bash', 08:28:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ljfo6lkHo9/Listeners', 08:28:23 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:28:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:23 INFO - 'USER': 'cltbld', 08:28:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:23 INFO - 'XPC_FLAGS': '0x0', 08:28:23 INFO - 'XPC_SERVICE_NAME': '0', 08:28:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:28:24 INFO - mozversion application_buildid: 20170307064827 08:28:24 INFO - mozversion application_changeset: 2b89485cf946cdfdd69e5d11aa6af2563fa7520d 08:28:24 INFO - mozversion application_display_name: Firefox 08:28:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:28:24 INFO - mozversion application_name: Firefox 08:28:24 INFO - mozversion application_remotingname: firefox 08:28:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:28:24 INFO - mozversion application_vendor: Mozilla 08:28:24 INFO - mozversion application_version: 53.0 08:28:24 INFO - mozversion platform_buildid: 20170307064827 08:28:24 INFO - mozversion platform_changeset: 2b89485cf946cdfdd69e5d11aa6af2563fa7520d 08:28:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:28:24 INFO - mozversion platform_version: 53.0 08:28:24 INFO - using testdate: 1488904104 08:28:24 INFO - actual date: 1488904104 08:28:24 INFO - starting webserver on 'localhost:49250' 08:28:24 INFO - SUITE-START | Running 2 tests 08:28:24 INFO - TEST-START | dromaeo_css 08:28:24 INFO - operating with platform_type : mac_ 08:28:24 INFO - Initialising browser for dromaeo_css test... 08:28:24 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpeDu9xd/profile http://localhost:49250/getInfo.html) 08:28:27 INFO - PROCESS | 1635 | 2017-03-07 08:28:26.995 plugin-container[1638:7407] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a33, name = 'com.apple.tsm.portname' 08:28:27 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:28:27 INFO - PROCESS | 1635 | 2017-03-07 08:28:26.996 plugin-container[1638:7407] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a23, name = 'com.apple.CFPasteboardClient' 08:28:27 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:28:27 INFO - PROCESS | 1635 | 2017-03-07 08:28:26.996 plugin-container[1638:7407] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:28:27 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 08:28:27 INFO - PROCESS | 1635 | colorDepth:24 08:28:27 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 08:28:27 INFO - PROCESS | 1635 | __metrics 08:28:27 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 08:28:27 INFO - TEST-INFO | 1635: exit 0 08:28:27 INFO - Browser initialized. 08:28:27 INFO - Running cycle 1/1 for dromaeo_css test... 08:28:27 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpeDu9xd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:28:30 INFO - PROCESS | 1639 | 2017-03-07 08:28:30.048 plugin-container[1640:7546] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 08:28:30 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:28:30 INFO - PROCESS | 1639 | 2017-03-07 08:28:30.049 plugin-container[1640:7546] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4327, name = 'com.apple.CFPasteboardClient' 08:28:30 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:28:30 INFO - PROCESS | 1639 | 2017-03-07 08:28:30.049 plugin-container[1640:7546] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:28:39 INFO - PROCESS | 1639 | RSS: Main: 172523520 08:28:39 INFO - PROCESS | 1639 | 08:31:42 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49250/tests/dromaeo/cssquery-ext.html) 08:31:43 INFO - PROCESS | 1639 | RSS: Main: 188624896 08:31:43 INFO - PROCESS | 1639 | 08:34:46 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-ext.html (next: http://localhost:49250/tests/dromaeo/cssquery-jquery.html) 08:34:46 INFO - PROCESS | 1639 | RSS: Main: 200880128 08:34:46 INFO - PROCESS | 1639 | 08:37:48 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49250/tests/dromaeo/cssquery-mootools.html) 08:37:49 INFO - PROCESS | 1639 | RSS: Main: 203493376 08:37:49 INFO - PROCESS | 1639 | 08:40:51 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49250/tests/dromaeo/cssquery-prototype.html) 08:40:51 INFO - PROCESS | 1639 | RSS: Main: 206594048 08:40:51 INFO - PROCESS | 1639 | 08:43:54 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49250/tests/dromaeo/cssquery-yui.html) 08:43:55 INFO - PROCESS | 1639 | RSS: Main: 207777792 08:43:55 INFO - PROCESS | 1639 | 08:46:57 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-yui.html (next: http://localhost:49250/tests/dromaeo/cssquery-dojo.html) 08:46:57 INFO - PROCESS | 1639 | RSS: Main: 207982592 08:46:57 INFO - PROCESS | 1639 | 08:46:57 INFO - PROCESS | 1639 | __start_tp_report 08:46:57 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 08:46:57 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 08:46:57 INFO - PROCESS | 1639 | |i|pagename|runs| 08:46:57 INFO - PROCESS | 1639 | |0;dojo.html;3414.095264754842;3442.9999999999995;3455.7926524408535;3463.982680086599;3481;5880.500157486614;5900.144479050535;5907.6750778707155;5941.7029148542615;5941.88116237675;5895.174675545427;5950.494207992326;5968.462838344548;5981.491573216282;6012.188354572131;5250.579953603703;5311.389190243113;5339.145736682131;5375.408705042442;5391.379991301012;4709.481956984729;5045.738565358661;5311.62818602698;5364.517193452589;5375;4866.78099485524;5232.058229518685;5306.442823503515;5314;5325.946740532584;5184.533391994739;5243.632945693803;5265.025970195487;5277.70972596507;5282.709450980195;5265.078611243017;5265.842024739264;5290.338707661543;5291.100512912814;5311.256424100629;5694.829155125353;5811.738471768781;5840.795572154997;5934.6142500737315;5941.584089113764;6100.420460056288;6144.262688477412;6265.436110750009;6271.717772700239;6274.058891166362;6198.783042593533;6241.251049873998;6256.468200202989;6325.87348253037;6342.143810585558;6054.8183554493435;6068.9696551517845;6073.119397687355;6090.421409966046;6097.664628445434;6245.75016999324;6368.344230500011;6485.578437401648;6489.383508566679;6505.544611877123;6112.442689633878;6117.705611546597;6124.6325220486915;6131.754729810847;6180.536459765535;6316.684165791692;6325.557210995186;6335.429811316912;6339.746410143543;6351.237851457762;6170.784022559189;6223.626582405071;6230.408111229426;6234.37656234382;6242.15730876335;5209.869753256199;6129.511613759063;6135.171751813448;6139.676133582418;6179.5056395488255;5079.6952182869145;5102.2346648003095;5109.718965456861;5111.131107711698;5133.486651334837;4121.464209652726;4254.851190178696;4548.681592288508;4565.995480994243;4590.839320623829;4625.583697467244;5802.4197580241635;5864.38423965486;5892.646441213541;5908.350081491032;6338.778142764981;6359.20509936258;6359.841003974938;6360.872782544323;6373.553851230462;5643.153526970905;5948.375420580864;5953.107033944856;5960.433758792908;5976.5218782497295;5355.892882142335;5552.69460179694;5555.305586801569;5568.9443105569235;5637.7181140942785;5300.575953923754;6347.936520634919;6373.553851230462;6389.041643753567;6390.360963903572;5192.9740351299515;5533.170024496358;5659.094544872801;6303.180586523906;6316.62100273985;6193.721282542387;6232.844178895564;6266.216722909637;6284.654344011124;6309.3690630936535;6187.257529096537;6225.968870155621;6237.844053898689;6243.250809902659;6252.437280644764;4973.328600638867;5251.264822924717;6301.873962520633;6304.5271604628915;6344.968275158594;6114.479856340571;6142.713648198372;6144.832481828438;6187.350328215653;6210.4100110489435;6160.568760186744;6192.504599632109;6204.348543402878;6210.378962103753;6216.129741836056;5816.592838501264;5855.711743416442;6139.324674285915;6144.41628045335;6170.568060235741;6086.6348019119;6154.630722156685;6178.598391104564;6215.595986260879;6232.626042437287;6104.175936248727;6133.110698948538;6172.166757487682;6185.938140618537;6186.783462578788 08:46:57 INFO - PROCESS | 1639 | |1;ext.html;456.84190576887573;469.8472996276211;495.58866141102885;532.1326238231682;544.1565573361289;41909.161816763684;44273.77863110684;44283.778581107086;44505.10989780202;44513.55486445135;45818.0836383273;46212.306815397795;46230;46260.537394626044;46293;9546.474943878067;9740.172085372751;9750.9512452438;9761.414315141079;11649.825252621218;11212.10303175744;11463.885361146407;11476.885231147708;11481.59814406496;11497.540098396095;11503.252288601214;11517.193796434252;11545.960863522281;11554.480048397842;11571.652850414459;11394.373309467976;11411;11452.141089418245;11477.885221147764;11493.367864767435;11822;11839.348835814028;11847.763044739144;11897.524099036029;11915.463804128749;38932.80533597342;39190.41214381786;39206.21587568261;39234.41148382777;39270;3718.4980027696183;4102.2821006323775;4194.9370759438325;4199.538050814408;4228.746275223496;28834;29110.563341549892;29119.56320655192;30705.77176912921;30927.91752288659;2685.925629748097;3213.1163929919226;3304.2347604702904;3344.0803778960735;3387.220939183977;431452.68547314755;493446.0655393472;497437;498341;502668.486657568;50973.980520389385;59602;61298.38701613016;61845;61855.072173917426;62913;63717.36282637207;63797;63814.40463988437;63970.72058558803;17125.400610978555;17279.136043198043;17290.962542247504;17313.3074677014;17323.740143897852;12074.154809163274;12306.75386492283;13156.868431315565;13902.23537705436;14190.85809141916;20308;22347.770872602163;22518.874056297118;22541.661875071888;22575.548489030127;9109.635614575343;9388.765280867896;9390.671326503676;9397.530123493798;9483.525823708787;911.3301723233418;913.2374467319862;915.2129168914728;944.390867890207;953.3993584042009;30275.545866812015;30294;31241.687583124334;31445.528317075263;32193.034208973768;1083.6965649618119;1102.9669109926715;1116.5310569560804;1151.3034614058374;1257.9244745742442;16257;16395.180240988142;16523.00861948287;16611.262465945307;16701;252045;253040;254307.7284613602;254821;255321;65231;65410.345896541374;65526.67236663881;65856.34143658598;66042.00936985949;1490.128274959161;1491.9179445130405;1518.3698765012616;1529.9847001530065;1537.3927298716944;30270.243243919078;31363.84318078486;31416.842915785273;31583.052508424786;31928;53042.46957530375;53213.20180197222;53409.198862017874;53469.19796202982;53474.93050139053;485149;564732;570452;570945;572316;34921;38786.2242755149;41371.379429309185;44580;45392;45344.546554534034;47911.5208847921;48394.758026209645;48782;49607.503924960285;37859.432108518944;38011.23977520489;39269;39529.01177470586;41068.794656026526;34959.65040349564;35895.461568075974;36252.81873590615;36693.08267293339;37056.814715926244;36135.819320903225;36219;36349.90950271496;37444.62555374411;38077.23845523019;31287.68712312848;33710.66289337174;33930.49104263388;33985.490217646264;34142.317153657285;24019.03923843027;25748.485030299664;25749.3562660935;25770.484590307715;25795 08:46:57 INFO - PROCESS | 1639 | |2;jquery.html;365.1728834190558;396.71634781131485;402.1695199413213;408.78538767147234;433.80478784546943;18436.354727584505;20435.978201089943;20786.16855325789;21739.6739048914;21845.781542184573;18072.81927180728;21991.560168796655;22157;22183.44541386466;22298.665520017214;5820.330661973885;6041.972864613004;6115.29674087479;6132.141500189977;6136.140940268366;5169.715665638389;6008.158857759917;6042.879142417149;6045.546584006217;6124.958757011296;6196.9690151549175;6245.219347581552;6258.843528911791;6259.092431597438;6263.780767673133;5871.295444546639;6246.718897649616;6254.21822272216;6889.724411023553;6898.206706228772;5933.376995415506;6157.846053848645;6171.413715697002;6181.629102253879;6205.782797602107;22921.42696432586;26462.6030609541;26751.464970700476;27011.86494067537;27179;2645.8280211786173;2778.333200031978;2944.544576441642;3061.4336347775734;3073.4928736758407;9933.205343572497;10386.948065259625;10509.789804203949;10810.945945270301;10836.729081772939;1547.5512101490667;1878.4176905159309;1888.5628156754713;1890.1210936914263;1900.144934779341;113117;139013;139564;143851.28074359667;144575;11874.228175168586;12184.695382615506;12231.266124032587;12294.877051229552;12328.383580820922;15945.601359966093;16234.837651623333;16275.674486510203;16297;16342.75485867713;9024.503652299187;9192.540372981324;9259.814803703888;9275.443473391619;9416.435013899189;7704.998350214549;9094.86357704635;9108.089191080971;9248.907510924804;9250.352475326663;4776.402949631296;4826.517348265145;4844.055409195244;4909.919817640113;4940.061388336205;2045.4375046862233;2090.174381119449;2093.7592176899852;2095.21429463951;2103.453102193441;895.820835832823;901.6844104563351;917.3257655623112;932.7015355086443;965.1988849255101;20953.26663566768;21135.8943205283;21878.796666183513;21984.230551930606;22187;933.8739270198572;937.5593471068587;958.1424624960621;959.596969272907;964.6045121500291;10869.728256793485;11320.433978301216;11520.884791152148;11525.366104864146;11567;717622;723580;724302;737492;737498;117154.41422793001;154065;156711;158495;159644.40355596528;1022.4632068164274;1026.235454586338;1059.613241166984;1062.436908438529;1063.3194755356578;20195.798042019393;20638.86486243271;21048.68426973566;21064.15743370248;21076.68384974196;62054;62107.6894615524;62209;64068.679656601416;64290;81980.18019819725;83475;86609;91201;91997;8535.3598480113;8550.273226775545;8581.528015959182;8868.290536756915;8955.283577313669;9420.905790942277;11280.830787538358;12733.55432559856;13391.263480508665;13686.04197706151;9501.334906556474;11435.942820285845;11483;11893.167478276437;11947.10396720232;10987.285826421577;11371.772564548828;11486.081113511067;11698.824517632072;11887.524499019943;9241.445513269226;10118.696439106838;11378.43107844621;11665;11887.108466865193;6689.5986240825705;7319.45104117204;7391.667374968032;7830.3735701144915;8431.831363372576;9562.808743824946;11616.186866919235;11751.588694395656;11919.582814601446;12007.579734709243 08:46:57 INFO - PROCESS | 1639 | |3;mootools.html;1294.2752058847043;1468.0604413175577;1505.7953637090325;1519.642883922278;1521.5435369389181;7778.338841198491;8012.399070069753;8257.87613185802;8714.651413943442;8752.956235218815;8325.209105135018;8587.270082043035;8596.269317108054;8604.956975215131;8613.870791938127;2381.452265978826;2403.9519209615796;2407.2296865003204;2432.978149177341;2437.9634305485433;2423.0792298926385;2453.950920981579;2456.299954512964;2458.754124587545;2471.3203868936007;2060.6702927531596;2248.3142641494337;2381.38084098134;2424.769646883552;2442.7068751749725;2154.202944910385;2226.899789509476;2333.3116730564525;2398;2414.4929564791414;2402.231285988484;2411.6985376827897;2433.4889673168655;2436.098643501898;2449;8517.446365986252;8612.267957223643;8620.224179823846;8622.827543449095;8629.05080441151;5216.374035115772;5388.326459192601;5388.811391601275;5391.406945236021;5396.595255355864;3540.291941611688;3568.678818906285;3580.4092324766384;3652.853885844563;3701.204241088179;1261.7287283234148;1267.6767424306743;1311.9278439685909;1326.9668258293523;1327.2965328375976;10962.725931851688;11035.668929932035;11036.448177591088;11040;11058.723531911686;4679.157751604744;4761.857144285677;4812.374391329105;4846.636502262344;4871.707697538159;9783.0706082922;9805.411675299505;9819.36174148678;9829.115379615869;9836.458994755358;5451.836444906659;5452.59105567084;5460.099083651153;5466.234727138204;5480.506754392044;4243.193793179286;4664.253719404879;4897.1674815281485;4922.9753851231235;4983.127952608279;3865.3622152344547;3987.8205480753436;4013.458183145238;4042.0905296308097;4094.2220978014084;1328.7674656934998;1335.4991878045732;1336.5722968649939;1347.47448495087;1348.4067010515341;924.9548010747812;926.1571969507715;939.3049143633662;949.4303417949175;985.4579981010415;5052.059214555719;5108.821191258288;5136.691798492027;5140.126178549655;5261.158214685632;873.668006157656;914.4970266353479;1027.0499787696351;1039.1738567838586;1040.5317607076695;3763.284975854579;3823;3826.789526576065;3935.2719746846924;3955.0705584187695;10011.099001089937;10272.691819245434;10280.280380373451;10293.485325733684;10305.072543471124;10181.796364072825;10192.949035254776;10257.076863082359;10258.846117308247;10276.588936442458;6445.258795238514;8034.638441270157;8036.517808931366;8072.233137851895;8192.221738934792;4819;5602.719864006865;5759;5787.045137552257;5863.120531920246;9151.679691211042;9219.400738952216;9304.116108969638;9327.253819694273;9374.95312523433;8467.53428561435;9508.286878484003;9585.952070239604;9609.327347085637;9715.902840971783;6790.898136528055;6970.128733908262;7201.279872012757;7203.531770434905;7371.336579707852;7986.640601172845;8192.713255036046;8196.180381961756;8196.631151598312;8198.795030124056;7172.13934327884;7269.927300726925;7275.708971641075;7382.224866388952;7418.221086785809;6118.938810611837;7100.396466300423;7148.320909513588;7167.534110282815;7306.561606303639;6420.871582568229;6454.386833250834;6502.642354670538;6512.381323774234;6518.185226846645;4993;5014.548690617862;5045.11710450677;5059.949400506095;5092.337996060489;5046.29351890743;5163.070647283525;5176.741162941913;5179.04187725272;5196.53231209193 08:46:57 INFO - PROCESS | 1639 | |4;prototype.html;326.96566860479646;367.7260440971477;377.7120020729313;385.84759020187045;386.8027306073902;5020.824271150511;5383.16560933056;5550.611457197998;5652.434756524345;5768.510051418889;5442.591805614585;5573.331200255965;5653.180288858113;5655;5655.773769049243;4367.672424568163;4383.9342409863875;4389.626881715058;4409.360642706805;4425.557444255564;4227.450431443908;4315.611594956454;4396.736195828261;4415.278041563793;4450.2434586120435;3851.9984803951033;4296.5273819879785;4360.847370342039;4366.733647242296;4375.9562404375865;3279.393312237232;3445.620981692012;3763.6800871926057;3785.981070094632;4210.136921930997;3172.825494597796;3391.6777906098882;3823.1971286029966;3857;3870.4000879863665;4377.956220437787;5351.331083614548;5368.852459016385;5462.3445186577865;5493.725313734298;5027.371578552681;5053;5076;5097.1844504879045;5128.179491281415;4711.081339138869;5566.276384070085;5671.943280567224;5715.714214289269;5944.989351810203;3950.545687245975;4273.658107351435;4292.527821939584;4293.29160688486;4295.720778149442;6213.347598502182;6238.251409830804;6268.805917928688;6292.276388215368;6303.779367722108;3761.9811900940867;4131.97934010334;4312.762798046075;4341.696081274344;4377.5116460403615;3500.387432199355;3523.4010218262956;3979.4428779970826;4089.7750623715983;4093.3655283431117;3986.3422535281934;4852.441969173519;4863.829765958175;4887.65786394956;4901.926471102936;5678.77284908599;5688.203651488795;5997.170862886861;6066.636001839904;6079.544034197364;1321.6365499487717;1346.4210389532595;1347.7035052288481;1349.0691422918155;1355.5391167003265;787.8896954426385;855.9186877246651;906.3293163059288;909.3452714045876;918.7917887977422;4882.340883980688;5224.686518808883;5254.579633629301;5270.92093618596;5294.896455247137;5846.707664616837;5869.706514674249;5874.619464425942;5899.734511946973;5922.940770592239;4857.465678775332;5141.922871156936;5162.767675454614;5270.578353731692;5302.496262855023;2571.7171111177754;2931.9120426387244;2971.5839782430476;3112.2686168750324;3120.3306231166216;5107.489251074937;5397.973010134924;5412.512873841373;6031.265456616623;6054.485368743618;5370.624056316098;5946.613470124515;6005.438585967592;6062.454379105901;6110.725017374229;5189.768399588138;5240.371155461369;6041.184440100706;6053.848653783691;6061.333253342129;4924.72913989734;5149.674273610185;5363.517283444508;5427.592930530305;5603.243562119224;4738.028704115717;5194.7922083116255;5639.266895303748;5758.740856661377;5808.7095645218415;5359.63329351013;5562.888742225052;5654.773809047757;6014.188084608521;6021.488173505126;3098.2409309719264;3274.770766046354;3656.2870240302886;4024.577419371041;4036.7779772112817;5814.30228372581;6062;6065.9081365352895;6073.969630151821;6119.418655227746;5522.392536821027;5604.06659702402;5861.501772349398;5865.706714664335;5933.169356290036;5091.6181286402925;5616.326040875121;5888.735006924613;5944.6433214005565;6005.609635373687;5742.181449336312;5765.56758243125;5782.190493331022;5803.47768700819;5867.711592478851;5462.890742185214;5832.737526811388;5852;5927.170196172626;5950.702464876826;5339.385970613429;5681.886362272814;5788.102844059177;5825.592208545361;5827.79602713903 08:46:57 INFO - PROCESS | 1639 | |5;yui.html;724.7898109548231;752.6989204318276;786.2098590916129;805.6092794994427;853.3599800149888;15122.773158402619;15787.131707756069;15905.363785448597;16114.435994740186;16120.435784747537;14673.899457540674;14828.332725027372;14838.480653177141;14848.628784280372;14994.175320357375;697.3967518096835;727.4834867244263;852.5225873510823;882.1531329923265;882.3779235638859;887.4320434921623;888.4136469929848;899.7885496908222;902.9322800789935;903.7876099116702;930.6044930904372;947.1901524196816;947.2516711797672;948.6632245552833;949.8100379924043;928.1692884868039;939.7979434421633;940.8118376324694;942.0344147249056;942.2273735536862;691.5954166812421;699.2657709405104;702.2345643248832;702.3117345001875;702.4240123099061;14967.625809354744;15459.690806183813;15469.5359139226;16233.594160145973;16405.261763220682;14718.632034199123;14860.62848428787;14882.841987911801;14897.329620167011;15123.39506419753;11719.94140029303;11741.09232899368;11943.761124777542;11950.223235489751;11986.88013119875;3753.8123093845197;3760.3983362662116;3840.1743625120735;3880.20455806559;4014.096828213658;110056;110117;111878.44060779725;113817.43091284491;115143;11245.71885702864;11263.549458021751;11484.827727584094;11944.283342999448;12149.453274602663;23717.932693028855;24287.907044183055;24452.633210501856;24496.775161241865;24557.017719291027;8090.4336696431865;8110.310623596943;8129.268365846984;8257.79355516117;8295.878382740764;4368.301071828492;4413.470383553929;4518.096380723869;4530.456345238526;4687.554682305175;3728.6084961079237;3754.0990162360836;3774.848671155324;3814.675752561008;3836.8465261389233;668.4652278177439;670.902719105727;671.5265737654942;679.1612358736955;686.4268335939546;668.5153263883743;680.2653134614604;704.1796307301936;716.4912911832553;746.8394295226552;10598.258121931545;10704.357738535713;10740.892591074145;11106.333619982826;11215.383153926612;672.865426914619;699.7410957945593;701.6211245927244;730.5580124024995;736.7347754808264;5177.896442071213;5219.399769026534;5257.342832145981;5265.6050796189675;5281.287026251407;108613;109657;111390;111790;112932.43533782443;76079.23920760832;79606;93824;93992;94143;76802.61598691971;77370;78776.60611696905;79110.60444697854;79864;11060.983971460795;11083.113350932068;11104;11287.492062857027;11473.36896470702;40856.774296771146;47373.52626473829;47725.76137119292;51934.70163245949;55865;55588.16617750818;65799.34200657932;66629;69381.3061869395;69500;14226.573202803933;14619.075529241063;14778.113313201242;14807.629809254855;15026;15300.617484562976;15876.444324448588;15978.60053498672;15985.120818355103;16323.59191020234;13942.372593233531;14111.647208819862;14262.786058209344;14285.42858285657;14479.85520144785;14237.572872813418;14700.264986750406;14848.554543363716;15025.774613381027;15244.3902243909;13583.864161358655;13956.860431395962;14075.577732668036;14084.718305633625;14100.576982690536;11054;11296.887031129912;11299.265547739371;11369.2609980351;11481.942590286995;9144.451332920045;11121.777564448828;11874.703132421759;12172.817407739069;12228.816567751313 08:46:57 INFO - PROCESS | 1639 | __end_tp_report 08:46:57 INFO - PROCESS | 1639 | __start_cc_report 08:46:57 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,381 08:46:57 INFO - PROCESS | 1639 | __end_cc_report 08:46:57 INFO - PROCESS | 1639 | __startTimestamp1488905217871__endTimestamp 08:46:57 INFO - PROCESS | 1639 | 08:46:57 INFO - PROCESS | 1639 | ------- Summary: start ------- 08:46:57 INFO - PROCESS | 1639 | Number of tests: 6 08:46:57 INFO - PROCESS | 1639 | 08:46:57 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5786.80 Median:6086.63 stddev:649.59 (10.7%) stddev-sans-first:624.35 08:46:57 INFO - PROCESS | 1639 | Values: 3414.1 3443.0 3455.8 3464.0 3481.0 5880.5 5900.1 5907.7 5941.7 5941.9 5895.2 5950.5 5968.5 5981.5 6012.2 5250.6 5311.4 5339.1 5375.4 5391.4 4709.5 5045.7 5311.6 5364.5 5375.0 4866.8 5232.1 5306.4 5314.0 5325.9 5184.5 5243.6 5265.0 5277.7 5282.7 5265.1 5265.8 5290.3 5291.1 5311.3 5694.8 5811.7 5840.8 5934.6 5941.6 6100.4 6144.3 6265.4 6271.7 6274.1 6198.8 6241.3 6256.5 6325.9 6342.1 6054.8 6069.0 6073.1 6090.4 6097.7 6245.8 6368.3 6485.6 6489.4 6505.5 6112.4 6117.7 6124.6 6131.8 6180.5 6316.7 6325.6 6335.4 6339.7 6351.2 6170.8 6223.6 6230.4 6234.4 6242.2 5209.9 6129.5 6135.2 6139.7 6179.5 5079.7 5102.2 5109.7 5111.1 5133.5 4121.5 4254.9 4548.7 4566.0 4590.8 4625.6 5802.4 5864.4 5892.6 5908.4 6338.8 6359.2 6359.8 6360.9 6373.6 5643.2 5948.4 5953.1 5960.4 5976.5 5355.9 5552.7 5555.3 5568.9 5637.7 5300.6 6347.9 6373.6 6389.0 6390.4 5193.0 5533.2 5659.1 6303.2 6316.6 6193.7 6232.8 6266.2 6284.7 6309.4 6187.3 6226.0 6237.8 6243.3 6252.4 4973.3 5251.3 6301.9 6304.5 6345.0 6114.5 6142.7 6144.8 6187.4 6210.4 6160.6 6192.5 6204.3 6210.4 6216.1 5816.6 5855.7 6139.3 6144.4 6170.6 6086.6 6154.6 6178.6 6215.6 6232.6 6104.2 6133.1 6172.2 6185.9 6186.8 08:46:57 INFO - PROCESS | 1639 | 08:46:57 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:60010.91 Median:36235.91 stddev:119610.99 (330.1%) stddev-sans-first:119862.96 08:46:57 INFO - PROCESS | 1639 | Values: 456.8 469.8 495.6 532.1 544.2 41909.2 44273.8 44283.8 44505.1 44513.6 45818.1 46212.3 46230.0 46260.5 46293.0 9546.5 9740.2 9751.0 9761.4 11649.8 11212.1 11463.9 11476.9 11481.6 11497.5 11503.3 11517.2 11546.0 11554.5 11571.7 11394.4 11411.0 11452.1 11477.9 11493.4 11822.0 11839.3 11847.8 11897.5 11915.5 38932.8 39190.4 39206.2 39234.4 39270.0 3718.5 4102.3 4194.9 4199.5 4228.7 28834.0 29110.6 29119.6 30705.8 30927.9 2685.9 3213.1 3304.2 3344.1 3387.2 431452.7 493446.1 497437.0 498341.0 502668.5 50974.0 59602.0 61298.4 61845.0 61855.1 62913.0 63717.4 63797.0 63814.4 63970.7 17125.4 17279.1 17291.0 17313.3 17323.7 12074.2 12306.8 13156.9 13902.2 14190.9 20308.0 22347.8 22518.9 22541.7 22575.5 9109.6 9388.8 9390.7 9397.5 9483.5 911.3 913.2 915.2 944.4 953.4 30275.5 30294.0 31241.7 31445.5 32193.0 1083.7 1103.0 1116.5 1151.3 1257.9 16257.0 16395.2 16523.0 16611.3 16701.0 252045.0 253040.0 254307.7 254821.0 255321.0 65231.0 65410.3 65526.7 65856.3 66042.0 1490.1 1491.9 1518.4 1530.0 1537.4 30270.2 31363.8 31416.8 31583.1 31928.0 53042.5 53213.2 53409.2 53469.2 53474.9 485149.0 564732.0 570452.0 570945.0 572316.0 34921.0 38786.2 41371.4 44580.0 45392.0 45344.5 47911.5 48394.8 48782.0 49607.5 37859.4 38011.2 39269.0 39529.0 41068.8 34959.7 35895.5 36252.8 36693.1 37056.8 36135.8 36219.0 36349.9 37444.6 38077.2 31287.7 33710.7 33930.5 33985.5 34142.3 24019.0 25748.5 25749.4 25770.5 25795.0 08:46:57 INFO - PROCESS | 1639 | 08:46:57 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:40589.43 Median:14978.67 stddev:121433.49 (810.7%) stddev-sans-first:121736.58 08:46:57 INFO - PROCESS | 1639 | Values: 365.2 396.7 402.2 408.8 433.8 18436.4 20436.0 20786.2 21739.7 21845.8 18072.8 21991.6 22157.0 22183.4 22298.7 5820.3 6042.0 6115.3 6132.1 6136.1 5169.7 6008.2 6042.9 6045.5 6125.0 6197.0 6245.2 6258.8 6259.1 6263.8 5871.3 6246.7 6254.2 6889.7 6898.2 5933.4 6157.8 6171.4 6181.6 6205.8 22921.4 26462.6 26751.5 27011.9 27179.0 2645.8 2778.3 2944.5 3061.4 3073.5 9933.2 10386.9 10509.8 10810.9 10836.7 1547.6 1878.4 1888.6 1890.1 1900.1 113117.0 139013.0 139564.0 143851.3 144575.0 11874.2 12184.7 12231.3 12294.9 12328.4 15945.6 16234.8 16275.7 16297.0 16342.8 9024.5 9192.5 9259.8 9275.4 9416.4 7705.0 9094.9 9108.1 9248.9 9250.4 4776.4 4826.5 4844.1 4909.9 4940.1 2045.4 2090.2 2093.8 2095.2 2103.5 895.8 901.7 917.3 932.7 965.2 20953.3 21135.9 21878.8 21984.2 22187.0 933.9 937.6 958.1 959.6 964.6 10869.7 11320.4 11520.9 11525.4 11567.0 717622.0 723580.0 724302.0 737492.0 737498.0 117154.4 154065.0 156711.0 158495.0 159644.4 1022.5 1026.2 1059.6 1062.4 1063.3 20195.8 20638.9 21048.7 21064.2 21076.7 62054.0 62107.7 62209.0 64068.7 64290.0 81980.2 83475.0 86609.0 91201.0 91997.0 8535.4 8550.3 8581.5 8868.3 8955.3 9420.9 11280.8 12733.6 13391.3 13686.0 9501.3 11435.9 11483.0 11893.2 11947.1 10987.3 11371.8 11486.1 11698.8 11887.5 9241.4 10118.7 11378.4 11665.0 11887.1 6689.6 7319.5 7391.7 7830.4 8431.8 9562.8 11616.2 11751.6 11919.6 12007.6 08:46:57 INFO - PROCESS | 1639 | 08:46:57 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5551.07 Median:5100.58 stddev:3006.16 (58.9%) stddev-sans-first:2997.56 08:46:57 INFO - PROCESS | 1639 | Values: 1294.3 1468.1 1505.8 1519.6 1521.5 7778.3 8012.4 8257.9 8714.7 8753.0 8325.2 8587.3 8596.3 8605.0 8613.9 2381.5 2404.0 2407.2 2433.0 2438.0 2423.1 2454.0 2456.3 2458.8 2471.3 2060.7 2248.3 2381.4 2424.8 2442.7 2154.2 2226.9 2333.3 2398.0 2414.5 2402.2 2411.7 2433.5 2436.1 2449.0 8517.4 8612.3 8620.2 8622.8 8629.1 5216.4 5388.3 5388.8 5391.4 5396.6 3540.3 3568.7 3580.4 3652.9 3701.2 1261.7 1267.7 1311.9 1327.0 1327.3 10962.7 11035.7 11036.4 11040.0 11058.7 4679.2 4761.9 4812.4 4846.6 4871.7 9783.1 9805.4 9819.4 9829.1 9836.5 5451.8 5452.6 5460.1 5466.2 5480.5 4243.2 4664.3 4897.2 4923.0 4983.1 3865.4 3987.8 4013.5 4042.1 4094.2 1328.8 1335.5 1336.6 1347.5 1348.4 925.0 926.2 939.3 949.4 985.5 5052.1 5108.8 5136.7 5140.1 5261.2 873.7 914.5 1027.0 1039.2 1040.5 3763.3 3823.0 3826.8 3935.3 3955.1 10011.1 10272.7 10280.3 10293.5 10305.1 10181.8 10192.9 10257.1 10258.8 10276.6 6445.3 8034.6 8036.5 8072.2 8192.2 4819.0 5602.7 5759.0 5787.0 5863.1 9151.7 9219.4 9304.1 9327.3 9375.0 8467.5 9508.3 9586.0 9609.3 9715.9 6790.9 6970.1 7201.3 7203.5 7371.3 7986.6 8192.7 8196.2 8196.6 8198.8 7172.1 7269.9 7275.7 7382.2 7418.2 6118.9 7100.4 7148.3 7167.5 7306.6 6420.9 6454.4 6502.6 6512.4 6518.2 4993.0 5014.5 5045.1 5059.9 5092.3 5046.3 5163.1 5176.7 5179.0 5196.5 08:46:57 INFO - PROCESS | 1639 | 08:46:57 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4725.65 Median:5320.94 stddev:1452.31 (27.3%) stddev-sans-first:1418.36 08:46:57 INFO - PROCESS | 1639 | Values: 327.0 367.7 377.7 385.8 386.8 5020.8 5383.2 5550.6 5652.4 5768.5 5442.6 5573.3 5653.2 5655.0 5655.8 4367.7 4383.9 4389.6 4409.4 4425.6 4227.5 4315.6 4396.7 4415.3 4450.2 3852.0 4296.5 4360.8 4366.7 4376.0 3279.4 3445.6 3763.7 3786.0 4210.1 3172.8 3391.7 3823.2 3857.0 3870.4 4378.0 5351.3 5368.9 5462.3 5493.7 5027.4 5053.0 5076.0 5097.2 5128.2 4711.1 5566.3 5671.9 5715.7 5945.0 3950.5 4273.7 4292.5 4293.3 4295.7 6213.3 6238.3 6268.8 6292.3 6303.8 3762.0 4132.0 4312.8 4341.7 4377.5 3500.4 3523.4 3979.4 4089.8 4093.4 3986.3 4852.4 4863.8 4887.7 4901.9 5678.8 5688.2 5997.2 6066.6 6079.5 1321.6 1346.4 1347.7 1349.1 1355.5 787.9 855.9 906.3 909.3 918.8 4882.3 5224.7 5254.6 5270.9 5294.9 5846.7 5869.7 5874.6 5899.7 5922.9 4857.5 5141.9 5162.8 5270.6 5302.5 2571.7 2931.9 2971.6 3112.3 3120.3 5107.5 5398.0 5412.5 6031.3 6054.5 5370.6 5946.6 6005.4 6062.5 6110.7 5189.8 5240.4 6041.2 6053.8 6061.3 4924.7 5149.7 5363.5 5427.6 5603.2 4738.0 5194.8 5639.3 5758.7 5808.7 5359.6 5562.9 5654.8 6014.2 6021.5 3098.2 3274.8 3656.3 4024.6 4036.8 5814.3 6062.0 6065.9 6074.0 6119.4 5522.4 5604.1 5861.5 5865.7 5933.2 5091.6 5616.3 5888.7 5944.6 6005.6 5742.2 5765.6 5782.2 5803.5 5867.7 5462.9 5832.7 5852.0 5927.2 5950.7 5339.4 5681.9 5788.1 5825.6 5827.8 08:46:57 INFO - PROCESS | 1639 | 08:46:57 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:21198.04 Median:3753.96 stddev:30281.21 (806.6%) stddev-sans-first:30327.14 08:46:57 INFO - PROCESS | 1639 | Values: 724.8 752.7 786.2 805.6 853.4 15122.8 15787.1 15905.4 16114.4 16120.4 14673.9 14828.3 14838.5 14848.6 14994.2 697.4 727.5 852.5 882.2 882.4 887.4 888.4 899.8 902.9 903.8 930.6 947.2 947.3 948.7 949.8 928.2 939.8 940.8 942.0 942.2 691.6 699.3 702.2 702.3 702.4 14967.6 15459.7 15469.5 16233.6 16405.3 14718.6 14860.6 14882.8 14897.3 15123.4 11719.9 11741.1 11943.8 11950.2 11986.9 3753.8 3760.4 3840.2 3880.2 4014.1 110056.0 110117.0 111878.4 113817.4 115143.0 11245.7 11263.5 11484.8 11944.3 12149.5 23717.9 24287.9 24452.6 24496.8 24557.0 8090.4 8110.3 8129.3 8257.8 8295.9 4368.3 4413.5 4518.1 4530.5 4687.6 3728.6 3754.1 3774.8 3814.7 3836.8 668.5 670.9 671.5 679.2 686.4 668.5 680.3 704.2 716.5 746.8 10598.3 10704.4 10740.9 11106.3 11215.4 672.9 699.7 701.6 730.6 736.7 5177.9 5219.4 5257.3 5265.6 5281.3 108613.0 109657.0 111390.0 111790.0 112932.4 76079.2 79606.0 93824.0 93992.0 94143.0 76802.6 77370.0 78776.6 79110.6 79864.0 11061.0 11083.1 11104.0 11287.5 11473.4 40856.8 47373.5 47725.8 51934.7 55865.0 55588.2 65799.3 66629.0 69381.3 69500.0 14226.6 14619.1 14778.1 14807.6 15026.0 15300.6 15876.4 15978.6 15985.1 16323.6 13942.4 14111.6 14262.8 14285.4 14479.9 14237.6 14700.3 14848.6 15025.8 15244.4 13583.9 13956.9 14075.6 14084.7 14100.6 11054.0 11296.9 11299.3 11369.3 11481.9 9144.5 11121.8 11874.7 12172.8 12228.8 08:46:57 INFO - PROCESS | 1639 | -------- Summary: end -------- 08:46:57 INFO - PROCESS | 1639 | 08:46:58 INFO - TEST-INFO | 1639: exit 0 08:46:58 INFO - TEST-OK | dromaeo_css | took 1114487ms 08:46:58 INFO - TEST-START | kraken 08:46:58 INFO - operating with platform_type : mac_ 08:46:58 INFO - Initialising browser for kraken test... 08:46:58 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpSIUHtQ/profile http://localhost:49250/getInfo.html) 08:47:01 INFO - PROCESS | 1652 | 2017-03-07 08:47:01.197 plugin-container[1654:9787] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 08:47:01 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:01 INFO - PROCESS | 1652 | 2017-03-07 08:47:01.197 plugin-container[1654:9787] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 08:47:01 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:01 INFO - PROCESS | 1652 | 2017-03-07 08:47:01.197 plugin-container[1654:9787] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:47:01 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 08:47:01 INFO - PROCESS | 1652 | colorDepth:24 08:47:01 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 08:47:01 INFO - PROCESS | 1652 | __metrics 08:47:01 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 08:47:02 INFO - TEST-INFO | 1652: exit 0 08:47:02 INFO - Browser initialized. 08:47:02 INFO - Running cycle 1/1 for kraken test... 08:47:02 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpSIUHtQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:47:04 INFO - PROCESS | 1655 | 2017-03-07 08:47:04.410 plugin-container[1656:9903] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 08:47:04 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:04 INFO - PROCESS | 1655 | 2017-03-07 08:47:04.410 plugin-container[1656:9903] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 08:47:04 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:04 INFO - PROCESS | 1655 | 2017-03-07 08:47:04.410 plugin-container[1656:9903] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:47:14 INFO - PROCESS | 1655 | RSS: Main: 172810240 08:47:14 INFO - PROCESS | 1655 | 08:47:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection) 08:47:17 INFO - PROCESS | 1655 | RSS: Main: 186155008 08:47:17 INFO - PROCESS | 1655 | 08:47:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-dft) 08:47:21 INFO - PROCESS | 1655 | RSS: Main: 193638400 08:47:21 INFO - PROCESS | 1655 | 08:47:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-fft) 08:47:25 INFO - PROCESS | 1655 | RSS: Main: 197554176 08:47:25 INFO - PROCESS | 1655 | 08:47:27 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator) 08:47:27 INFO - PROCESS | 1655 | RSS: Main: 198815744 08:47:27 INFO - PROCESS | 1655 | 08:47:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:47:30 INFO - PROCESS | 1655 | RSS: Main: 198914048 08:47:30 INFO - PROCESS | 1655 | 08:47:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom) 08:47:33 INFO - PROCESS | 1655 | RSS: Main: 203235328 08:47:33 INFO - PROCESS | 1655 | 08:47:37 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate) 08:47:37 INFO - PROCESS | 1655 | RSS: Main: 202481664 08:47:37 INFO - PROCESS | 1655 | 08:47:40 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial) 08:47:41 INFO - PROCESS | 1655 | RSS: Main: 202792960 08:47:41 INFO - PROCESS | 1655 | 08:47:43 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:47:43 INFO - PROCESS | 1655 | RSS: Main: 201760768 08:47:43 INFO - PROCESS | 1655 | 08:47:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:47:46 INFO - PROCESS | 1655 | RSS: Main: 201895936 08:47:46 INFO - PROCESS | 1655 | 08:47:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:47:49 INFO - PROCESS | 1655 | RSS: Main: 201764864 08:47:49 INFO - PROCESS | 1655 | 08:47:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:47:52 INFO - PROCESS | 1655 | RSS: Main: 202223616 08:47:52 INFO - PROCESS | 1655 | 08:47:55 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:47:56 INFO - PROCESS | 1655 | RSS: Main: 202170368 08:47:56 INFO - PROCESS | 1655 | 08:47:57 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49250/tests/kraken/driver.html?testName=ai-astar) 08:47:58 INFO - PROCESS | 1655 | RSS: Main: 204271616 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | __start_tp_report 08:47:58 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 08:47:58 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 08:47:58 INFO - PROCESS | 1655 | |i|pagename|runs| 08:47:58 INFO - PROCESS | 1655 | |0;ai-astar;104;118;114;117;122;116;118;118;117;117 08:47:58 INFO - PROCESS | 1655 | |1;audio-beat-detection;143;109;110;140;136;108;144;140;162;115 08:47:58 INFO - PROCESS | 1655 | |2;audio-dft;197;196;192;193;194;194;199;195;201;199 08:47:58 INFO - PROCESS | 1655 | |3;audio-fft;81;60;58;99;82;82;79;59;72;61 08:47:58 INFO - PROCESS | 1655 | |4;audio-oscillator;86;73;105;101;99;87;81;70;71;82 08:47:58 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;92;88;96;128;133;89;99;89;133;118 08:47:58 INFO - PROCESS | 1655 | |6;imaging-darkroom;137;96;96;96;96;109;126;96;128;160 08:47:58 INFO - PROCESS | 1655 | |7;imaging-desaturate;91;98;142;142;87;149;146;109;91;90 08:47:58 INFO - PROCESS | 1655 | |8;json-parse-financial;57;57;57;66;55;61;61;61;61;62 08:47:58 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;66;75;69;77;73;70;71;73;70;71 08:47:58 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;82;86;84;80;88;102;79;86;86;84 08:47:58 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;159;146;128;145;151;130;138;153;210;181 08:47:58 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;164;169;164;167;170;173;166;167;165;166 08:47:58 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;59;68;64;65;63;67;66;64;60;76 08:47:58 INFO - PROCESS | 1655 | __end_tp_report 08:47:58 INFO - PROCESS | 1655 | __start_cc_report 08:47:58 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,939 08:47:58 INFO - PROCESS | 1655 | __end_cc_report 08:47:58 INFO - PROCESS | 1655 | __startTimestamp1488905278111__endTimestamp 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | ------- Summary: start ------- 08:47:58 INFO - PROCESS | 1655 | Number of tests: 14 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:116.10 Median:117.50 stddev:4.70 (4.0%) stddev-sans-first:2.13 08:47:58 INFO - PROCESS | 1655 | Values: 104.0 118.0 114.0 117.0 122.0 116.0 118.0 118.0 117.0 117.0 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:140.00 stddev:18.77 (13.4%) stddev-sans-first:19.37 08:47:58 INFO - PROCESS | 1655 | Values: 143.0 109.0 110.0 140.0 136.0 108.0 144.0 140.0 162.0 115.0 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:196.00 Median:196.50 stddev:2.94 (1.5%) stddev-sans-first:3.10 08:47:58 INFO - PROCESS | 1655 | Values: 197.0 196.0 192.0 193.0 194.0 194.0 199.0 195.0 201.0 199.0 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:73.30 Median:80.00 stddev:13.63 (17.0%) stddev-sans-first:14.17 08:47:58 INFO - PROCESS | 1655 | Values: 81.0 60.0 58.0 99.0 82.0 82.0 79.0 59.0 72.0 61.0 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:85.50 Median:81.50 stddev:12.67 (15.5%) stddev-sans-first:13.44 08:47:58 INFO - PROCESS | 1655 | Values: 86.0 73.0 105.0 101.0 99.0 87.0 81.0 70.0 71.0 82.0 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:106.50 Median:89.00 stddev:19.24 (21.6%) stddev-sans-first:19.68 08:47:58 INFO - PROCESS | 1655 | Values: 92.0 88.0 96.0 128.0 133.0 89.0 99.0 89.0 133.0 118.0 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:114.00 Median:96.00 stddev:22.68 (23.6%) stddev-sans-first:22.48 08:47:58 INFO - PROCESS | 1655 | Values: 137.0 96.0 96.0 96.0 96.0 109.0 126.0 96.0 128.0 160.0 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:114.50 Median:88.50 stddev:26.79 (30.3%) stddev-sans-first:27.03 08:47:58 INFO - PROCESS | 1655 | Values: 91.0 98.0 142.0 142.0 87.0 149.0 146.0 109.0 91.0 90.0 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:61.00 stddev:3.26 (5.3%) stddev-sans-first:3.30 08:47:58 INFO - PROCESS | 1655 | Values: 57.0 57.0 57.0 66.0 55.0 61.0 61.0 61.0 61.0 62.0 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.00 stddev:3.14 (4.4%) stddev-sans-first:2.62 08:47:58 INFO - PROCESS | 1655 | Values: 66.0 75.0 69.0 77.0 73.0 70.0 71.0 73.0 70.0 71.0 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:85.00 stddev:6.40 (7.5%) stddev-sans-first:6.64 08:47:58 INFO - PROCESS | 1655 | Values: 82.0 86.0 84.0 80.0 88.0 102.0 79.0 86.0 86.0 84.0 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:154.10 Median:152.00 stddev:24.84 (16.3%) stddev-sans-first:26.28 08:47:58 INFO - PROCESS | 1655 | Values: 159.0 146.0 128.0 145.0 151.0 130.0 138.0 153.0 210.0 181.0 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.10 Median:167.00 stddev:2.85 (1.7%) stddev-sans-first:2.79 08:47:58 INFO - PROCESS | 1655 | Values: 164.0 169.0 164.0 167.0 170.0 173.0 166.0 167.0 165.0 166.0 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.50 stddev:4.73 (7.2%) stddev-sans-first:4.46 08:47:58 INFO - PROCESS | 1655 | Values: 59.0 68.0 64.0 65.0 63.0 67.0 66.0 64.0 60.0 76.0 08:47:58 INFO - PROCESS | 1655 | -------- Summary: end -------- 08:47:58 INFO - PROCESS | 1655 | 08:47:58 INFO - TEST-INFO | 1655: exit 0 08:47:58 INFO - TEST-OK | kraken | took 60437ms 08:47:58 INFO - SUITE-END | took 1174s 08:47:59 INFO - Completed test suite (00:19:35) 08:47:59 INFO - javascript benchmark 08:47:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7547.629588218265, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3414.095264754842, 3442.9999999999995, 3455.7926524408535, 3463.982680086599, 3481.0, 5880.500157486614, 5900.144479050535, 5907.6750778707155, 5941.7029148542615, 5941.88116237675, 5895.174675545427, 5950.494207992326, 5968.462838344548, 5981.491573216282, 6012.188354572131, 5250.579953603703, 5311.389190243113, 5339.145736682131, 5375.408705042442, 5391.379991301012, 4709.481956984729, 5045.738565358661, 5311.62818602698, 5364.517193452589, 5375.0, 4866.78099485524, 5232.058229518685, 5306.442823503515, 5314.0, 5325.946740532584, 5184.533391994739, 5243.632945693803, 5265.025970195487, 5277.70972596507, 5282.709450980195, 5265.078611243017, 5265.842024739264, 5290.338707661543, 5291.100512912814, 5311.256424100629, 5694.829155125353, 5811.738471768781, 5840.795572154997, 5934.6142500737315, 5941.584089113764, 6100.420460056288, 6144.262688477412, 6265.436110750009, 6271.717772700239, 6274.058891166362, 6198.783042593533, 6241.251049873998, 6256.468200202989, 6325.87348253037, 6342.143810585558, 6054.8183554493435, 6068.9696551517845, 6073.119397687355, 6090.421409966046, 6097.664628445434, 6245.75016999324, 6368.344230500011, 6485.578437401648, 6489.383508566679, 6505.544611877123, 6112.442689633878, 6117.705611546597, 6124.6325220486915, 6131.754729810847, 6180.536459765535, 6316.684165791692, 6325.557210995186, 6335.429811316912, 6339.746410143543, 6351.237851457762, 6170.784022559189, 6223.626582405071, 6230.408111229426, 6234.37656234382, 6242.15730876335, 5209.869753256199, 6129.511613759063, 6135.171751813448, 6139.676133582418, 6179.5056395488255, 5079.6952182869145, 5102.2346648003095, 5109.718965456861, 5111.131107711698, 5133.486651334837, 4121.464209652726, 4254.851190178696, 4548.681592288508, 4565.995480994243, 4590.839320623829, 4625.583697467244, 5802.4197580241635, 5864.38423965486, 5892.646441213541, 5908.350081491032, 6338.778142764981, 6359.20509936258, 6359.841003974938, 6360.872782544323, 6373.553851230462, 5643.153526970905, 5948.375420580864, 5953.107033944856, 5960.433758792908, 5976.5218782497295, 5355.892882142335, 5552.69460179694, 5555.305586801569, 5568.9443105569235, 5637.7181140942785, 5300.575953923754, 6347.936520634919, 6373.553851230462, 6389.041643753567, 6390.360963903572, 5192.9740351299515, 5533.170024496358, 5659.094544872801, 6303.180586523906, 6316.62100273985, 6193.721282542387, 6232.844178895564, 6266.216722909637, 6284.654344011124, 6309.3690630936535, 6187.257529096537, 6225.968870155621, 6237.844053898689, 6243.250809902659, 6252.437280644764, 4973.328600638867, 5251.264822924717, 6301.873962520633, 6304.5271604628915, 6344.968275158594, 6114.479856340571, 6142.713648198372, 6144.832481828438, 6187.350328215653, 6210.4100110489435, 6160.568760186744, 6192.504599632109, 6204.348543402878, 6210.378962103753, 6216.129741836056, 5816.592838501264, 5855.711743416442, 6139.324674285915, 6144.41628045335, 6170.568060235741, 6086.6348019119, 6154.630722156685, 6178.598391104564, 6215.595986260879, 6232.626042437287, 6104.175936248727, 6133.110698948538, 6172.166757487682, 6185.938140618537, 6186.783462578788], "value": 5747.74604756307, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.84190576887573, 469.8472996276211, 495.58866141102885, 532.1326238231682, 544.1565573361289, 41909.161816763684, 44273.77863110684, 44283.778581107086, 44505.10989780202, 44513.55486445135, 45818.0836383273, 46212.306815397795, 46230.0, 46260.537394626044, 46293.0, 9546.474943878067, 9740.172085372751, 9750.9512452438, 9761.414315141079, 11649.825252621218, 11212.10303175744, 11463.885361146407, 11476.885231147708, 11481.59814406496, 11497.540098396095, 11503.252288601214, 11517.193796434252, 11545.960863522281, 11554.480048397842, 11571.652850414459, 11394.373309467976, 11411.0, 11452.141089418245, 11477.885221147764, 11493.367864767435, 11822.0, 11839.348835814028, 11847.763044739144, 11897.524099036029, 11915.463804128749, 38932.80533597342, 39190.41214381786, 39206.21587568261, 39234.41148382777, 39270.0, 3718.4980027696183, 4102.2821006323775, 4194.9370759438325, 4199.538050814408, 4228.746275223496, 28834.0, 29110.563341549892, 29119.56320655192, 30705.77176912921, 30927.91752288659, 2685.925629748097, 3213.1163929919226, 3304.2347604702904, 3344.0803778960735, 3387.220939183977, 431452.68547314755, 493446.0655393472, 497437.0, 498341.0, 502668.486657568, 50973.980520389385, 59602.0, 61298.38701613016, 61845.0, 61855.072173917426, 62913.0, 63717.36282637207, 63797.0, 63814.40463988437, 63970.72058558803, 17125.400610978555, 17279.136043198043, 17290.962542247504, 17313.3074677014, 17323.740143897852, 12074.154809163274, 12306.75386492283, 13156.868431315565, 13902.23537705436, 14190.85809141916, 20308.0, 22347.770872602163, 22518.874056297118, 22541.661875071888, 22575.548489030127, 9109.635614575343, 9388.765280867896, 9390.671326503676, 9397.530123493798, 9483.525823708787, 911.3301723233418, 913.2374467319862, 915.2129168914728, 944.390867890207, 953.3993584042009, 30275.545866812015, 30294.0, 31241.687583124334, 31445.528317075263, 32193.034208973768, 1083.6965649618119, 1102.9669109926715, 1116.5310569560804, 1151.3034614058374, 1257.9244745742442, 16257.0, 16395.180240988142, 16523.00861948287, 16611.262465945307, 16701.0, 252045.0, 253040.0, 254307.7284613602, 254821.0, 255321.0, 65231.0, 65410.345896541374, 65526.67236663881, 65856.34143658598, 66042.00936985949, 1490.128274959161, 1491.9179445130405, 1518.3698765012616, 1529.9847001530065, 1537.3927298716944, 30270.243243919078, 31363.84318078486, 31416.842915785273, 31583.052508424786, 31928.0, 53042.46957530375, 53213.20180197222, 53409.198862017874, 53469.19796202982, 53474.93050139053, 485149.0, 564732.0, 570452.0, 570945.0, 572316.0, 34921.0, 38786.2242755149, 41371.379429309185, 44580.0, 45392.0, 45344.546554534034, 47911.5208847921, 48394.758026209645, 48782.0, 49607.503924960285, 37859.432108518944, 38011.23977520489, 39269.0, 39529.01177470586, 41068.794656026526, 34959.65040349564, 35895.461568075974, 36252.81873590615, 36693.08267293339, 37056.814715926244, 36135.819320903225, 36219.0, 36349.90950271496, 37444.62555374411, 38077.23845523019, 31287.68712312848, 33710.66289337174, 33930.49104263388, 33985.490217646264, 34142.317153657285, 24019.03923843027, 25748.485030299664, 25749.3562660935, 25770.484590307715, 25795.0], "value": 20758.422371013723, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.1728834190558, 396.71634781131485, 402.1695199413213, 408.78538767147234, 433.80478784546943, 18436.354727584505, 20435.978201089943, 20786.16855325789, 21739.6739048914, 21845.781542184573, 18072.81927180728, 21991.560168796655, 22157.0, 22183.44541386466, 22298.665520017214, 5820.330661973885, 6041.972864613004, 6115.29674087479, 6132.141500189977, 6136.140940268366, 5169.715665638389, 6008.158857759917, 6042.879142417149, 6045.546584006217, 6124.958757011296, 6196.9690151549175, 6245.219347581552, 6258.843528911791, 6259.092431597438, 6263.780767673133, 5871.295444546639, 6246.718897649616, 6254.21822272216, 6889.724411023553, 6898.206706228772, 5933.376995415506, 6157.846053848645, 6171.413715697002, 6181.629102253879, 6205.782797602107, 22921.42696432586, 26462.6030609541, 26751.464970700476, 27011.86494067537, 27179.0, 2645.8280211786173, 2778.333200031978, 2944.544576441642, 3061.4336347775734, 3073.4928736758407, 9933.205343572497, 10386.948065259625, 10509.789804203949, 10810.945945270301, 10836.729081772939, 1547.5512101490667, 1878.4176905159309, 1888.5628156754713, 1890.1210936914263, 1900.144934779341, 113117.0, 139013.0, 139564.0, 143851.28074359667, 144575.0, 11874.228175168586, 12184.695382615506, 12231.266124032587, 12294.877051229552, 12328.383580820922, 15945.601359966093, 16234.837651623333, 16275.674486510203, 16297.0, 16342.75485867713, 9024.503652299187, 9192.540372981324, 9259.814803703888, 9275.443473391619, 9416.435013899189, 7704.998350214549, 9094.86357704635, 9108.089191080971, 9248.907510924804, 9250.352475326663, 4776.402949631296, 4826.517348265145, 4844.055409195244, 4909.919817640113, 4940.061388336205, 2045.4375046862233, 2090.174381119449, 2093.7592176899852, 2095.21429463951, 2103.453102193441, 895.820835832823, 901.6844104563351, 917.3257655623112, 932.7015355086443, 965.1988849255101, 20953.26663566768, 21135.8943205283, 21878.796666183513, 21984.230551930606, 22187.0, 933.8739270198572, 937.5593471068587, 958.1424624960621, 959.596969272907, 964.6045121500291, 10869.728256793485, 11320.433978301216, 11520.884791152148, 11525.366104864146, 11567.0, 717622.0, 723580.0, 724302.0, 737492.0, 737498.0, 117154.41422793001, 154065.0, 156711.0, 158495.0, 159644.40355596528, 1022.4632068164274, 1026.235454586338, 1059.613241166984, 1062.436908438529, 1063.3194755356578, 20195.798042019393, 20638.86486243271, 21048.68426973566, 21064.15743370248, 21076.68384974196, 62054.0, 62107.6894615524, 62209.0, 64068.679656601416, 64290.0, 81980.18019819725, 83475.0, 86609.0, 91201.0, 91997.0, 8535.3598480113, 8550.273226775545, 8581.528015959182, 8868.290536756915, 8955.283577313669, 9420.905790942277, 11280.830787538358, 12733.55432559856, 13391.263480508665, 13686.04197706151, 9501.334906556474, 11435.942820285845, 11483.0, 11893.167478276437, 11947.10396720232, 10987.285826421577, 11371.772564548828, 11486.081113511067, 11698.824517632072, 11887.524499019943, 9241.445513269226, 10118.696439106838, 11378.43107844621, 11665.0, 11887.108466865193, 6689.5986240825705, 7319.45104117204, 7391.667374968032, 7830.3735701144915, 8431.831363372576, 9562.808743824946, 11616.186866919235, 11751.588694395656, 11919.582814601446, 12007.579734709243], "value": 10161.109827822942, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1294.2752058847043, 1468.0604413175577, 1505.7953637090325, 1519.642883922278, 1521.5435369389181, 7778.338841198491, 8012.399070069753, 8257.87613185802, 8714.651413943442, 8752.956235218815, 8325.209105135018, 8587.270082043035, 8596.269317108054, 8604.956975215131, 8613.870791938127, 2381.452265978826, 2403.9519209615796, 2407.2296865003204, 2432.978149177341, 2437.9634305485433, 2423.0792298926385, 2453.950920981579, 2456.299954512964, 2458.754124587545, 2471.3203868936007, 2060.6702927531596, 2248.3142641494337, 2381.38084098134, 2424.769646883552, 2442.7068751749725, 2154.202944910385, 2226.899789509476, 2333.3116730564525, 2398.0, 2414.4929564791414, 2402.231285988484, 2411.6985376827897, 2433.4889673168655, 2436.098643501898, 2449.0, 8517.446365986252, 8612.267957223643, 8620.224179823846, 8622.827543449095, 8629.05080441151, 5216.374035115772, 5388.326459192601, 5388.811391601275, 5391.406945236021, 5396.595255355864, 3540.291941611688, 3568.678818906285, 3580.4092324766384, 3652.853885844563, 3701.204241088179, 1261.7287283234148, 1267.6767424306743, 1311.9278439685909, 1326.9668258293523, 1327.2965328375976, 10962.725931851688, 11035.668929932035, 11036.448177591088, 11040.0, 11058.723531911686, 4679.157751604744, 4761.857144285677, 4812.374391329105, 4846.636502262344, 4871.707697538159, 9783.0706082922, 9805.411675299505, 9819.36174148678, 9829.115379615869, 9836.458994755358, 5451.836444906659, 5452.59105567084, 5460.099083651153, 5466.234727138204, 5480.506754392044, 4243.193793179286, 4664.253719404879, 4897.1674815281485, 4922.9753851231235, 4983.127952608279, 3865.3622152344547, 3987.8205480753436, 4013.458183145238, 4042.0905296308097, 4094.2220978014084, 1328.7674656934998, 1335.4991878045732, 1336.5722968649939, 1347.47448495087, 1348.4067010515341, 924.9548010747812, 926.1571969507715, 939.3049143633662, 949.4303417949175, 985.4579981010415, 5052.059214555719, 5108.821191258288, 5136.691798492027, 5140.126178549655, 5261.158214685632, 873.668006157656, 914.4970266353479, 1027.0499787696351, 1039.1738567838586, 1040.5317607076695, 3763.284975854579, 3823.0, 3826.789526576065, 3935.2719746846924, 3955.0705584187695, 10011.099001089937, 10272.691819245434, 10280.280380373451, 10293.485325733684, 10305.072543471124, 10181.796364072825, 10192.949035254776, 10257.076863082359, 10258.846117308247, 10276.588936442458, 6445.258795238514, 8034.638441270157, 8036.517808931366, 8072.233137851895, 8192.221738934792, 4819.0, 5602.719864006865, 5759.0, 5787.045137552257, 5863.120531920246, 9151.679691211042, 9219.400738952216, 9304.116108969638, 9327.253819694273, 9374.95312523433, 8467.53428561435, 9508.286878484003, 9585.952070239604, 9609.327347085637, 9715.902840971783, 6790.898136528055, 6970.128733908262, 7201.279872012757, 7203.531770434905, 7371.336579707852, 7986.640601172845, 8192.713255036046, 8196.180381961756, 8196.631151598312, 8198.795030124056, 7172.13934327884, 7269.927300726925, 7275.708971641075, 7382.224866388952, 7418.221086785809, 6118.938810611837, 7100.396466300423, 7148.320909513588, 7167.534110282815, 7306.561606303639, 6420.871582568229, 6454.386833250834, 6502.642354670538, 6512.381323774234, 6518.185226846645, 4993.0, 5014.548690617862, 5045.11710450677, 5059.949400506095, 5092.337996060489, 5046.29351890743, 5163.070647283525, 5176.741162941913, 5179.04187725272, 5196.53231209193], "value": 4537.97218626043, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.96566860479646, 367.7260440971477, 377.7120020729313, 385.84759020187045, 386.8027306073902, 5020.824271150511, 5383.16560933056, 5550.611457197998, 5652.434756524345, 5768.510051418889, 5442.591805614585, 5573.331200255965, 5653.180288858113, 5655.0, 5655.773769049243, 4367.672424568163, 4383.9342409863875, 4389.626881715058, 4409.360642706805, 4425.557444255564, 4227.450431443908, 4315.611594956454, 4396.736195828261, 4415.278041563793, 4450.2434586120435, 3851.9984803951033, 4296.5273819879785, 4360.847370342039, 4366.733647242296, 4375.9562404375865, 3279.393312237232, 3445.620981692012, 3763.6800871926057, 3785.981070094632, 4210.136921930997, 3172.825494597796, 3391.6777906098882, 3823.1971286029966, 3857.0, 3870.4000879863665, 4377.956220437787, 5351.331083614548, 5368.852459016385, 5462.3445186577865, 5493.725313734298, 5027.371578552681, 5053.0, 5076.0, 5097.1844504879045, 5128.179491281415, 4711.081339138869, 5566.276384070085, 5671.943280567224, 5715.714214289269, 5944.989351810203, 3950.545687245975, 4273.658107351435, 4292.527821939584, 4293.29160688486, 4295.720778149442, 6213.347598502182, 6238.251409830804, 6268.805917928688, 6292.276388215368, 6303.779367722108, 3761.9811900940867, 4131.97934010334, 4312.762798046075, 4341.696081274344, 4377.5116460403615, 3500.387432199355, 3523.4010218262956, 3979.4428779970826, 4089.7750623715983, 4093.3655283431117, 3986.3422535281934, 4852.441969173519, 4863.829765958175, 4887.65786394956, 4901.926471102936, 5678.77284908599, 5688.203651488795, 5997.170862886861, 6066.636001839904, 6079.544034197364, 1321.6365499487717, 1346.4210389532595, 1347.7035052288481, 1349.0691422918155, 1355.5391167003265, 787.8896954426385, 855.9186877246651, 906.3293163059288, 909.3452714045876, 918.7917887977422, 4882.340883980688, 5224.686518808883, 5254.579633629301, 5270.92093618596, 5294.896455247137, 5846.707664616837, 5869.706514674249, 5874.619464425942, 5899.734511946973, 5922.940770592239, 4857.465678775332, 5141.922871156936, 5162.767675454614, 5270.578353731692, 5302.496262855023, 2571.7171111177754, 2931.9120426387244, 2971.5839782430476, 3112.2686168750324, 3120.3306231166216, 5107.489251074937, 5397.973010134924, 5412.512873841373, 6031.265456616623, 6054.485368743618, 5370.624056316098, 5946.613470124515, 6005.438585967592, 6062.454379105901, 6110.725017374229, 5189.768399588138, 5240.371155461369, 6041.184440100706, 6053.848653783691, 6061.333253342129, 4924.72913989734, 5149.674273610185, 5363.517283444508, 5427.592930530305, 5603.243562119224, 4738.028704115717, 5194.7922083116255, 5639.266895303748, 5758.740856661377, 5808.7095645218415, 5359.63329351013, 5562.888742225052, 5654.773809047757, 6014.188084608521, 6021.488173505126, 3098.2409309719264, 3274.770766046354, 3656.2870240302886, 4024.577419371041, 4036.7779772112817, 5814.30228372581, 6062.0, 6065.9081365352895, 6073.969630151821, 6119.418655227746, 5522.392536821027, 5604.06659702402, 5861.501772349398, 5865.706714664335, 5933.169356290036, 5091.6181286402925, 5616.326040875121, 5888.735006924613, 5944.6433214005565, 6005.609635373687, 5742.181449336312, 5765.56758243125, 5782.190493331022, 5803.47768700819, 5867.711592478851, 5462.890742185214, 5832.737526811388, 5852.0, 5927.170196172626, 5950.702464876826, 5339.385970613429, 5681.886362272814, 5788.102844059177, 5825.592208545361, 5827.79602713903], "value": 4269.29308468362, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.7898109548231, 752.6989204318276, 786.2098590916129, 805.6092794994427, 853.3599800149888, 15122.773158402619, 15787.131707756069, 15905.363785448597, 16114.435994740186, 16120.435784747537, 14673.899457540674, 14828.332725027372, 14838.480653177141, 14848.628784280372, 14994.175320357375, 697.3967518096835, 727.4834867244263, 852.5225873510823, 882.1531329923265, 882.3779235638859, 887.4320434921623, 888.4136469929848, 899.7885496908222, 902.9322800789935, 903.7876099116702, 930.6044930904372, 947.1901524196816, 947.2516711797672, 948.6632245552833, 949.8100379924043, 928.1692884868039, 939.7979434421633, 940.8118376324694, 942.0344147249056, 942.2273735536862, 691.5954166812421, 699.2657709405104, 702.2345643248832, 702.3117345001875, 702.4240123099061, 14967.625809354744, 15459.690806183813, 15469.5359139226, 16233.594160145973, 16405.261763220682, 14718.632034199123, 14860.62848428787, 14882.841987911801, 14897.329620167011, 15123.39506419753, 11719.94140029303, 11741.09232899368, 11943.761124777542, 11950.223235489751, 11986.88013119875, 3753.8123093845197, 3760.3983362662116, 3840.1743625120735, 3880.20455806559, 4014.096828213658, 110056.0, 110117.0, 111878.44060779725, 113817.43091284491, 115143.0, 11245.71885702864, 11263.549458021751, 11484.827727584094, 11944.283342999448, 12149.453274602663, 23717.932693028855, 24287.907044183055, 24452.633210501856, 24496.775161241865, 24557.017719291027, 8090.4336696431865, 8110.310623596943, 8129.268365846984, 8257.79355516117, 8295.878382740764, 4368.301071828492, 4413.470383553929, 4518.096380723869, 4530.456345238526, 4687.554682305175, 3728.6084961079237, 3754.0990162360836, 3774.848671155324, 3814.675752561008, 3836.8465261389233, 668.4652278177439, 670.902719105727, 671.5265737654942, 679.1612358736955, 686.4268335939546, 668.5153263883743, 680.2653134614604, 704.1796307301936, 716.4912911832553, 746.8394295226552, 10598.258121931545, 10704.357738535713, 10740.892591074145, 11106.333619982826, 11215.383153926612, 672.865426914619, 699.7410957945593, 701.6211245927244, 730.5580124024995, 736.7347754808264, 5177.896442071213, 5219.399769026534, 5257.342832145981, 5265.6050796189675, 5281.287026251407, 108613.0, 109657.0, 111390.0, 111790.0, 112932.43533782443, 76079.23920760832, 79606.0, 93824.0, 93992.0, 94143.0, 76802.61598691971, 77370.0, 78776.60611696905, 79110.60444697854, 79864.0, 11060.983971460795, 11083.113350932068, 11104.0, 11287.492062857027, 11473.36896470702, 40856.774296771146, 47373.52626473829, 47725.76137119292, 51934.70163245949, 55865.0, 55588.16617750818, 65799.34200657932, 66629.0, 69381.3061869395, 69500.0, 14226.573202803933, 14619.075529241063, 14778.113313201242, 14807.629809254855, 15026.0, 15300.617484562976, 15876.444324448588, 15978.60053498672, 15985.120818355103, 16323.59191020234, 13942.372593233531, 14111.647208819862, 14262.786058209344, 14285.42858285657, 14479.85520144785, 14237.572872813418, 14700.264986750406, 14848.554543363716, 15025.774613381027, 15244.3902243909, 13583.864161358655, 13956.860431395962, 14075.577732668036, 14084.718305633625, 14100.576982690536, 11054.0, 11296.887031129912, 11299.265547739371, 11369.2609980351, 11481.942590286995, 9144.451332920045, 11121.777564448828, 11874.703132421759, 12172.817407739069, 12228.816567751313], "value": 7869.842321377636, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1539.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 118.0, 114.0, 117.0, 122.0, 116.0, 118.0, 118.0, 117.0, 117.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 110.0, 140.0, 136.0, 108.0, 144.0, 140.0, 162.0, 115.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 196.0, 192.0, 193.0, 194.0, 194.0, 199.0, 195.0, 201.0, 199.0], "value": 196.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 58.0, 99.0, 82.0, 82.0, 79.0, 59.0, 72.0, 61.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 73.0, 105.0, 101.0, 99.0, 87.0, 81.0, 70.0, 71.0, 82.0], "value": 85.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 96.0, 128.0, 133.0, 89.0, 99.0, 89.0, 133.0, 118.0], "value": 106.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 96.0, 96.0, 96.0, 96.0, 109.0, 126.0, 96.0, 128.0, 160.0], "value": 114.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 98.0, 142.0, 142.0, 87.0, 149.0, 146.0, 109.0, 91.0, 90.0], "value": 114.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 66.0, 55.0, 61.0, 61.0, 61.0, 61.0, 62.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 75.0, 69.0, 77.0, 73.0, 70.0, 71.0, 73.0, 70.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 86.0, 84.0, 80.0, 88.0, 102.0, 79.0, 86.0, 86.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 146.0, 128.0, 145.0, 151.0, 130.0, 138.0, 153.0, 210.0, 181.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 169.0, 164.0, 167.0, 170.0, 173.0, 166.0, 167.0, 165.0, 166.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 68.0, 64.0, 65.0, 63.0, 67.0, 66.0, 64.0, 60.0, 76.0], "value": 65.2, "unit": "score"}]}]} 08:47:59 INFO - Return code: 0 08:47:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:47:59 ERROR - # TBPL SUCCESS # 08:47:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:47:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:47:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:47:59 INFO - 2017-03-07 08:47:59.453 screenresolution[1660:10285] starting screenresolution argv=screenresolution get 08:47:59 INFO - 2017-03-07 08:47:59.456 screenresolution[1660:10285] Display 0: 1600x1200x32@60 08:47:59 INFO - 2017-03-07 08:47:59.462 screenresolution[1661:10288] starting screenresolution argv=screenresolution list 08:47:59 INFO - Available Modes on Display 0 08:47:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:47:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:47:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:47:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:47:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:47:59 INFO - Intel Iris: 08:47:59 INFO - Chipset Model: Intel Iris 08:47:59 INFO - Type: GPU 08:47:59 INFO - Bus: Built-In 08:47:59 INFO - VRAM (Dynamic, Max): 1536 MB 08:47:59 INFO - Vendor: Intel (0x8086) 08:47:59 INFO - Device ID: 0x0a2e 08:47:59 INFO - Revision ID: 0x0009 08:47:59 INFO - Displays: 08:47:59 INFO - Display: 08:47:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:47:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:47:59 INFO - Display Serial Number: 1600x1200 60 08:47:59 INFO - Main Display: Yes 08:47:59 INFO - Mirror: Off 08:47:59 INFO - Online: Yes 08:47:59 INFO - Rotation: Supported 08:47:59 INFO - Return code: 0 08:47:59 INFO - Running post-action listener: _resource_record_post_action 08:47:59 INFO - [mozharness: 2017-03-07 16:47:59.699019Z] Finished run-tests step (success) 08:47:59 INFO - Running post-run listener: _resource_record_post_run 08:47:59 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 39614464; Write bytes: 422453760; Read time: 256; Write time: 1426 08:47:59 INFO - TinderboxPrint: CPU usage
54.5% 08:47:59 INFO - TinderboxPrint: I/O read bytes / time
39,614,464 / 256 08:47:59 INFO - TinderboxPrint: I/O write bytes / time
422,453,760 / 1,426 08:47:59 INFO - TinderboxPrint: CPU idle
2,167.2 (45.5%) 08:47:59 INFO - TinderboxPrint: CPU system
961.9 (20.2%) 08:47:59 INFO - TinderboxPrint: CPU user
1,632.5 (34.3%) 08:47:59 INFO - TinderboxPrint: Swap in / out
161,611,776 / 0 08:47:59 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 122937856; Write bytes: 123794944; Read time: 11227; Write time: 288 08:47:59 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 35944448; Write bytes: 297290752; Read time: 230; Write time: 1133 08:48:00 INFO - Running post-run listener: _upload_blobber_files 08:48:00 INFO - Blob upload gear active. 08:48:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:48:00 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 08:48:00 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:48:00 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:48:00 INFO - (blobuploader) - INFO - Open directory for files ... 08:48:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:48:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:48:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:48:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:48:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:48:01 INFO - (blobuploader) - INFO - Done attempting. 08:48:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:48:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:48:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:48:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:48:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:48:02 INFO - (blobuploader) - INFO - Done attempting. 08:48:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:48:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:48:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:48:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:48:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:48:03 INFO - (blobuploader) - INFO - Done attempting. 08:48:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:48:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:48:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:48:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:48:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:48:06 INFO - (blobuploader) - INFO - Done attempting. 08:48:06 INFO - (blobuploader) - INFO - Iteration through files over. 08:48:06 INFO - Return code: 0 08:48:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:48:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:48:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5475b42752b15a78087e7915d0daa0b4fdba453fd5163c0f329c3d62a868a5fca56916fd460122a765883c6e5f884f08f3c4ee409a55f92a696efab0ef57adc4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7dc4bd46cee1726de97700fee3491bc311809d84f3bd292cfd7248fa85b7a7daed929562a7e23209a5db6a3f148bb6d8bb27daafce094292b97486f75fb0e83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4ded8cd53fb2c7a56894fa19591c3e60a98b90a73d8107f0ad955e8ddee68cf9757ae84eff0a513d5fe4e824a8278706da09b769431d5eb294db77ef8498deb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec144e22bbd87752514abdeaa46a11beefb2e196ef5b26028bf0dc3c8dc03da47ac569580a58dac9feba875608915db8e52aeed2873a7b29cc3a8b2f4ffade82"} 08:48:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:48:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:48:06 INFO - Contents: 08:48:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5475b42752b15a78087e7915d0daa0b4fdba453fd5163c0f329c3d62a868a5fca56916fd460122a765883c6e5f884f08f3c4ee409a55f92a696efab0ef57adc4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7dc4bd46cee1726de97700fee3491bc311809d84f3bd292cfd7248fa85b7a7daed929562a7e23209a5db6a3f148bb6d8bb27daafce094292b97486f75fb0e83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4ded8cd53fb2c7a56894fa19591c3e60a98b90a73d8107f0ad955e8ddee68cf9757ae84eff0a513d5fe4e824a8278706da09b769431d5eb294db77ef8498deb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec144e22bbd87752514abdeaa46a11beefb2e196ef5b26028bf0dc3c8dc03da47ac569580a58dac9feba875608915db8e52aeed2873a7b29cc3a8b2f4ffade82"} 08:48:06 INFO - Running post-run listener: copy_logs_to_upload_dir 08:48:06 INFO - Copying logs to upload dir... 08:48:06 INFO - mkdir: /builds/slave/test/build/upload/logs 08:48:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.022591 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-07 08:48:06.267119) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-07 08:48:06.270383) ========= 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.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5475b42752b15a78087e7915d0daa0b4fdba453fd5163c0f329c3d62a868a5fca56916fd460122a765883c6e5f884f08f3c4ee409a55f92a696efab0ef57adc4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7dc4bd46cee1726de97700fee3491bc311809d84f3bd292cfd7248fa85b7a7daed929562a7e23209a5db6a3f148bb6d8bb27daafce094292b97486f75fb0e83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4ded8cd53fb2c7a56894fa19591c3e60a98b90a73d8107f0ad955e8ddee68cf9757ae84eff0a513d5fe4e824a8278706da09b769431d5eb294db77ef8498deb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec144e22bbd87752514abdeaa46a11beefb2e196ef5b26028bf0dc3c8dc03da47ac569580a58dac9feba875608915db8e52aeed2873a7b29cc3a8b2f4ffade82"} build_url:https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011139 build_url: 'https://queue.taskcluster.net/v1/task/C8ixPDDSSQOhXaA9zONtZQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5475b42752b15a78087e7915d0daa0b4fdba453fd5163c0f329c3d62a868a5fca56916fd460122a765883c6e5f884f08f3c4ee409a55f92a696efab0ef57adc4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7dc4bd46cee1726de97700fee3491bc311809d84f3bd292cfd7248fa85b7a7daed929562a7e23209a5db6a3f148bb6d8bb27daafce094292b97486f75fb0e83", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4ded8cd53fb2c7a56894fa19591c3e60a98b90a73d8107f0ad955e8ddee68cf9757ae84eff0a513d5fe4e824a8278706da09b769431d5eb294db77ef8498deb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ec144e22bbd87752514abdeaa46a11beefb2e196ef5b26028bf0dc3c8dc03da47ac569580a58dac9feba875608915db8e52aeed2873a7b29cc3a8b2f4ffade82"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-07 08:48:06.305797) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 08:48:06.306137) ========= 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.YBdh5Yb1nU/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.Ljfo6lkHo9/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.005073 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 08:48:06.354815) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-07 08:48:06.355125) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-07 08:48:06.355448) ========= ========= Total master_lag: 0.18 =========