builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0133 starttime: 1490624989.73 results: success (0) buildid: 20170327063815 builduid: e72136090af740ee94927689452694d9 revision: e7ec5225149925c16217cc16f2093cb6ae6f9889 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 07:29:49.731488) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 07:29:49.732000) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 07:29:49.732286) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 07:29:49.758897) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 07:29:49.759268) ========= 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.ktDCI657Lt/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.br2n8qAekO/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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-27 07:29:49-- 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% 68.3M=0s 2017-03-27 07:29:49 (68.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131698 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 07:29:49.915305) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 07:29:49.915650) ========= 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.ktDCI657Lt/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.br2n8qAekO/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.028940 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 07:29:49.966388) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-27 07:29:49.966760) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e7ec5225149925c16217cc16f2093cb6ae6f9889 --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 e7ec5225149925c16217cc16f2093cb6ae6f9889 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ktDCI657Lt/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.br2n8qAekO/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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-27 07:29:50,070 truncating revision to first 12 chars 2017-03-27 07:29:50,070 Setting DEBUG logging. 2017-03-27 07:29:50,070 attempt 1/10 2017-03-27 07:29:50,070 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e7ec52251499?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 07:29:52,139 unpacking tar archive at: mozilla-beta-e7ec52251499/testing/mozharness/ program finished with exit code 0 elapsedTime=2.346479 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-27 07:29:52.336769) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 07:29:52.337095) ========= script_repo_revision: e7ec5225149925c16217cc16f2093cb6ae6f9889 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 07:29:52.337480) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 07:29:52.337766) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 07:29:52.358322) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-27 07:29:52.358757) ========= /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.ktDCI657Lt/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.br2n8qAekO/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 07:29:52 INFO - MultiFileLogger online at 20170327 07:29:52 in /builds/slave/test 07:29:52 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 07:29:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:29:52 INFO - {'append_to_log': False, 07:29:52 INFO - 'base_work_dir': '/builds/slave/test', 07:29:52 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:29:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:29:52 INFO - 'branch': 'Mozilla-Beta', 07:29:52 INFO - 'buildbot_json_path': 'buildprops.json', 07:29:52 INFO - 'config_files': ('talos/mac_config.py',), 07:29:52 INFO - 'default_actions': ('clobber', 07:29:52 INFO - 'read-buildbot-config', 07:29:52 INFO - 'download-and-extract', 07:29:52 INFO - 'populate-webroot', 07:29:52 INFO - 'create-virtualenv', 07:29:52 INFO - 'install', 07:29:52 INFO - 'run-tests'), 07:29:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:29:52 INFO - 'download_minidump_stackwalk': True, 07:29:52 INFO - 'download_symbols': 'ondemand', 07:29:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:29:52 INFO - 'tooltool.py': '/tools/tooltool.py', 07:29:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:29:52 INFO - '/tools/misc-python/virtualenv.py')}, 07:29:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:29:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:29:52 INFO - 'installer_path': 'installer.exe', 07:29:52 INFO - 'log_level': 'info', 07:29:52 INFO - 'log_name': 'talos', 07:29:52 INFO - 'log_to_console': True, 07:29:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:29:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:29:52 INFO - 'opt_config_files': (), 07:29:52 INFO - 'pip_index': False, 07:29:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:29:52 INFO - 'cmd': ('bash', 07:29:52 INFO - '-c', 07:29:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:29:52 INFO - 'enabled': True, 07:29:52 INFO - 'halt_on_failure': False, 07:29:52 INFO - 'name': 'check_screen_resolution'},), 07:29:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:29:52 INFO - 'cmd': ('bash', 07:29:52 INFO - '-c', 07:29:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:29:52 INFO - 'enabled': True, 07:29:52 INFO - 'halt_on_failure': False, 07:29:52 INFO - 'name': 'check_screen_resolution'},), 07:29:52 INFO - 'run_cmd_checks_enabled': True, 07:29:52 INFO - 'sps_profile': False, 07:29:52 INFO - 'sps_profile_interval': 0, 07:29:52 INFO - 'suite': 'dromaeojs-e10s', 07:29:52 INFO - 'system_bits': '32', 07:29:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:29:52 INFO - 'title': 't-yosemite-r7-0133', 07:29:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:29:52 INFO - 'use_talos_json': True, 07:29:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:29:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:29:52 INFO - 'work_dir': 'build'} 07:29:52 INFO - [mozharness: 2017-03-27 14:29:52.575328Z] Running clobber step. 07:29:52 INFO - Running pre-action listener: _resource_record_pre_action 07:29:52 INFO - Running main action method: clobber 07:29:52 INFO - rmtree: /builds/slave/test/build 07:29:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:29:55 INFO - Running post-action listener: _resource_record_post_action 07:29:55 INFO - [mozharness: 2017-03-27 14:29:55.090443Z] Finished clobber step (success) 07:29:55 INFO - [mozharness: 2017-03-27 14:29:55.090598Z] Running read-buildbot-config step. 07:29:55 INFO - Running pre-action listener: _resource_record_pre_action 07:29:55 INFO - Running main action method: read_buildbot_config 07:29:55 INFO - Using buildbot properties: 07:29:55 INFO - { 07:29:55 INFO - "project": "", 07:29:55 INFO - "product": "firefox", 07:29:55 INFO - "script_repo_revision": "production", 07:29:55 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 07:29:55 INFO - "repository": "", 07:29:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 07:29:55 INFO - "buildid": "20170327063815", 07:29:55 INFO - "pgo_build": "False", 07:29:55 INFO - "basedir": "/builds/slave/test", 07:29:55 INFO - "buildnumber": 3, 07:29:55 INFO - "slavename": "t-yosemite-r7-0133", 07:29:55 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:29:55 INFO - "platform": "yosemite_r7", 07:29:55 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 07:29:55 INFO - "branch": "mozilla-beta", 07:29:55 INFO - "repo_path": "releases/mozilla-beta", 07:29:55 INFO - "revision": "e7ec5225149925c16217cc16f2093cb6ae6f9889", 07:29:55 INFO - "stage_platform": "macosx64", 07:29:55 INFO - "builduid": "e72136090af740ee94927689452694d9", 07:29:55 INFO - "slavebuilddir": "test" 07:29:55 INFO - } 07:29:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 07:29:55 INFO - Running post-action listener: _resource_record_post_action 07:29:55 INFO - [mozharness: 2017-03-27 14:29:55.093951Z] Finished read-buildbot-config step (success) 07:29:55 INFO - [mozharness: 2017-03-27 14:29:55.094085Z] Running download-and-extract step. 07:29:55 INFO - Running pre-action listener: _resource_record_pre_action 07:29:55 INFO - Running main action method: download_and_extract 07:29:55 INFO - mkdir: /builds/slave/test/build/tests 07:29:55 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')]} 07:29:55 INFO - trying https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 07:29:55 INFO - Downloading https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/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 07:29:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/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 07:29:56 INFO - Downloaded 1165 bytes. 07:29:56 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 07:29:56 INFO - Using the following test package requirements: 07:29:56 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 07:29:56 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 07:29:56 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 07:29:56 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 07:29:56 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 07:29:56 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 07:29:56 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 07:29:56 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 07:29:56 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 07:29:56 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 07:29:56 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 07:29:56 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 07:29:56 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 07:29:56 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 07:29:56 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 07:29:56 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 07:29:56 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 07:29:56 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 07:29:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 07:29:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 07:29:56 INFO - Fetch https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 07:29:56 INFO - Expected file size: 16108431 07:29:56 INFO - Obtained file size: 16108431 07:30:03 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 07:30:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 07:30:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 07:30:03 INFO - Fetch https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 07:30:04 INFO - Expected file size: 16108431 07:30:04 INFO - Obtained file size: 16108431 07:30:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 07:30:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 07:30:11 INFO - Fetch https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 07:30:12 INFO - Expected file size: 14403663 07:30:12 INFO - Obtained file size: 14403663 07:30:12 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')]} 07:30:12 INFO - trying https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 07:30:12 INFO - Downloading https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 07:30:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/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 07:30:14 INFO - Downloaded 58675245 bytes. 07:30:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 07:30:14 INFO - mkdir: /builds/slave/test/properties 07:30:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:30:14 INFO - Writing to file /builds/slave/test/properties/build_url 07:30:14 INFO - Contents: 07:30:14 INFO - build_url:https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.dmg 07:30:17 INFO - Running post-action listener: _resource_record_post_action 07:30:17 INFO - Running post-action listener: set_extra_try_arguments 07:30:17 INFO - [mozharness: 2017-03-27 14:30:17.114653Z] Finished download-and-extract step (success) 07:30:17 INFO - [mozharness: 2017-03-27 14:30:17.114802Z] Running populate-webroot step. 07:30:17 INFO - Running pre-action listener: _resource_record_pre_action 07:30:17 INFO - Running main action method: populate_webroot 07:30:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:30:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:30:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:30:17 INFO - '--tppagecycles', 07:30:17 INFO - '7'], 07:30:17 INFO - 'tests': ['tsvgm']}}, 07:30:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:30:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:30:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:30:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:30:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:30:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:30:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:30:17 INFO - 'talos_options': ['--disable-e10s'], 07:30:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:30:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:30:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:30:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:30:17 INFO - 'talos_options': ['--disable-e10s'], 07:30:17 INFO - 'tests': ['damp', 'tps']}, 07:30:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:30:17 INFO - 'tests': ['damp', 'tps']}, 07:30:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:30:17 INFO - 'tests': ['dromaeo_dom']}, 07:30:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:30:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:30:17 INFO - 'tests': ['basic_compositor_video']}, 07:30:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:30:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:30:17 INFO - 'tests': ['a11yr', 07:30:17 INFO - 'ts_paint', 07:30:17 INFO - 'tpaint', 07:30:17 INFO - 'sessionrestore', 07:30:17 INFO - 'sessionrestore_no_auto_restore', 07:30:17 INFO - 'tabpaint']}, 07:30:17 INFO - 'other-e10s': {'tests': ['a11yr', 07:30:17 INFO - 'ts_paint', 07:30:17 INFO - 'tpaint', 07:30:17 INFO - 'sessionrestore', 07:30:17 INFO - 'sessionrestore_no_auto_restore', 07:30:17 INFO - 'tabpaint']}, 07:30:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:30:17 INFO - 'ts_paint', 07:30:17 INFO - 'tpaint', 07:30:17 INFO - 'sessionrestore', 07:30:17 INFO - 'sessionrestore_no_auto_restore', 07:30:17 INFO - 'tabpaint']}, 07:30:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:30:17 INFO - 'ts_paint', 07:30:17 INFO - 'tpaint', 07:30:17 INFO - 'sessionrestore', 07:30:17 INFO - 'sessionrestore_no_auto_restore', 07:30:17 INFO - 'tabpaint']}, 07:30:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:30:17 INFO - 'tests': ['a11yr', 07:30:17 INFO - 'ts_paint', 07:30:17 INFO - 'tpaint', 07:30:17 INFO - 'sessionrestore', 07:30:17 INFO - 'sessionrestore_no_auto_restore', 07:30:17 INFO - 'tabpaint']}, 07:30:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:30:17 INFO - 'tests': ['a11yr', 07:30:17 INFO - 'ts_paint', 07:30:17 INFO - 'tpaint', 07:30:17 INFO - 'sessionrestore', 07:30:17 INFO - 'sessionrestore_no_auto_restore', 07:30:17 INFO - 'tabpaint']}, 07:30:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:30:17 INFO - 'tests': ['tsvgx', 07:30:17 INFO - 'tsvgr_opacity', 07:30:17 INFO - 'tart', 07:30:17 INFO - 'tscrollx', 07:30:17 INFO - 'cart', 07:30:17 INFO - 'tsvg_static']}, 07:30:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:30:17 INFO - 'tsvgr_opacity', 07:30:17 INFO - 'tart', 07:30:17 INFO - 'tscrollx', 07:30:17 INFO - 'cart', 07:30:17 INFO - 'tsvg_static']}, 07:30:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:30:17 INFO - 'talos_options': ['--disable-e10s'], 07:30:17 INFO - 'tests': ['tp5o']}, 07:30:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:30:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:30:17 INFO - 'talos_options': ['--disable-e10s', 07:30:17 INFO - '--xperf_path', 07:30:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:30:17 INFO - 'tests': ['tp5n']}, 07:30:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:30:17 INFO - 'talos_options': ['--xperf_path', 07:30:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:30:17 INFO - 'tests': ['tp5n']}}, 07:30:17 INFO - 'talos.zip': {'path': '', 07:30:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:30:17 INFO - Running post-action listener: _resource_record_post_action 07:30:17 INFO - [mozharness: 2017-03-27 14:30:17.133083Z] Finished populate-webroot step (success) 07:30:17 INFO - [mozharness: 2017-03-27 14:30:17.133224Z] Running create-virtualenv step. 07:30:17 INFO - Running pre-action listener: _resource_record_pre_action 07:30:17 INFO - Running main action method: create_virtualenv 07:30:17 INFO - Creating virtualenv /builds/slave/test/build/venv 07:30:17 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 07:30:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:30:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:30:17 INFO - Using real prefix '/tools/python27' 07:30:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:30:17 INFO - Installing distribute.............................................................................................................................................................................................done. 07:30:20 INFO - Installing pip.................done. 07:30:20 INFO - Return code: 0 07:30:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:30:21 INFO - Reading from file tmpfile_stdout 07:30:21 INFO - Output received: 07:30:21 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) 07:30:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:21 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 0x10bf7db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf80768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1bbd7a040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5bd30>, '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 0x10bc82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd50be8>, '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.ktDCI657Lt/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.br2n8qAekO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 07:30:21 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 07:30:21 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 07:30:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktDCI657Lt/Render', 07:30:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:21 INFO - 'HOME': '/Users/cltbld', 07:30:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:21 INFO - 'LOGNAME': 'cltbld', 07:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:21 INFO - 'MOZ_NO_REMOTE': '1', 07:30:21 INFO - 'NO_EM_RESTART': '1', 07:30:21 INFO - 'PAGER': '/bin/cat', 07:30:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:21 INFO - 'PWD': '/builds/slave/test', 07:30:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:21 INFO - 'SHELL': '/bin/bash', 07:30:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.br2n8qAekO/Listeners', 07:30:21 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 07:30:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:21 INFO - 'USER': 'cltbld', 07:30:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:21 INFO - 'XPC_FLAGS': '0x0', 07:30:21 INFO - 'XPC_SERVICE_NAME': '0', 07:30:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:21 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 07:30:21 INFO - Cleaning up... 07:30:21 INFO - Return code: 0 07:30:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:21 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 0x10bf7db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf80768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1bbd7a040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5bd30>, '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 0x10bc82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd50be8>, '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.ktDCI657Lt/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.br2n8qAekO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 07:30:21 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 07:30:21 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 07:30:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktDCI657Lt/Render', 07:30:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:21 INFO - 'HOME': '/Users/cltbld', 07:30:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:21 INFO - 'LOGNAME': 'cltbld', 07:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:21 INFO - 'MOZ_NO_REMOTE': '1', 07:30:21 INFO - 'NO_EM_RESTART': '1', 07:30:21 INFO - 'PAGER': '/bin/cat', 07:30:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:21 INFO - 'PWD': '/builds/slave/test', 07:30:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:21 INFO - 'SHELL': '/bin/bash', 07:30:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.br2n8qAekO/Listeners', 07:30:21 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 07:30:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:21 INFO - 'USER': 'cltbld', 07:30:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:21 INFO - 'XPC_FLAGS': '0x0', 07:30:21 INFO - 'XPC_SERVICE_NAME': '0', 07:30:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:21 INFO - Downloading/unpacking psutil>=3.1.1 07:30:21 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 07:30:21 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 07:30:21 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 07:30:21 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 07:30:21 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 07:30:21 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 07:30:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:30:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:30:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:30:25 INFO - Installing collected packages: psutil 07:30:25 INFO - Running setup.py install for psutil 07:30:25 INFO - building 'psutil._psutil_osx' extension 07:30:25 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 07:30:25 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 07:30:25 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 07:30:26 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 07:30:26 INFO - building 'psutil._psutil_posix' extension 07:30:26 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 07:30:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:30:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:30:26 INFO - ^ 07:30:26 INFO - 1 warning generated. 07:30:26 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 07:30:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:30:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:30:26 INFO - Successfully installed psutil 07:30:26 INFO - Cleaning up... 07:30:26 INFO - Return code: 0 07:30:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:30:26 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')]} 07:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:26 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')]} 07:30:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:26 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 0x10bf7db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf80768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1bbd7a040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5bd30>, '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 0x10bc82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd50be8>, '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.ktDCI657Lt/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.br2n8qAekO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 07:30:26 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 07:30:26 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 07:30:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktDCI657Lt/Render', 07:30:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:26 INFO - 'HOME': '/Users/cltbld', 07:30:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:26 INFO - 'LOGNAME': 'cltbld', 07:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:26 INFO - 'MOZ_NO_REMOTE': '1', 07:30:26 INFO - 'NO_EM_RESTART': '1', 07:30:26 INFO - 'PAGER': '/bin/cat', 07:30:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:26 INFO - 'PWD': '/builds/slave/test', 07:30:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:26 INFO - 'SHELL': '/bin/bash', 07:30:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.br2n8qAekO/Listeners', 07:30:26 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 07:30:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:26 INFO - 'USER': 'cltbld', 07:30:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:26 INFO - 'XPC_FLAGS': '0x0', 07:30:26 INFO - 'XPC_SERVICE_NAME': '0', 07:30:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:30:26 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 07:30:26 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 07:30:26 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 07:30:26 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 07:30:26 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 07:30:26 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 07:30:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:30:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:30:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:30:29 INFO - Installing collected packages: mozsystemmonitor 07:30:29 INFO - Running setup.py install for mozsystemmonitor 07:30:29 INFO - Successfully installed mozsystemmonitor 07:30:29 INFO - Cleaning up... 07:30:29 INFO - Return code: 0 07:30:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bf7db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf80768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1bbd7a040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5bd30>, '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 0x10bc82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd50be8>, '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.ktDCI657Lt/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.br2n8qAekO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 07:30:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:30:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:30:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktDCI657Lt/Render', 07:30:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:29 INFO - 'HOME': '/Users/cltbld', 07:30:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:29 INFO - 'LOGNAME': 'cltbld', 07:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:29 INFO - 'MOZ_NO_REMOTE': '1', 07:30:29 INFO - 'NO_EM_RESTART': '1', 07:30:29 INFO - 'PAGER': '/bin/cat', 07:30:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:29 INFO - 'PWD': '/builds/slave/test', 07:30:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:29 INFO - 'SHELL': '/bin/bash', 07:30:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.br2n8qAekO/Listeners', 07:30:29 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 07:30:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:29 INFO - 'USER': 'cltbld', 07:30:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:29 INFO - 'XPC_FLAGS': '0x0', 07:30:29 INFO - 'XPC_SERVICE_NAME': '0', 07:30:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:29 INFO - Downloading/unpacking jsonschema==2.5.1 07:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:30:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:33 INFO - Downloading functools32-3.2.3-2.tar.gz 07:30:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:30:33 INFO - warning: no files found matching '*.txt' 07:30:33 INFO - no previously-included directories found matching 'build' 07:30:33 INFO - no previously-included directories found matching 'dist' 07:30:33 INFO - no previously-included directories found matching '.git*' 07:30:33 INFO - Installing collected packages: jsonschema, functools32 07:30:33 INFO - Running setup.py install for functools32 07:30:33 INFO - warning: no files found matching '*.txt' 07:30:33 INFO - no previously-included directories found matching 'build' 07:30:33 INFO - no previously-included directories found matching 'dist' 07:30:33 INFO - no previously-included directories found matching '.git*' 07:30:33 INFO - Successfully installed jsonschema functools32 07:30:33 INFO - Cleaning up... 07:30:33 INFO - Return code: 0 07:30:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:30:33 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')]} 07:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:33 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')]} 07:30:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:33 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 0x10bf7db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf80768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1bbd7a040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5bd30>, '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 0x10bc82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd50be8>, '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.ktDCI657Lt/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.br2n8qAekO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 07:30:33 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 07:30:33 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 07:30:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktDCI657Lt/Render', 07:30:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:33 INFO - 'HOME': '/Users/cltbld', 07:30:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:33 INFO - 'LOGNAME': 'cltbld', 07:30:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:33 INFO - 'MOZ_NO_REMOTE': '1', 07:30:33 INFO - 'NO_EM_RESTART': '1', 07:30:33 INFO - 'PAGER': '/bin/cat', 07:30:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:33 INFO - 'PWD': '/builds/slave/test', 07:30:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:33 INFO - 'SHELL': '/bin/bash', 07:30:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.br2n8qAekO/Listeners', 07:30:33 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 07:30:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:33 INFO - 'USER': 'cltbld', 07:30:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:33 INFO - 'XPC_FLAGS': '0x0', 07:30:33 INFO - 'XPC_SERVICE_NAME': '0', 07:30:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:30:33 INFO - Cleaning up... 07:30:33 INFO - Return code: 0 07:30:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:30:33 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')]} 07:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:33 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')]} 07:30:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:33 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 0x10bf7db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf80768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1bbd7a040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5bd30>, '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 0x10bc82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd50be8>, '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.ktDCI657Lt/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.br2n8qAekO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 07:30:33 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 07:30:33 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 07:30:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktDCI657Lt/Render', 07:30:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:33 INFO - 'HOME': '/Users/cltbld', 07:30:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:33 INFO - 'LOGNAME': 'cltbld', 07:30:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:33 INFO - 'MOZ_NO_REMOTE': '1', 07:30:33 INFO - 'NO_EM_RESTART': '1', 07:30:33 INFO - 'PAGER': '/bin/cat', 07:30:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:33 INFO - 'PWD': '/builds/slave/test', 07:30:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:33 INFO - 'SHELL': '/bin/bash', 07:30:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.br2n8qAekO/Listeners', 07:30:33 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 07:30:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:33 INFO - 'USER': 'cltbld', 07:30:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:33 INFO - 'XPC_FLAGS': '0x0', 07:30:33 INFO - 'XPC_SERVICE_NAME': '0', 07:30:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:33 INFO - Downloading/unpacking blobuploader==1.2.4 07:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:36 INFO - Downloading blobuploader-1.2.4.tar.gz 07:30:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:30:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:30:36 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 07:30:36 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 07:30:36 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 07:30:36 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 07:30:36 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 07:30:36 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 07:30:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:30:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:30:37 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 07:30:37 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 07:30:37 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 07:30:37 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 07:30:37 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 07:30:37 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 07:30:37 INFO - Downloading docopt-0.6.1.tar.gz 07:30:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:30:37 INFO - Installing collected packages: blobuploader, requests, docopt 07:30:37 INFO - Running setup.py install for blobuploader 07:30:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:30:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:30:38 INFO - Running setup.py install for requests 07:30:38 INFO - Running setup.py install for docopt 07:30:38 INFO - Successfully installed blobuploader requests docopt 07:30:38 INFO - Cleaning up... 07:30:38 INFO - Return code: 0 07:30:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:30: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')]} 07:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30: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')]} 07:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:38 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 0x10bf7db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf80768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1bbd7a040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5bd30>, '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 0x10bc82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd50be8>, '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.ktDCI657Lt/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.br2n8qAekO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 07:30:38 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 07:30:38 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 07:30:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktDCI657Lt/Render', 07:30:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:38 INFO - 'HOME': '/Users/cltbld', 07:30:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:38 INFO - 'LOGNAME': 'cltbld', 07:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:38 INFO - 'MOZ_NO_REMOTE': '1', 07:30:38 INFO - 'NO_EM_RESTART': '1', 07:30:38 INFO - 'PAGER': '/bin/cat', 07:30:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:38 INFO - 'PWD': '/builds/slave/test', 07:30:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:38 INFO - 'SHELL': '/bin/bash', 07:30:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.br2n8qAekO/Listeners', 07:30:38 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 07:30:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:38 INFO - 'USER': 'cltbld', 07:30:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:38 INFO - 'XPC_FLAGS': '0x0', 07:30:38 INFO - 'XPC_SERVICE_NAME': '0', 07:30:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:30:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-vykV6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:30:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-8kuWtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:30:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-kFKuTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:30:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-R7uEUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:30:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-keSLoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:30:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-gk3Tju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:30:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-yUsW0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:30:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-YLZktS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:30:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-ccVdgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:30:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-kHQSmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:30:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-uhmlaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:30:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-WZ8etc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:30:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-rGAjas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:30:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-OJ3h3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:30:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-T7UwRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:30:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-_8FwWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:30:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-1FNDLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:30:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:30:40 INFO - Running setup.py install for manifestparser 07:30:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:30:40 INFO - Running setup.py install for mozcrash 07:30:41 INFO - Running setup.py install for mozdebug 07:30:41 INFO - Running setup.py install for mozdevice 07:30:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:30:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:30:41 INFO - Running setup.py install for mozfile 07:30:41 INFO - Running setup.py install for mozhttpd 07:30:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:30:41 INFO - Running setup.py install for mozinfo 07:30:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:30:41 INFO - Running setup.py install for mozInstall 07:30:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:30:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:30:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:30:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:30:41 INFO - Running setup.py install for mozleak 07:30:42 INFO - Running setup.py install for mozlog 07:30:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:30:42 INFO - Running setup.py install for moznetwork 07:30:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:30:42 INFO - Running setup.py install for mozprocess 07:30:42 INFO - Running setup.py install for mozprofile 07:30:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:30:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:30:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:30:42 INFO - Running setup.py install for mozrunner 07:30:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:30:42 INFO - Running setup.py install for mozscreenshot 07:30:43 INFO - Running setup.py install for moztest 07:30:43 INFO - Running setup.py install for mozversion 07:30:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:30:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:30:43 INFO - Cleaning up... 07:30:43 INFO - Return code: 0 07:30:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:30:43 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')]} 07:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:43 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')]} 07:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:43 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 0x10bf7db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf80768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1bbd7a040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5bd30>, '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 0x10bc82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd50be8>, '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.ktDCI657Lt/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.br2n8qAekO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 07:30:43 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 07:30:43 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 07:30:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktDCI657Lt/Render', 07:30:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:43 INFO - 'HOME': '/Users/cltbld', 07:30:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:43 INFO - 'LOGNAME': 'cltbld', 07:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:43 INFO - 'MOZ_NO_REMOTE': '1', 07:30:43 INFO - 'NO_EM_RESTART': '1', 07:30:43 INFO - 'PAGER': '/bin/cat', 07:30:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:43 INFO - 'PWD': '/builds/slave/test', 07:30:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:43 INFO - 'SHELL': '/bin/bash', 07:30:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.br2n8qAekO/Listeners', 07:30:43 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 07:30:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:43 INFO - 'USER': 'cltbld', 07:30:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:43 INFO - 'XPC_FLAGS': '0x0', 07:30:43 INFO - 'XPC_SERVICE_NAME': '0', 07:30:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:30:43 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-IId6j3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:30:43 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)) 07:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:30:43 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Ep05fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:30:43 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)) 07:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:30:43 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-lE2wOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:30:44 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)) 07:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:30:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-yTQJGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:30:44 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)) 07:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:30:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-4s3AYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:30:44 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)) 07:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:30:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Pi0TdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:30:44 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)) 07:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:30:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-_tuwRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:30:44 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)) 07:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:30:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-ZOnPJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:30:44 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)) 07:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:30:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-4zYRZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:30:44 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)) 07:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:30:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-MtasOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:30:44 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)) 07:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:30:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Derdx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:30:44 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)) 07:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:30:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-U8kjJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:30:44 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)) 07:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:30:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-riNbVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:30:45 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)) 07:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:30:45 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Mn198N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:30:45 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)) 07:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:30:45 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-DBifmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:30:45 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)) 07:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:30:45 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-E6eMPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:30:45 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)) 07:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:30:45 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-5jLeZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:30:45 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)) 07:30:45 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)) 07:30:45 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)) 07:30:45 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)) 07:30:45 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)) 07:30:45 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)) 07:30:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:30:45 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 07:30:45 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 07:30:45 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 07:30:45 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 07:30:45 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 07:30:45 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 07:30:48 INFO - Downloading blessings-1.6.tar.gz 07:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:30:48 INFO - Installing collected packages: blessings 07:30:48 INFO - Running setup.py install for blessings 07:30:48 INFO - Successfully installed blessings 07:30:48 INFO - Cleaning up... 07:30:48 INFO - Return code: 0 07:30:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:30:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:30:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:30:49 INFO - Reading from file tmpfile_stdout 07:30:49 INFO - Current package versions: 07:30:49 INFO - blessings == 1.6 07:30:49 INFO - blobuploader == 1.2.4 07:30:49 INFO - docopt == 0.6.1 07:30:49 INFO - functools32 == 3.2.3-2 07:30:49 INFO - jsonschema == 2.5.1 07:30:49 INFO - manifestparser == 1.1 07:30:49 INFO - mozInstall == 1.12 07:30:49 INFO - mozcrash == 1.0 07:30:49 INFO - mozdebug == 0.1 07:30:49 INFO - mozdevice == 0.49 07:30:49 INFO - mozfile == 1.2 07:30:49 INFO - mozhttpd == 0.7 07:30:49 INFO - mozinfo == 0.9 07:30:49 INFO - mozleak == 0.1 07:30:49 INFO - mozlog == 3.4 07:30:49 INFO - moznetwork == 0.27 07:30:49 INFO - mozprocess == 0.24 07:30:49 INFO - mozprofile == 0.28 07:30:49 INFO - mozrunner == 6.13 07:30:49 INFO - mozscreenshot == 0.1 07:30:49 INFO - mozsystemmonitor == 0.3 07:30:49 INFO - moztest == 0.8 07:30:49 INFO - mozversion == 1.4 07:30:49 INFO - psutil == 3.1.1 07:30:49 INFO - requests == 1.2.3 07:30:49 INFO - wsgiref == 0.1.2 07:30:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:49 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 0x10bf7db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf80768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1bbd7a040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5bd30>, '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 0x10bc82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd50be8>, '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.ktDCI657Lt/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.br2n8qAekO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 07:30:49 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 07:30:49 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 07:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktDCI657Lt/Render', 07:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:49 INFO - 'HOME': '/Users/cltbld', 07:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:49 INFO - 'LOGNAME': 'cltbld', 07:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:49 INFO - 'MOZ_NO_REMOTE': '1', 07:30:49 INFO - 'NO_EM_RESTART': '1', 07:30:49 INFO - 'PAGER': '/bin/cat', 07:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:49 INFO - 'PWD': '/builds/slave/test', 07:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:49 INFO - 'SHELL': '/bin/bash', 07:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.br2n8qAekO/Listeners', 07:30:49 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 07:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:49 INFO - 'USER': 'cltbld', 07:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:49 INFO - 'XPC_FLAGS': '0x0', 07:30:49 INFO - 'XPC_SERVICE_NAME': '0', 07:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:49 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)) 07:30:49 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)) 07:30:49 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)) 07:30:49 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)) 07:30:49 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)) 07:30:49 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)) 07:30:49 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)) 07:30:49 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)) 07:30:49 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)) 07:30:49 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)) 07:30:49 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)) 07:30:49 INFO - Cleaning up... 07:30:49 INFO - Return code: 0 07:30:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bf7db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf80768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1bbd7a040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5bd30>, '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 0x10bc82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd50be8>, '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.ktDCI657Lt/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.br2n8qAekO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 07:30:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktDCI657Lt/Render', 07:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:49 INFO - 'HOME': '/Users/cltbld', 07:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:49 INFO - 'LOGNAME': 'cltbld', 07:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:49 INFO - 'MOZ_NO_REMOTE': '1', 07:30:49 INFO - 'NO_EM_RESTART': '1', 07:30:49 INFO - 'PAGER': '/bin/cat', 07:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:49 INFO - 'PWD': '/builds/slave/test', 07:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:49 INFO - 'SHELL': '/bin/bash', 07:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.br2n8qAekO/Listeners', 07:30:49 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 07:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:49 INFO - 'USER': 'cltbld', 07:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:49 INFO - 'XPC_FLAGS': '0x0', 07:30:49 INFO - 'XPC_SERVICE_NAME': '0', 07:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:30:50 INFO - Cleaning up... 07:30:50 INFO - Return code: 0 07:30:50 INFO - Running post-action listener: _resource_record_post_action 07:30:50 INFO - Running post-action listener: _start_resource_monitoring 07:30:50 INFO - Starting resource monitoring. 07:30:50 INFO - [mozharness: 2017-03-27 14:30:50.073398Z] Finished create-virtualenv step (success) 07:30:50 INFO - [mozharness: 2017-03-27 14:30:50.074384Z] Running install step. 07:30:50 INFO - Running pre-action listener: _resource_record_pre_action 07:30:50 INFO - Running main action method: install 07:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:30:50 INFO - Reading from file tmpfile_stdout 07:30:50 INFO - Detecting whether we're running mozinstall >=1.0... 07:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:30:50 INFO - Reading from file tmpfile_stdout 07:30:50 INFO - Output received: 07:30:50 INFO - Usage: mozinstall [options] installer 07:30:50 INFO - Options: 07:30:50 INFO - -h, --help show this help message and exit 07:30:50 INFO - -d DEST, --destination=DEST 07:30:50 INFO - Directory to install application into. [default: 07:30:50 INFO - "/builds/slave/test"] 07:30:50 INFO - --app=APP Application being installed. [default: firefox] 07:30:50 INFO - mkdir: /builds/slave/test/build/application 07:30:50 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'] 07:30:50 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 07:31:05 INFO - Reading from file tmpfile_stdout 07:31:05 INFO - Output received: 07:31:05 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 07:31:05 INFO - Running post-action listener: _resource_record_post_action 07:31:05 INFO - [mozharness: 2017-03-27 14:31:05.185519Z] Finished install step (success) 07:31:05 INFO - [mozharness: 2017-03-27 14:31:05.185653Z] Running run-tests step. 07:31:05 INFO - Running pre-action listener: _resource_record_pre_action 07:31:05 INFO - Running main action method: run_tests 07:31:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:31:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:31:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:31:05 INFO - 2017-03-27 07:31:05.200 screenresolution[1624:7359] starting screenresolution argv=screenresolution get 07:31:05 INFO - 2017-03-27 07:31:05.202 screenresolution[1624:7359] Display 0: 1600x1200x32@60 07:31:05 INFO - 2017-03-27 07:31:05.208 screenresolution[1625:7361] starting screenresolution argv=screenresolution list 07:31:05 INFO - Available Modes on Display 0 07:31:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:31:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:31:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:31:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:31:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:31:05 INFO - Intel Iris: 07:31:05 INFO - Chipset Model: Intel Iris 07:31:05 INFO - Type: GPU 07:31:05 INFO - Bus: Built-In 07:31:05 INFO - VRAM (Dynamic, Max): 1536 MB 07:31:05 INFO - Vendor: Intel (0x8086) 07:31:05 INFO - Device ID: 0x0a2e 07:31:05 INFO - Revision ID: 0x0009 07:31:05 INFO - Displays: 07:31:05 INFO - Display: 07:31:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:31:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:31:05 INFO - Display Serial Number: 1600x1200 60 07:31:05 INFO - Main Display: Yes 07:31:05 INFO - Mirror: Off 07:31:05 INFO - Online: Yes 07:31:05 INFO - Rotation: Supported 07:31:05 INFO - Return code: 0 07:31:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:31:05 INFO - Python 2.7.3 07:31:05 INFO - Return code: 0 07:31:05 INFO - grabbing minidump binary from tooltool 07:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:05 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5bd30>, '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 0x10bc82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd50be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:31:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:31:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:31:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:31:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:31:05 INFO - Return code: 0 07:31:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:31:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:31:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:31:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:31:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:31:05 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/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--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 07:31:05 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/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0133 --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 07:31:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ktDCI657Lt/Render', 07:31:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:05 INFO - 'HOME': '/Users/cltbld', 07:31:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:05 INFO - 'LOGNAME': 'cltbld', 07:31:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:31:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:05 INFO - 'MOZ_NO_REMOTE': '1', 07:31:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:31:05 INFO - 'NO_EM_RESTART': '1', 07:31:05 INFO - 'PAGER': '/bin/cat', 07:31:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:05 INFO - 'PWD': '/builds/slave/test', 07:31:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:31:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:05 INFO - 'SHELL': '/bin/bash', 07:31:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.br2n8qAekO/Listeners', 07:31:05 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 07:31:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:05 INFO - 'USER': 'cltbld', 07:31:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:05 INFO - 'XPC_FLAGS': '0x0', 07:31:05 INFO - 'XPC_SERVICE_NAME': '0', 07:31:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:05 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/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--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 07:31:05 INFO - mozversion application_buildid: 20170327063815 07:31:05 INFO - mozversion application_changeset: e7ec5225149925c16217cc16f2093cb6ae6f9889 07:31:05 INFO - mozversion application_display_name: Firefox 07:31:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:31:05 INFO - mozversion application_name: Firefox 07:31:05 INFO - mozversion application_remotingname: firefox 07:31:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:31:05 INFO - mozversion application_vendor: Mozilla 07:31:05 INFO - mozversion application_version: 53.0 07:31:05 INFO - mozversion platform_buildid: 20170327063815 07:31:05 INFO - mozversion platform_changeset: e7ec5225149925c16217cc16f2093cb6ae6f9889 07:31:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:31:05 INFO - mozversion platform_version: 53.0 07:31:05 INFO - using testdate: 1490625065 07:31:05 INFO - actual date: 1490625065 07:31:05 INFO - starting webserver on 'localhost:49243' 07:31:05 INFO - SUITE-START | Running 2 tests 07:31:05 INFO - TEST-START | dromaeo_css 07:31:05 INFO - operating with platform_type : mac_ 07:31:05 INFO - Initialising browser for dromaeo_css test... 07:31:05 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpScpDPy/profile http://localhost:49243/getInfo.html) 07:31:08 INFO - PROCESS | 1635 | 2017-03-27 07:31:08.769 plugin-container[1638:7533] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 07:31:08 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:31:08 INFO - PROCESS | 1635 | 2017-03-27 07:31:08.770 plugin-container[1638:7533] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 07:31:08 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:31:08 INFO - PROCESS | 1635 | 2017-03-27 07:31:08.770 plugin-container[1638:7533] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:31:08 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 07:31:08 INFO - PROCESS | 1635 | colorDepth:24 07:31:08 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 07:31:08 INFO - PROCESS | 1635 | __metrics 07:31:08 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 07:31:09 INFO - TEST-INFO | 1635: exit 0 07:31:09 INFO - Browser initialized. 07:31:09 INFO - Running cycle 1/1 for dromaeo_css test... 07:31:09 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpScpDPy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:31:11 INFO - PROCESS | 1639 | 2017-03-27 07:31:11.731 plugin-container[1640:7665] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x842f, name = 'com.apple.tsm.portname' 07:31:11 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:31:11 INFO - PROCESS | 1639 | 2017-03-27 07:31:11.731 plugin-container[1640:7665] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4257, name = 'com.apple.CFPasteboardClient' 07:31:11 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:31:11 INFO - PROCESS | 1639 | 2017-03-27 07:31:11.731 plugin-container[1640:7665] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:31:21 INFO - PROCESS | 1639 | RSS: Main: 173068288 07:31:21 INFO - PROCESS | 1639 | 07:34:22 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 07:34:24 INFO - PROCESS | 1639 | RSS: Main: 190537728 07:34:24 INFO - PROCESS | 1639 | 07:37:27 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 07:37:27 INFO - PROCESS | 1639 | RSS: Main: 199602176 07:37:27 INFO - PROCESS | 1639 | 07:40:29 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 07:40:30 INFO - PROCESS | 1639 | RSS: Main: 202444800 07:40:30 INFO - PROCESS | 1639 | 07:43:32 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 07:43:32 INFO - PROCESS | 1639 | RSS: Main: 205647872 07:43:32 INFO - PROCESS | 1639 | 07:46:35 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 07:46:36 INFO - PROCESS | 1639 | RSS: Main: 206811136 07:46:36 INFO - PROCESS | 1639 | 07:49:39 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 07:49:39 INFO - PROCESS | 1639 | RSS: Main: 207978496 07:49:39 INFO - PROCESS | 1639 | 07:49:39 INFO - PROCESS | 1639 | __start_tp_report 07:49:39 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 07:49:39 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 07:49:39 INFO - PROCESS | 1639 | |i|pagename|runs| 07:49:39 INFO - PROCESS | 1639 | |0;dojo.html;3464.3590935676884;3465.272292818508;3470.7049900758434;3491.7555771096036;3499.2826470573536;5680.233168522253;5744.396838331978;5768.769249230026;5780.7687692492245;5786.594938354317;5743.741531631076;5743.798967036155;5830.591858569902;5852.209951656525;5856.267966504176;5176.0683077046215;5255.605829562779;5262.9473705263035;5304.708241046741;5314.096603577382;4262.9147417051645;4357.477102747659;5195.922061169085;5302.893942121155;5348.679079255257;5164.612654050942;5202.08963431398;5288.127458969266;5289.471052894699;5293.9735301323435;5231.633785635007;5235.214717792323;5278;5291.629585928987;5356;5130.204818253177;5216.191490319006;5233.319668443116;5244.895102097975;5303.23103150045;4952.702837829742;5561.6663000220115;5794.855128621776;5921.200637913869;5970.701464926737;5797.478226959552;5963.2844058713235;6171.321154672983;6188.938110618881;6256.562040657156;6309.684515774193;6332.240131184288;6339.746410143589;6348.809535713936;6370.9681451592905;5848.795292164754;5873.942690315785;5881.1490752988575;5884.08796636522;5914.319853216849;6252.030935205051;6260.593061451083;6262;6263.248410190807;6281.842953926189;6179.351168127372;6217.9689101555105;6240.157578726905;6247.75008999644;6257.7184026718905;6300.59046161998;6319.210098737658;6329.335419780857;6374.298827129012;6381.521385895983;6118.345080237303;6189.071639254148;6195.1636529068;6228.065790131516;6228.252609686776;6276.843078923063;6280.623162610258;6291.748330066746;6311.27420346666;6348.333424990402;4229.492460904711;4992.076465853828;5003.174476211457;5015.799368025238;5018.372703412074;4366.541513141138;4407.7796110195;4419.337099435046;4434.467863856358;4456.75487848165;5944.375840536768;5986.221791167208;6019.277686677627;6031.849203769941;6060.878782424327;6273.780417685359;6306.400891915261;6307.369263073656;6327;6332.176817013758;5806.361300256968;5836.241288632451;5947.197128387699;5999.970000149971;6049.667268300285;4795.784189711471;5387.81142660005;5436.21174929633;5485.862853428696;5501.229827824108;6310.652914089677;6335.651539165206;6343.492520598341;6355.809325720235;6387.968060159669;6227.96886015567;6252.531060170414;6285.434310911857;6295.464885484785;6302.338254483213;6322;6323.841903952438;6326.746930122928;6330.873382532415;6359.618422894641;6256.624602523863;6283.183186185766;6293.118963345044;6296.968515157578;6301.6534090625455;6131.284916201153;6194.287656919512;6274.435300822948;6360.236771587439;6401.8719625608155;5907.793227237026;5971.910421343597;5997.220361352996;6012.549058820693;6033.42682445167;5943.940560594338;5945.316288626863;5964.165016897551;5972.074328479092;5975.462208401264;5409.323834520685;5496.131820049156;5834.94165058344;5863.237779088862;5977.432143946318;6149.2620885494025;6166.290876549254;6215.9378406217165;6231.719572619152;6232.937670623236;6038.69806509664;6083.939160608337;6086.543509236736;6089.421504957022;6121.326654068138 07:49:39 INFO - PROCESS | 1639 | |1;ext.html;458.68580022684444;481.65080316770343;510.7956817273093;521.5749164430987;548.2215254338838;36376.2724745505;43239.91900202492;43385.56614433855;43391.349129763075;43425.78287108568;44254;44739;44803;44823.32765008527;45000;8729;9525.5237238138;9807.460589667566;11460.312381257152;11848.34834084125;9599.616015359377;11701.005414539773;11706.7658646827;11711.648650540455;11750.588729394472;11797.29216247028;11827.645170644895;11837.526498940031;11851.822222666668;11860.644180674593;11289.943550282278;11796.05631549474;11833.94083029588;11843.170978031498;11843.526258949718;11660.416979151008;11751.647450576496;11755.764884702257;11756.764864702744;11768.823467647991;38761;39291.8035409824;39305.60694393048;39372;39436.802815985735;3588.8923332300074;4043.191361727637;4075.32757095079;4084.4485994390675;4119.8970025749595;28551;28577;28635.856820715762;29752.107436776932;30353;3253.1216571525792;3527.0829584308008;3539.4159963605975;3540.6105328413855;3541.11472131967;423993.9999999969;428040;428481.8575907132;429158;430560;53045.73477132667;56986.71506642523;57567.424325756205;57736.422635773946;57890;55232.44767552354;58902.82194356089;59934.10098848521;60334.698326508966;60485;16935.915320423566;17152.48542543726;17176.055316957438;17179.31282748701;17235.74146387805;12083.275003499819;12324.198927069712;12888.097833151589;13136.474541018251;13148.539801106916;18867.245310187714;18902.960337181314;18905.24379024851;18935.242590296508;19108;8087.555184464911;8221.958890205511;8288.544130072783;8330.8333833323;8332.083470818205;838.6268110690684;896.4755617963434;905.2712566383992;911.6581282019242;948.7343543807744;31481.583328749803;32036.519452208235;32064;32318.030459085796;32801.01596952095;871.6992637540038;1108.8558487396588;1135.9716007099723;1149.8160294352863;1158.8145896656495;16424.917875410545;16540.338386464406;16542.172891355385;16579.336826527044;16812.831871681126;671833;683977;684835.5758221177;688033;689866;65467.017994730115;65719.67140164365;66251;66331;66493.66753166204;1490.5155824357128;1504.9322780474904;1515.613518552762;1530.556138719781;1532.4023630784004;29779.255518612208;30936.381272373976;31203;31678.524822128147;31856.5221521682;53699;53983;55739.44260557342;56559.999999998356;58757.4124258769;575492;607315;610738;612245;614127;35023;38638.613613863505;42008.57991420047;42125.1574968505;42677.78661106799;44749.55250447584;44826.77586612177;45009.32486012647;45880;46085.38701145444;31198;31697;31923.68076319207;32016;32646.51030234596;26982.46035079248;30473.695263047084;31255.374892502477;31533.8423307882;32182.551785169257;36664.26671466609;36689;36857.078573034814;37324.62675373212;37701.4344784823;26226.737732623194;30372.544411833398;30735.538966915068;30758.077257682304;31454.213644659067;23257.88371058134;23576.0569577215;25156.371090722878;25220.74779252184;25225.747742523075 07:49:39 INFO - PROCESS | 1639 | |2;jquery.html;309.9116751725759;391.9392494163405;401.78504500092436;420.3148867346225;432.48750230976293;20190.59618807623;21922.45193870154;22190;22465.663015054786;22681.319560413194;21917.01373438195;22399;22449.765262910532;22525;22677.659835102488;5825.708714564276;6129.233845769279;6136.662483563402;6141.846453838667;6153.415425534589;4409.404730361408;4599.057193275387;6067.635941843481;6623.172103487064;6930.930690693104;6706.329367063279;6986.895196572055;6989.510734248604;6992.300769922992;6994.3005699429905;6734.023566582842;6844.486663500207;6845.760398386033;6866.725330986779;6868.450523958071;5955.076963070731;5999.880002399971;6003.429674180946;6062.96968515159;6075;26993.730062699316;27044.323891902663;27254.182374528795;27278.863605681843;27723.445531089266;2840.6449193850767;2948;2954.1728316071535;2956.9852150739107;2958.4378967996226;12883.227006379648;16329;16452.670946581;16879.493615191564;17005;1898.8955607441585;1931.9710204346945;1951.5999220159833;1952.463072655017;1952.7656681198205;128148.35925820404;128215.35892320571;128824;133172;133640;9946.850797238047;10985.462035315062;11110.222284440011;11881.524739010516;12076.396180190955;15101.47144849925;15217.391304347702;15530.223488825739;15542.766858497132;15544.922275388551;9074.274058075338;9306.627734890664;9320.067993200626;9429.52852357393;9531.332806703464;9123.452592844451;9247.12152345526;9304.302177336727;9442.811143777086;9456.676065350857;7614.314711675976;7709.113451952985;7738.303552680286;7777.57223352721;7848.568328741974;1561.8281988981203;1750.8424241818298;1825.7809062912536;1836.8897866128075;2040.1023549638112;1007.5617106558524;1024.2522958240527;1029.917606591471;1037.1961729659574;1040.1107053469327;18039.08335731802;21567.2451464198;21836.89081554582;21972;22164;865.2774932931073;909.0500427053745;1070.464767616192;1088.7604726960055;1112.3159257057014;11423.485943132579;11493.712657183638;11711;11722.062235021178;11756.823647645293;732051.0192347119;760830;763947;782528;792914;116726.83273167329;119459.40270298767;143271.28364358112;144473;150192;1058.6083149234832;1072.5709716113618;1109.0794640448407;1109.4785450838094;1109.5672687651822;17109.914450427666;20418;20832;21004.054817532942;21245;53778;61007;61029;61959.69020154871;62048.68975655093;87934;91674.54162729411;95073.5246323764;95499;96039;8405.621747021276;8421;8675.783105422415;8710.69512567057;8735.519546424754;11555.768884622092;13052.15161014531;13075.869241307466;13228.669283267995;13459.923206143681;11339.09287257034;11359.034482069117;11522.481488332878;11651.88348116542;11721.765564688829;9040.683576074805;10672.73318167021;10805.351678899291;10967.561297548327;11057.889421105687;10078.740157480315;11691.590794322157;11872.940635296769;11878.168528202943;12018.939905300418;8255.752327430188;8451.535165565952;8464.64215348131;8714.085021072697;8837.337199709918;8522.531260780468;10498.160147188359;11411.942940285246;11787.646370608896;11845.52617895309 07:49:39 INFO - PROCESS | 1639 | |3;mootools.html;1403.6210223239718;1488.0773920169497;1491.3139955620413;1504.172705012243;1505.5709122899973;7791.259830316111;8020.639071241808;8290.502569845814;8722.651093956241;8732.30141588673;8229.300509456689;8528.147185281468;8546.615402306896;8632.568371581427;8640.049594544595;2060.6805945078536;2407.5064611754547;2412.9034838606435;2421.370443684637;2440.8291419600637;2380.642903564462;2382.880855957204;2411.5538625354366;2416.359664688859;2426.3085020769086;2375.4417711837705;2386.05750728463;2396.6524853896176;2409.2531315292317;2411.8311718179734;2363.4800343924308;2376;2382.5830479666074;2403.7956773674346;2410.373302941239;2388.10446082719;2390.9760902390926;2412.91418861513;2420.261820144855;2429.878506074689;8672.696455624086;8705.521196334199;8726.956365218197;8734.781630459225;8744.081871403538;5258.421573626898;5411.729413529308;5424.972875135639;5448.782048718046;5469.863253418696;3613.927721445583;3667.3032123896373;3684.797336146511;3719.6466335698065;3730.682891954187;1259.250745806244;1262.9431675574529;1301.4859130643438;1309.8231738715342;1323.358171286925;11086.334819910751;11233.157513186461;11250.324980501115;11266.887331126665;11281.661550153427;4743.193657078305;4786.1863483207935;4864.294677271776;4870.95129048705;4911.386076740408;9679.322447428613;9689.224862011022;9700.126988571063;9700.466474343979;9712.368696034735;5343.786248550092;5436.945630543644;5441.564674826004;5458.153986132156;5459.071957767189;4483.484399294058;4723.716577005391;4954.157793175169;4996.1007018736245;5000.724960127155;3786;3812.8474861005247;4035.6973227008093;4080.6531444827524;4156.147989662112;1326.7014921642553;1332.247280286635;1332.4403750424844;1335.5058628307588;1341.2354957674227;790.517784151667;889.1330952321434;906.1301150895083;921.1755478846395;924.7528505731003;5006.3992320921725;5365.463453654681;5388.305847076462;5646.491815736604;5773.913391299134;531.9015982043335;542.175892643188;544.9509544141047;550.086855819338;551.5615086006634;3550.55618047744;3571.3035957987945;3632.0919770057485;3668.6147954464395;3683.0055884910926;10013.949930250303;10273.486325683685;10356.948215259026;10362.481875906175;10379.273450858367;9711.36876103051;9915.405075695482;10195.643152489776;10247.538860751283;10295.124914382212;8014.719484818003;8027.237412445925;8202.712905048294;8210.261076503144;8235.341172706168;5913.069425104471;5987.730552125077;5995.400459953969;6023.367546407564;6154.5384096192065;9319.16127548524;9433.433993960385;9434.858477122985;9443.197328227177;9606;9605.903940960501;9624.133827955518;9640.662576809778;9709.126178643955;9812.362196457207;6905.447564194752;7148.749793757193;7404.148522919933;7444.441666875115;7494.250574942462;7962.323202527618;8079.192080792109;8088.029436467662;8093.31206847401;8095.473794203358;6964.895526567207;7067.434605231672;7164.355208031302;7186.928130718626;7279.526830755984;6932;7066.964665176642;7070.858582828212;7156.105486814148;7187.820304492429;6575.901361479678;6587.670616469253;6677.165354330708;6685.2646208916;6697.832554186184;5083.822066227664;5125.282460455464;5143.974280128576;5169.198774190007;5220.347456567929;4976.60655016596;5113.233015047773;5172.9741351293005;5203.765830537559;5267.920981185362 07:49:39 INFO - PROCESS | 1639 | |4;prototype.html;371.29639333463086;374.59169505239294;383.4612369620683;385.1507426125394;387.45175576559154;4604.286335617976;4803.879903002422;5034.874128146798;5408.2698835657175;5549.2231087647715;4245.851395201161;4404.559544045594;4743.359646447729;5265.710385928773;5449.6375906023495;3574.963260654407;4095.215253627532;4272.479237250014;4312.116016216683;4328.437303150586;3916.236333914888;3940.113474468236;4319.904846149615;4322.481302243735;4328.199283132615;4065.715399922007;4095.856645017425;4100.692448066392;4105.67154627629;4131.252716943229;3255.5930508686415;3578.944211457613;4130.938035929464;4159.272127377727;4159.396887451303;3606.637652337723;3620.9094772630633;3623.3477973964677;3632.237230181665;3642.471841582982;5066.40408271397;5232.790688372498;5329;5360.678359298455;5392.487713667196;3606.062423769839;3803.7717736935874;4332.588404101605;4520.773961301955;4739.289106633998;4859.58693511052;5527.695976721278;5742.100239379087;5750.108733146369;5816.76054718932;3471.316411540401;3792.848286068554;3848.1918797052654;3970.8808735737975;4002.8598999035185;5598.608097433182;5699.629524080921;6075.210222671069;6083.057126145454;6129.016139509355;3424.9315013700084;3997.960020399817;4017.192263481445;4022.456968309299;4026.697997650188;3796.4684944107294;4012;4031.3952907064177;4073.0835561998906;4079.5920407958965;4709.293605959134;4725.527447255316;4742.762861856893;4749.667523273338;4752.524747525289;5818.2436283282905;5826.572489739956;5843.561732870052;5855.795047173328;5884.029135192645;1152.2452793420323;1154.2209008918946;1154.6362895687832;1269.9428525716364;1332.566915752365;776.4952780692482;847.2882778466118;882.4881568690145;897.9942464439869;901.454619954931;5298.258243845788;5301.363836339664;5327.520523152934;5340.385855626652;5341.706206158621;4745.976270118627;4869.440014398318;5198.454162312978;5729.197912292282;5798;4880.486549427048;4900.705957642553;5011.624128190328;5234.555062819703;5273.2617433559335;2727.59086137081;2835.319523314431;2867.34770337437;2977.419403216352;3005.7144571265694;4958.975205124024;5574.9721251393485;5986.700664966735;6010.158577799111;6082;5099.5410413063;5155.6391052626705;5580.911722214129;5741.024025915604;5956.54064754138;5371.919421208685;5900.085486749561;5918.704064796743;5999.910001349983;6026.337102918676;4539.5006549280215;4956.851294461172;5470.562355011669;5558.833235002957;5682.403347648437;4770.761461926821;5082.872928176826;5090.949090509195;5376.650517716336;5464.644798088111;5380.43505431924;5913.5564832636865;5942.940570594239;5971.701414929323;6051.667158306335;3357;3377.2137986161024;3627.6553727395853;3839.3856982882603;3848.5189351331082;5011.9976004798455;5642.50473624498;5757.992351338583;5759.424057594207;5772.305724336344;4535.591796738309;4975.303457515878;5088.829569199179;5411.431799660979;5639.492445679908;5073.188289873602;5369.946300537101;5490.80782172622;5692.402297758676;5734.426557344399;4802.495737947465;4980.950190498049;5203.10086818301;5509.393966663583;5636.182753500638;4888.535589119028;5139.6145289104215;5582.86042848932;5829.941700583109;5847.912281315868;4842.95157048425;5383.380911195106;5767.884642307046;5790.160426738184;5796.362400136066 07:49:39 INFO - PROCESS | 1639 | |5;yui.html;737.8819388897774;747.5925620536807;794.6821271491402;836.8117173635933;866.2982983783135;15214.54356369308;15851.365945362186;16771.580710482245;17006.14969251537;17077.40229091979;15242.466513672023;15363.923180384083;15438.305276262567;15441.073535587873;15467;891.5497673674802;908;910.5765818894213;910.6038873090226;912.6805618033668;715.5241764226782;739.4121673269764;867.2498288980019;898.5148020069188;910.0671811393308;962.2542529539593;962.711186644004;966.917811985982;966.9903300967006;968.5786682792973;941.5010044676333;956.3926906414476;956.6412595276771;958.8513780363985;960.4285450157146;700.8633316503284;709.6061685764398;711.6761873347615;717.6304203335286;720.6144712578745;15576.532704018897;15951.76072358904;16042.679146417006;16466.506004819876;16710.58223544421;13863.37614807326;14599.051061680957;14912.850871491255;15239.23803809805;15290.541283761506;11926.761464770656;11952.402379881058;11962.641120766304;11969.162158648898;12011.57959471423;3775.2826962876966;3909.863154789569;3927.5483319418345;4041.474608300902;4048.5344185418758;97737.51131244298;97805.02194978102;98404.50797746037;98670.5066474663;102299.48850255775;9621.422714637145;11471.4264286787;11521.366324852213;11833.644990650295;12218;24325.51348973046;24759.87620061888;24905.252842414757;25066.498670026864;25198.992040318182;8077.555734434663;8098.068722096917;8113.8782918256275;8180.181981801772;8387.454815436977;4341.261985462479;4342.525979216677;4437.42313499243;4471.709338892962;4486.641068714495;3690.612485689018;3745.3445647011663;3777.2823163598828;3783.4135708965155;3814.9427758583643;676.1379241467168;682.6586706646676;684.4387602166175;688.683205725362;690.9827254318682;690.1683471417031;690.7444245629049;703.4477934821243;706.3678008182648;742.8514297140593;10463.758570102704;10595.576176952989;10674.893251067546;11127.833082503768;11299.661010169708;685.2804555217002;703.1622437629079;732.4689600040035;741.068191187647;746.4700062955254;3849.364854798927;4169.936666150173;4171.499420069611;4261.70168088237;4285.61429471349;107309;110615.44692276487;112044.31933521004;113054;113789.43105284586;90383.54808225916;97392.51303743436;99414;100826;102004.48997754963;80651.58045258651;81595.5920220407;81840.59079704563;83451.5827420859;87307.25385492254;7911.327537159175;7964.721234756756;7989.640466179152;8068.435209535276;8151.225633565039;45944.31083533683;54145;55486.72256638691;55571;55878.72060639671;66933.66533167497;72232.63883680548;73629;73913.6304318475;74090;11307.43462826839;11412.372319522507;11731.941340293244;12425.875741242833;13854.099483533539;13791.896648268315;14248.928755356157;15117.09297442157;15238;15859.969102008794;13031.348432578523;13907.165570065828;14497.85502144965;15072.910053439238;15223.086614803147;14041.578752637437;14050.297485125908;14460.349284282443;14464.927675361556;14714.926425367805;11712.472938717608;13644.727105458034;13870.306484675524;13958.860411396163;14152.22162781057;9676.322657413913;10172.949135254275;10255.94872025635;10356.067953884187;10826;10573.110300341928;11340.546378144782;11680.47437865281;11728.70678233051;11896.22674526153 07:49:39 INFO - PROCESS | 1639 | __end_tp_report 07:49:39 INFO - PROCESS | 1639 | __start_cc_report 07:49:39 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,372 07:49:39 INFO - PROCESS | 1639 | __end_cc_report 07:49:39 INFO - PROCESS | 1639 | __startTimestamp1490626179643__endTimestamp 07:49:39 INFO - PROCESS | 1639 | 07:49:39 INFO - PROCESS | 1639 | ------- Summary: start ------- 07:49:39 INFO - PROCESS | 1639 | Number of tests: 6 07:49:39 INFO - PROCESS | 1639 | 07:49:39 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5772.67 Median:5977.43 stddev:664.35 (11.1%) stddev-sans-first:641.23 07:49:39 INFO - PROCESS | 1639 | Values: 3464.4 3465.3 3470.7 3491.8 3499.3 5680.2 5744.4 5768.8 5780.8 5786.6 5743.7 5743.8 5830.6 5852.2 5856.3 5176.1 5255.6 5262.9 5304.7 5314.1 4262.9 4357.5 5195.9 5302.9 5348.7 5164.6 5202.1 5288.1 5289.5 5294.0 5231.6 5235.2 5278.0 5291.6 5356.0 5130.2 5216.2 5233.3 5244.9 5303.2 4952.7 5561.7 5794.9 5921.2 5970.7 5797.5 5963.3 6171.3 6188.9 6256.6 6309.7 6332.2 6339.7 6348.8 6371.0 5848.8 5873.9 5881.1 5884.1 5914.3 6252.0 6260.6 6262.0 6263.2 6281.8 6179.4 6218.0 6240.2 6247.8 6257.7 6300.6 6319.2 6329.3 6374.3 6381.5 6118.3 6189.1 6195.2 6228.1 6228.3 6276.8 6280.6 6291.7 6311.3 6348.3 4229.5 4992.1 5003.2 5015.8 5018.4 4366.5 4407.8 4419.3 4434.5 4456.8 5944.4 5986.2 6019.3 6031.8 6060.9 6273.8 6306.4 6307.4 6327.0 6332.2 5806.4 5836.2 5947.2 6000.0 6049.7 4795.8 5387.8 5436.2 5485.9 5501.2 6310.7 6335.7 6343.5 6355.8 6388.0 6228.0 6252.5 6285.4 6295.5 6302.3 6322.0 6323.8 6326.7 6330.9 6359.6 6256.6 6283.2 6293.1 6297.0 6301.7 6131.3 6194.3 6274.4 6360.2 6401.9 5907.8 5971.9 5997.2 6012.5 6033.4 5943.9 5945.3 5964.2 5972.1 5975.5 5409.3 5496.1 5834.9 5863.2 5977.4 6149.3 6166.3 6215.9 6231.7 6232.9 6038.7 6083.9 6086.5 6089.4 6121.3 07:49:39 INFO - PROCESS | 1639 | 07:49:39 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:70907.69 Median:3565.00 stddev:155655.61 (4366.2%) stddev-sans-first:156002.39 07:49:39 INFO - PROCESS | 1639 | Values: 458.7 481.7 510.8 521.6 548.2 36376.3 43239.9 43385.6 43391.3 43425.8 44254.0 44739.0 44803.0 44823.3 45000.0 8729.0 9525.5 9807.5 11460.3 11848.3 9599.6 11701.0 11706.8 11711.6 11750.6 11797.3 11827.6 11837.5 11851.8 11860.6 11289.9 11796.1 11833.9 11843.2 11843.5 11660.4 11751.6 11755.8 11756.8 11768.8 38761.0 39291.8 39305.6 39372.0 39436.8 3588.9 4043.2 4075.3 4084.4 4119.9 28551.0 28577.0 28635.9 29752.1 30353.0 3253.1 3527.1 3539.4 3540.6 3541.1 423994.0 428040.0 428481.9 429158.0 430560.0 53045.7 56986.7 57567.4 57736.4 57890.0 55232.4 58902.8 59934.1 60334.7 60485.0 16935.9 17152.5 17176.1 17179.3 17235.7 12083.3 12324.2 12888.1 13136.5 13148.5 18867.2 18903.0 18905.2 18935.2 19108.0 8087.6 8222.0 8288.5 8330.8 8332.1 838.6 896.5 905.3 911.7 948.7 31481.6 32036.5 32064.0 32318.0 32801.0 871.7 1108.9 1136.0 1149.8 1158.8 16424.9 16540.3 16542.2 16579.3 16812.8 671833.0 683977.0 684835.6 688033.0 689866.0 65467.0 65719.7 66251.0 66331.0 66493.7 1490.5 1504.9 1515.6 1530.6 1532.4 29779.3 30936.4 31203.0 31678.5 31856.5 53699.0 53983.0 55739.4 56560.0 58757.4 575492.0 607315.0 610738.0 612245.0 614127.0 35023.0 38638.6 42008.6 42125.2 42677.8 44749.6 44826.8 45009.3 45880.0 46085.4 31198.0 31697.0 31923.7 32016.0 32646.5 26982.5 30473.7 31255.4 31533.8 32182.6 36664.3 36689.0 36857.1 37324.6 37701.4 26226.7 30372.5 30735.5 30758.1 31454.2 23257.9 23576.1 25156.4 25220.7 25225.7 07:49:39 INFO - PROCESS | 1639 | 07:49:39 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:41508.59 Median:22457.71 stddev:127080.17 (565.9%) stddev-sans-first:127399.01 07:49:39 INFO - PROCESS | 1639 | Values: 309.9 391.9 401.8 420.3 432.5 20190.6 21922.5 22190.0 22465.7 22681.3 21917.0 22399.0 22449.8 22525.0 22677.7 5825.7 6129.2 6136.7 6141.8 6153.4 4409.4 4599.1 6067.6 6623.2 6930.9 6706.3 6986.9 6989.5 6992.3 6994.3 6734.0 6844.5 6845.8 6866.7 6868.5 5955.1 5999.9 6003.4 6063.0 6075.0 26993.7 27044.3 27254.2 27278.9 27723.4 2840.6 2948.0 2954.2 2957.0 2958.4 12883.2 16329.0 16452.7 16879.5 17005.0 1898.9 1932.0 1951.6 1952.5 1952.8 128148.4 128215.4 128824.0 133172.0 133640.0 9946.9 10985.5 11110.2 11881.5 12076.4 15101.5 15217.4 15530.2 15542.8 15544.9 9074.3 9306.6 9320.1 9429.5 9531.3 9123.5 9247.1 9304.3 9442.8 9456.7 7614.3 7709.1 7738.3 7777.6 7848.6 1561.8 1750.8 1825.8 1836.9 2040.1 1007.6 1024.3 1029.9 1037.2 1040.1 18039.1 21567.2 21836.9 21972.0 22164.0 865.3 909.1 1070.5 1088.8 1112.3 11423.5 11493.7 11711.0 11722.1 11756.8 732051.0 760830.0 763947.0 782528.0 792914.0 116726.8 119459.4 143271.3 144473.0 150192.0 1058.6 1072.6 1109.1 1109.5 1109.6 17109.9 20418.0 20832.0 21004.1 21245.0 53778.0 61007.0 61029.0 61959.7 62048.7 87934.0 91674.5 95073.5 95499.0 96039.0 8405.6 8421.0 8675.8 8710.7 8735.5 11555.8 13052.2 13075.9 13228.7 13459.9 11339.1 11359.0 11522.5 11651.9 11721.8 9040.7 10672.7 10805.4 10967.6 11057.9 10078.7 11691.6 11872.9 11878.2 12018.9 8255.8 8451.5 8464.6 8714.1 8837.3 8522.5 10498.2 11411.9 11787.6 11845.5 07:49:39 INFO - PROCESS | 1639 | 07:49:39 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5591.14 Median:2937.84 stddev:3054.29 (104.0%) stddev-sans-first:3046.64 07:49:39 INFO - PROCESS | 1639 | Values: 1403.6 1488.1 1491.3 1504.2 1505.6 7791.3 8020.6 8290.5 8722.7 8732.3 8229.3 8528.1 8546.6 8632.6 8640.0 2060.7 2407.5 2412.9 2421.4 2440.8 2380.6 2382.9 2411.6 2416.4 2426.3 2375.4 2386.1 2396.7 2409.3 2411.8 2363.5 2376.0 2382.6 2403.8 2410.4 2388.1 2391.0 2412.9 2420.3 2429.9 8672.7 8705.5 8727.0 8734.8 8744.1 5258.4 5411.7 5425.0 5448.8 5469.9 3613.9 3667.3 3684.8 3719.6 3730.7 1259.3 1262.9 1301.5 1309.8 1323.4 11086.3 11233.2 11250.3 11266.9 11281.7 4743.2 4786.2 4864.3 4871.0 4911.4 9679.3 9689.2 9700.1 9700.5 9712.4 5343.8 5436.9 5441.6 5458.2 5459.1 4483.5 4723.7 4954.2 4996.1 5000.7 3786.0 3812.8 4035.7 4080.7 4156.1 1326.7 1332.2 1332.4 1335.5 1341.2 790.5 889.1 906.1 921.2 924.8 5006.4 5365.5 5388.3 5646.5 5773.9 531.9 542.2 545.0 550.1 551.6 3550.6 3571.3 3632.1 3668.6 3683.0 10013.9 10273.5 10356.9 10362.5 10379.3 9711.4 9915.4 10195.6 10247.5 10295.1 8014.7 8027.2 8202.7 8210.3 8235.3 5913.1 5987.7 5995.4 6023.4 6154.5 9319.2 9433.4 9434.9 9443.2 9606.0 9605.9 9624.1 9640.7 9709.1 9812.4 6905.4 7148.7 7404.1 7444.4 7494.3 7962.3 8079.2 8088.0 8093.3 8095.5 6964.9 7067.4 7164.4 7186.9 7279.5 6932.0 7067.0 7070.9 7156.1 7187.8 6575.9 6587.7 6677.2 6685.3 6697.8 5083.8 5125.3 5144.0 5169.2 5220.3 4976.6 5113.2 5173.0 5203.8 5267.9 07:49:39 INFO - PROCESS | 1639 | 07:49:39 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4536.28 Median:5011.81 stddev:1393.58 (27.8%) stddev-sans-first:1361.97 07:49:39 INFO - PROCESS | 1639 | Values: 371.3 374.6 383.5 385.2 387.5 4604.3 4803.9 5034.9 5408.3 5549.2 4245.9 4404.6 4743.4 5265.7 5449.6 3575.0 4095.2 4272.5 4312.1 4328.4 3916.2 3940.1 4319.9 4322.5 4328.2 4065.7 4095.9 4100.7 4105.7 4131.3 3255.6 3578.9 4130.9 4159.3 4159.4 3606.6 3620.9 3623.3 3632.2 3642.5 5066.4 5232.8 5329.0 5360.7 5392.5 3606.1 3803.8 4332.6 4520.8 4739.3 4859.6 5527.7 5742.1 5750.1 5816.8 3471.3 3792.8 3848.2 3970.9 4002.9 5598.6 5699.6 6075.2 6083.1 6129.0 3424.9 3998.0 4017.2 4022.5 4026.7 3796.5 4012.0 4031.4 4073.1 4079.6 4709.3 4725.5 4742.8 4749.7 4752.5 5818.2 5826.6 5843.6 5855.8 5884.0 1152.2 1154.2 1154.6 1269.9 1332.6 776.5 847.3 882.5 898.0 901.5 5298.3 5301.4 5327.5 5340.4 5341.7 4746.0 4869.4 5198.5 5729.2 5798.0 4880.5 4900.7 5011.6 5234.6 5273.3 2727.6 2835.3 2867.3 2977.4 3005.7 4959.0 5575.0 5986.7 6010.2 6082.0 5099.5 5155.6 5580.9 5741.0 5956.5 5371.9 5900.1 5918.7 5999.9 6026.3 4539.5 4956.9 5470.6 5558.8 5682.4 4770.8 5082.9 5090.9 5376.7 5464.6 5380.4 5913.6 5942.9 5971.7 6051.7 3357.0 3377.2 3627.7 3839.4 3848.5 5012.0 5642.5 5758.0 5759.4 5772.3 4535.6 4975.3 5088.8 5411.4 5639.5 5073.2 5369.9 5490.8 5692.4 5734.4 4802.5 4981.0 5203.1 5509.4 5636.2 4888.5 5139.6 5582.9 5829.9 5847.9 4843.0 5383.4 5767.9 5790.2 5796.4 07:49:39 INFO - PROCESS | 1639 | 07:49:39 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:21365.55 Median:4045.00 stddev:30709.86 (759.2%) stddev-sans-first:30756.95 07:49:39 INFO - PROCESS | 1639 | Values: 737.9 747.6 794.7 836.8 866.3 15214.5 15851.4 16771.6 17006.1 17077.4 15242.5 15363.9 15438.3 15441.1 15467.0 891.5 908.0 910.6 910.6 912.7 715.5 739.4 867.2 898.5 910.1 962.3 962.7 966.9 967.0 968.6 941.5 956.4 956.6 958.9 960.4 700.9 709.6 711.7 717.6 720.6 15576.5 15951.8 16042.7 16466.5 16710.6 13863.4 14599.1 14912.9 15239.2 15290.5 11926.8 11952.4 11962.6 11969.2 12011.6 3775.3 3909.9 3927.5 4041.5 4048.5 97737.5 97805.0 98404.5 98670.5 102299.5 9621.4 11471.4 11521.4 11833.6 12218.0 24325.5 24759.9 24905.3 25066.5 25199.0 8077.6 8098.1 8113.9 8180.2 8387.5 4341.3 4342.5 4437.4 4471.7 4486.6 3690.6 3745.3 3777.3 3783.4 3814.9 676.1 682.7 684.4 688.7 691.0 690.2 690.7 703.4 706.4 742.9 10463.8 10595.6 10674.9 11127.8 11299.7 685.3 703.2 732.5 741.1 746.5 3849.4 4169.9 4171.5 4261.7 4285.6 107309.0 110615.4 112044.3 113054.0 113789.4 90383.5 97392.5 99414.0 100826.0 102004.5 80651.6 81595.6 81840.6 83451.6 87307.3 7911.3 7964.7 7989.6 8068.4 8151.2 45944.3 54145.0 55486.7 55571.0 55878.7 66933.7 72232.6 73629.0 73913.6 74090.0 11307.4 11412.4 11731.9 12425.9 13854.1 13791.9 14248.9 15117.1 15238.0 15860.0 13031.3 13907.2 14497.9 15072.9 15223.1 14041.6 14050.3 14460.3 14464.9 14714.9 11712.5 13644.7 13870.3 13958.9 14152.2 9676.3 10172.9 10255.9 10356.1 10826.0 10573.1 11340.5 11680.5 11728.7 11896.2 07:49:39 INFO - PROCESS | 1639 | -------- Summary: end -------- 07:49:39 INFO - PROCESS | 1639 | 07:49:40 INFO - TEST-INFO | 1639: exit 0 07:49:40 INFO - TEST-OK | dromaeo_css | took 1114438ms 07:49:40 INFO - TEST-START | kraken 07:49:40 INFO - operating with platform_type : mac_ 07:49:40 INFO - Initialising browser for kraken test... 07:49:40 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpBp7NdA/profile http://localhost:49243/getInfo.html) 07:49:42 INFO - PROCESS | 1651 | 2017-03-27 07:49:42.827 plugin-container[1653:9968] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 07:49:42 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:49:42 INFO - PROCESS | 1651 | 2017-03-27 07:49:42.828 plugin-container[1653:9968] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 07:49:42 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:49:42 INFO - PROCESS | 1651 | 2017-03-27 07:49:42.828 plugin-container[1653:9968] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:49:42 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 07:49:42 INFO - PROCESS | 1651 | colorDepth:24 07:49:42 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 07:49:42 INFO - PROCESS | 1651 | __metrics 07:49:43 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 07:49:43 INFO - TEST-INFO | 1651: exit 0 07:49:43 INFO - Browser initialized. 07:49:43 INFO - Running cycle 1/1 for kraken test... 07:49:43 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpBp7NdA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:49:45 INFO - PROCESS | 1654 | 2017-03-27 07:49:45.924 plugin-container[1655:10090] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833b, name = 'com.apple.tsm.portname' 07:49:45 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:49:45 INFO - PROCESS | 1654 | 2017-03-27 07:49:45.924 plugin-container[1655:10090] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461f, name = 'com.apple.CFPasteboardClient' 07:49:45 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:49:45 INFO - PROCESS | 1654 | 2017-03-27 07:49:45.924 plugin-container[1655:10090] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:49:55 INFO - PROCESS | 1654 | RSS: Main: 173146112 07:49:55 INFO - PROCESS | 1654 | 07:49:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 07:49:59 INFO - PROCESS | 1654 | RSS: Main: 185905152 07:49:59 INFO - PROCESS | 1654 | 07:50:01 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 07:50:02 INFO - PROCESS | 1654 | RSS: Main: 193687552 07:50:02 INFO - PROCESS | 1654 | 07:50:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 07:50:06 INFO - PROCESS | 1654 | RSS: Main: 197468160 07:50:06 INFO - PROCESS | 1654 | 07:50:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 07:50:09 INFO - PROCESS | 1654 | RSS: Main: 198975488 07:50:09 INFO - PROCESS | 1654 | 07:50:10 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:50:11 INFO - PROCESS | 1654 | RSS: Main: 198750208 07:50:11 INFO - PROCESS | 1654 | 07:50:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 07:50:15 INFO - PROCESS | 1654 | RSS: Main: 203202560 07:50:15 INFO - PROCESS | 1654 | 07:50:18 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 07:50:18 INFO - PROCESS | 1654 | RSS: Main: 203509760 07:50:18 INFO - PROCESS | 1654 | 07:50:21 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 07:50:22 INFO - PROCESS | 1654 | RSS: Main: 203034624 07:50:22 INFO - PROCESS | 1654 | 07:50:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:50:24 INFO - PROCESS | 1654 | RSS: Main: 201850880 07:50:24 INFO - PROCESS | 1654 | 07:50:26 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:50:27 INFO - PROCESS | 1654 | RSS: Main: 201854976 07:50:27 INFO - PROCESS | 1654 | 07:50:29 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:50:30 INFO - PROCESS | 1654 | RSS: Main: 201781248 07:50:30 INFO - PROCESS | 1654 | 07:50:32 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:50:33 INFO - PROCESS | 1654 | RSS: Main: 201834496 07:50:33 INFO - PROCESS | 1654 | 07:50:36 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:50:37 INFO - PROCESS | 1654 | RSS: Main: 202207232 07:50:37 INFO - PROCESS | 1654 | 07:50:38 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 07:50:39 INFO - PROCESS | 1654 | RSS: Main: 203927552 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | __start_tp_report 07:50:39 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 07:50:39 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 07:50:39 INFO - PROCESS | 1654 | |i|pagename|runs| 07:50:39 INFO - PROCESS | 1654 | |0;ai-astar;105;121;117;117;115;116;114;110;118;117 07:50:39 INFO - PROCESS | 1654 | |1;audio-beat-detection;143;107;113;142;112;112;110;111;130;113 07:50:39 INFO - PROCESS | 1654 | |2;audio-dft;195;198;200;200;197;227;221;196;199;187 07:50:39 INFO - PROCESS | 1654 | |3;audio-fft;82;60;59;87;57;61;57;67;97;80 07:50:39 INFO - PROCESS | 1654 | |4;audio-oscillator;84;72;83;71;70;70;80;54;74;114 07:50:39 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;92;122;90;89;89;86;89;86;89;107 07:50:39 INFO - PROCESS | 1654 | |6;imaging-darkroom;96;95;95;106;126;146;96;95;96;137 07:50:39 INFO - PROCESS | 1654 | |7;imaging-desaturate;126;93;145;90;142;142;91;92;111;120 07:50:39 INFO - PROCESS | 1654 | |8;json-parse-financial;58;58;58;58;56;66;85;97;94;97 07:50:39 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;68;75;70;80;64;67;87;73;70;69 07:50:39 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;84;85;82;80;86;83;82;84;86;104 07:50:39 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;134;145;165;150;155;130;131;142;179;136 07:50:39 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;167;169;168;165;169;169;228;177;167;164 07:50:39 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;67;64;64;65;65;65;63;65;66;64 07:50:39 INFO - PROCESS | 1654 | __end_tp_report 07:50:39 INFO - PROCESS | 1654 | __start_cc_report 07:50:39 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,923 07:50:39 INFO - PROCESS | 1654 | __end_cc_report 07:50:39 INFO - PROCESS | 1654 | __startTimestamp1490626239145__endTimestamp 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | ------- Summary: start ------- 07:50:39 INFO - PROCESS | 1654 | Number of tests: 14 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:115.00 Median:117.00 stddev:4.52 (3.9%) stddev-sans-first:3.02 07:50:39 INFO - PROCESS | 1654 | Values: 105.0 121.0 117.0 117.0 115.0 116.0 114.0 110.0 118.0 117.0 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:119.30 Median:113.00 stddev:13.68 (12.1%) stddev-sans-first:11.51 07:50:39 INFO - PROCESS | 1654 | Values: 143.0 107.0 113.0 142.0 112.0 112.0 110.0 111.0 130.0 113.0 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:202.00 Median:199.50 stddev:12.27 (6.1%) stddev-sans-first:12.75 07:50:39 INFO - PROCESS | 1654 | Values: 195.0 198.0 200.0 200.0 197.0 227.0 221.0 196.0 199.0 187.0 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:70.70 Median:73.50 stddev:14.55 (19.8%) stddev-sans-first:14.85 07:50:39 INFO - PROCESS | 1654 | Values: 82.0 60.0 59.0 87.0 57.0 61.0 57.0 67.0 97.0 80.0 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:73.00 stddev:15.49 (21.2%) stddev-sans-first:16.23 07:50:39 INFO - PROCESS | 1654 | Values: 84.0 72.0 83.0 71.0 70.0 70.0 80.0 54.0 74.0 114.0 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:93.90 Median:89.00 stddev:11.55 (13.0%) stddev-sans-first:12.23 07:50:39 INFO - PROCESS | 1654 | Values: 92.0 122.0 90.0 89.0 89.0 86.0 89.0 86.0 89.0 107.0 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:108.80 Median:95.00 stddev:19.85 (20.9%) stddev-sans-first:20.50 07:50:39 INFO - PROCESS | 1654 | Values: 96.0 95.0 95.0 106.0 126.0 146.0 96.0 95.0 96.0 137.0 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:115.20 Median:117.50 stddev:22.93 (19.5%) stddev-sans-first:23.99 07:50:39 INFO - PROCESS | 1654 | Values: 126.0 93.0 145.0 90.0 142.0 142.0 91.0 92.0 111.0 120.0 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:72.70 Median:75.50 stddev:18.18 (24.1%) stddev-sans-first:18.49 07:50:39 INFO - PROCESS | 1654 | Values: 58.0 58.0 58.0 58.0 56.0 66.0 85.0 97.0 94.0 97.0 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:71.50 stddev:6.83 (9.6%) stddev-sans-first:7.07 07:50:39 INFO - PROCESS | 1654 | Values: 68.0 75.0 70.0 80.0 64.0 67.0 87.0 73.0 70.0 69.0 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:84.00 stddev:6.74 (8.0%) stddev-sans-first:7.12 07:50:39 INFO - PROCESS | 1654 | Values: 84.0 85.0 82.0 80.0 86.0 83.0 82.0 84.0 86.0 104.0 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:147.50 stddev:15.93 (10.8%) stddev-sans-first:16.22 07:50:39 INFO - PROCESS | 1654 | Values: 134.0 145.0 165.0 150.0 155.0 130.0 131.0 142.0 179.0 136.0 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.30 Median:169.00 stddev:19.19 (11.4%) stddev-sans-first:20.17 07:50:39 INFO - PROCESS | 1654 | Values: 167.0 169.0 168.0 165.0 169.0 169.0 228.0 177.0 167.0 164.0 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.00 stddev:1.14 (1.7%) stddev-sans-first:0.88 07:50:39 INFO - PROCESS | 1654 | Values: 67.0 64.0 64.0 65.0 65.0 65.0 63.0 65.0 66.0 64.0 07:50:39 INFO - PROCESS | 1654 | -------- Summary: end -------- 07:50:39 INFO - PROCESS | 1654 | 07:50:39 INFO - TEST-INFO | 1654: exit 0 07:50:39 INFO - TEST-OK | kraken | took 59752ms 07:50:39 INFO - SUITE-END | took 1174s 07:50:40 INFO - Completed test suite (00:19:34) 07:50:40 INFO - javascript benchmark 07:50:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7504.874217505776, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3464.3590935676884, 3465.272292818508, 3470.7049900758434, 3491.7555771096036, 3499.2826470573536, 5680.233168522253, 5744.396838331978, 5768.769249230026, 5780.7687692492245, 5786.594938354317, 5743.741531631076, 5743.798967036155, 5830.591858569902, 5852.209951656525, 5856.267966504176, 5176.0683077046215, 5255.605829562779, 5262.9473705263035, 5304.708241046741, 5314.096603577382, 4262.9147417051645, 4357.477102747659, 5195.922061169085, 5302.893942121155, 5348.679079255257, 5164.612654050942, 5202.08963431398, 5288.127458969266, 5289.471052894699, 5293.9735301323435, 5231.633785635007, 5235.214717792323, 5278.0, 5291.629585928987, 5356.0, 5130.204818253177, 5216.191490319006, 5233.319668443116, 5244.895102097975, 5303.23103150045, 4952.702837829742, 5561.6663000220115, 5794.855128621776, 5921.200637913869, 5970.701464926737, 5797.478226959552, 5963.2844058713235, 6171.321154672983, 6188.938110618881, 6256.562040657156, 6309.684515774193, 6332.240131184288, 6339.746410143589, 6348.809535713936, 6370.9681451592905, 5848.795292164754, 5873.942690315785, 5881.1490752988575, 5884.08796636522, 5914.319853216849, 6252.030935205051, 6260.593061451083, 6262.0, 6263.248410190807, 6281.842953926189, 6179.351168127372, 6217.9689101555105, 6240.157578726905, 6247.75008999644, 6257.7184026718905, 6300.59046161998, 6319.210098737658, 6329.335419780857, 6374.298827129012, 6381.521385895983, 6118.345080237303, 6189.071639254148, 6195.1636529068, 6228.065790131516, 6228.252609686776, 6276.843078923063, 6280.623162610258, 6291.748330066746, 6311.27420346666, 6348.333424990402, 4229.492460904711, 4992.076465853828, 5003.174476211457, 5015.799368025238, 5018.372703412074, 4366.541513141138, 4407.7796110195, 4419.337099435046, 4434.467863856358, 4456.75487848165, 5944.375840536768, 5986.221791167208, 6019.277686677627, 6031.849203769941, 6060.878782424327, 6273.780417685359, 6306.400891915261, 6307.369263073656, 6327.0, 6332.176817013758, 5806.361300256968, 5836.241288632451, 5947.197128387699, 5999.970000149971, 6049.667268300285, 4795.784189711471, 5387.81142660005, 5436.21174929633, 5485.862853428696, 5501.229827824108, 6310.652914089677, 6335.651539165206, 6343.492520598341, 6355.809325720235, 6387.968060159669, 6227.96886015567, 6252.531060170414, 6285.434310911857, 6295.464885484785, 6302.338254483213, 6322.0, 6323.841903952438, 6326.746930122928, 6330.873382532415, 6359.618422894641, 6256.624602523863, 6283.183186185766, 6293.118963345044, 6296.968515157578, 6301.6534090625455, 6131.284916201153, 6194.287656919512, 6274.435300822948, 6360.236771587439, 6401.8719625608155, 5907.793227237026, 5971.910421343597, 5997.220361352996, 6012.549058820693, 6033.42682445167, 5943.940560594338, 5945.316288626863, 5964.165016897551, 5972.074328479092, 5975.462208401264, 5409.323834520685, 5496.131820049156, 5834.94165058344, 5863.237779088862, 5977.432143946318, 6149.2620885494025, 6166.290876549254, 6215.9378406217165, 6231.719572619152, 6232.937670623236, 6038.69806509664, 6083.939160608337, 6086.543509236736, 6089.421504957022, 6121.326654068138], "value": 5730.202821201969, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.68580022684444, 481.65080316770343, 510.7956817273093, 521.5749164430987, 548.2215254338838, 36376.2724745505, 43239.91900202492, 43385.56614433855, 43391.349129763075, 43425.78287108568, 44254.0, 44739.0, 44803.0, 44823.32765008527, 45000.0, 8729.0, 9525.5237238138, 9807.460589667566, 11460.312381257152, 11848.34834084125, 9599.616015359377, 11701.005414539773, 11706.7658646827, 11711.648650540455, 11750.588729394472, 11797.29216247028, 11827.645170644895, 11837.526498940031, 11851.822222666668, 11860.644180674593, 11289.943550282278, 11796.05631549474, 11833.94083029588, 11843.170978031498, 11843.526258949718, 11660.416979151008, 11751.647450576496, 11755.764884702257, 11756.764864702744, 11768.823467647991, 38761.0, 39291.8035409824, 39305.60694393048, 39372.0, 39436.802815985735, 3588.8923332300074, 4043.191361727637, 4075.32757095079, 4084.4485994390675, 4119.8970025749595, 28551.0, 28577.0, 28635.856820715762, 29752.107436776932, 30353.0, 3253.1216571525792, 3527.0829584308008, 3539.4159963605975, 3540.6105328413855, 3541.11472131967, 423993.9999999969, 428040.0, 428481.8575907132, 429158.0, 430560.0, 53045.73477132667, 56986.71506642523, 57567.424325756205, 57736.422635773946, 57890.0, 55232.44767552354, 58902.82194356089, 59934.10098848521, 60334.698326508966, 60485.0, 16935.915320423566, 17152.48542543726, 17176.055316957438, 17179.31282748701, 17235.74146387805, 12083.275003499819, 12324.198927069712, 12888.097833151589, 13136.474541018251, 13148.539801106916, 18867.245310187714, 18902.960337181314, 18905.24379024851, 18935.242590296508, 19108.0, 8087.555184464911, 8221.958890205511, 8288.544130072783, 8330.8333833323, 8332.083470818205, 838.6268110690684, 896.4755617963434, 905.2712566383992, 911.6581282019242, 948.7343543807744, 31481.583328749803, 32036.519452208235, 32064.0, 32318.030459085796, 32801.01596952095, 871.6992637540038, 1108.8558487396588, 1135.9716007099723, 1149.8160294352863, 1158.8145896656495, 16424.917875410545, 16540.338386464406, 16542.172891355385, 16579.336826527044, 16812.831871681126, 671833.0, 683977.0, 684835.5758221177, 688033.0, 689866.0, 65467.017994730115, 65719.67140164365, 66251.0, 66331.0, 66493.66753166204, 1490.5155824357128, 1504.9322780474904, 1515.613518552762, 1530.556138719781, 1532.4023630784004, 29779.255518612208, 30936.381272373976, 31203.0, 31678.524822128147, 31856.5221521682, 53699.0, 53983.0, 55739.44260557342, 56559.999999998356, 58757.4124258769, 575492.0, 607315.0, 610738.0, 612245.0, 614127.0, 35023.0, 38638.613613863505, 42008.57991420047, 42125.1574968505, 42677.78661106799, 44749.55250447584, 44826.77586612177, 45009.32486012647, 45880.0, 46085.38701145444, 31198.0, 31697.0, 31923.68076319207, 32016.0, 32646.51030234596, 26982.46035079248, 30473.695263047084, 31255.374892502477, 31533.8423307882, 32182.551785169257, 36664.26671466609, 36689.0, 36857.078573034814, 37324.62675373212, 37701.4344784823, 26226.737732623194, 30372.544411833398, 30735.538966915068, 30758.077257682304, 31454.213644659067, 23257.88371058134, 23576.0569577215, 25156.371090722878, 25220.74779252184, 25225.747742523075], "value": 20725.8867294411, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.9116751725759, 391.9392494163405, 401.78504500092436, 420.3148867346225, 432.48750230976293, 20190.59618807623, 21922.45193870154, 22190.0, 22465.663015054786, 22681.319560413194, 21917.01373438195, 22399.0, 22449.765262910532, 22525.0, 22677.659835102488, 5825.708714564276, 6129.233845769279, 6136.662483563402, 6141.846453838667, 6153.415425534589, 4409.404730361408, 4599.057193275387, 6067.635941843481, 6623.172103487064, 6930.930690693104, 6706.329367063279, 6986.895196572055, 6989.510734248604, 6992.300769922992, 6994.3005699429905, 6734.023566582842, 6844.486663500207, 6845.760398386033, 6866.725330986779, 6868.450523958071, 5955.076963070731, 5999.880002399971, 6003.429674180946, 6062.96968515159, 6075.0, 26993.730062699316, 27044.323891902663, 27254.182374528795, 27278.863605681843, 27723.445531089266, 2840.6449193850767, 2948.0, 2954.1728316071535, 2956.9852150739107, 2958.4378967996226, 12883.227006379648, 16329.0, 16452.670946581, 16879.493615191564, 17005.0, 1898.8955607441585, 1931.9710204346945, 1951.5999220159833, 1952.463072655017, 1952.7656681198205, 128148.35925820404, 128215.35892320571, 128824.0, 133172.0, 133640.0, 9946.850797238047, 10985.462035315062, 11110.222284440011, 11881.524739010516, 12076.396180190955, 15101.47144849925, 15217.391304347702, 15530.223488825739, 15542.766858497132, 15544.922275388551, 9074.274058075338, 9306.627734890664, 9320.067993200626, 9429.52852357393, 9531.332806703464, 9123.452592844451, 9247.12152345526, 9304.302177336727, 9442.811143777086, 9456.676065350857, 7614.314711675976, 7709.113451952985, 7738.303552680286, 7777.57223352721, 7848.568328741974, 1561.8281988981203, 1750.8424241818298, 1825.7809062912536, 1836.8897866128075, 2040.1023549638112, 1007.5617106558524, 1024.2522958240527, 1029.917606591471, 1037.1961729659574, 1040.1107053469327, 18039.08335731802, 21567.2451464198, 21836.89081554582, 21972.0, 22164.0, 865.2774932931073, 909.0500427053745, 1070.464767616192, 1088.7604726960055, 1112.3159257057014, 11423.485943132579, 11493.712657183638, 11711.0, 11722.062235021178, 11756.823647645293, 732051.0192347119, 760830.0, 763947.0, 782528.0, 792914.0, 116726.83273167329, 119459.40270298767, 143271.28364358112, 144473.0, 150192.0, 1058.6083149234832, 1072.5709716113618, 1109.0794640448407, 1109.4785450838094, 1109.5672687651822, 17109.914450427666, 20418.0, 20832.0, 21004.054817532942, 21245.0, 53778.0, 61007.0, 61029.0, 61959.69020154871, 62048.68975655093, 87934.0, 91674.54162729411, 95073.5246323764, 95499.0, 96039.0, 8405.621747021276, 8421.0, 8675.783105422415, 8710.69512567057, 8735.519546424754, 11555.768884622092, 13052.15161014531, 13075.869241307466, 13228.669283267995, 13459.923206143681, 11339.09287257034, 11359.034482069117, 11522.481488332878, 11651.88348116542, 11721.765564688829, 9040.683576074805, 10672.73318167021, 10805.351678899291, 10967.561297548327, 11057.889421105687, 10078.740157480315, 11691.590794322157, 11872.940635296769, 11878.168528202943, 12018.939905300418, 8255.752327430188, 8451.535165565952, 8464.64215348131, 8714.085021072697, 8837.337199709918, 8522.531260780468, 10498.160147188359, 11411.942940285246, 11787.646370608896, 11845.52617895309], "value": 10505.955964449307, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1403.6210223239718, 1488.0773920169497, 1491.3139955620413, 1504.172705012243, 1505.5709122899973, 7791.259830316111, 8020.639071241808, 8290.502569845814, 8722.651093956241, 8732.30141588673, 8229.300509456689, 8528.147185281468, 8546.615402306896, 8632.568371581427, 8640.049594544595, 2060.6805945078536, 2407.5064611754547, 2412.9034838606435, 2421.370443684637, 2440.8291419600637, 2380.642903564462, 2382.880855957204, 2411.5538625354366, 2416.359664688859, 2426.3085020769086, 2375.4417711837705, 2386.05750728463, 2396.6524853896176, 2409.2531315292317, 2411.8311718179734, 2363.4800343924308, 2376.0, 2382.5830479666074, 2403.7956773674346, 2410.373302941239, 2388.10446082719, 2390.9760902390926, 2412.91418861513, 2420.261820144855, 2429.878506074689, 8672.696455624086, 8705.521196334199, 8726.956365218197, 8734.781630459225, 8744.081871403538, 5258.421573626898, 5411.729413529308, 5424.972875135639, 5448.782048718046, 5469.863253418696, 3613.927721445583, 3667.3032123896373, 3684.797336146511, 3719.6466335698065, 3730.682891954187, 1259.250745806244, 1262.9431675574529, 1301.4859130643438, 1309.8231738715342, 1323.358171286925, 11086.334819910751, 11233.157513186461, 11250.324980501115, 11266.887331126665, 11281.661550153427, 4743.193657078305, 4786.1863483207935, 4864.294677271776, 4870.95129048705, 4911.386076740408, 9679.322447428613, 9689.224862011022, 9700.126988571063, 9700.466474343979, 9712.368696034735, 5343.786248550092, 5436.945630543644, 5441.564674826004, 5458.153986132156, 5459.071957767189, 4483.484399294058, 4723.716577005391, 4954.157793175169, 4996.1007018736245, 5000.724960127155, 3786.0, 3812.8474861005247, 4035.6973227008093, 4080.6531444827524, 4156.147989662112, 1326.7014921642553, 1332.247280286635, 1332.4403750424844, 1335.5058628307588, 1341.2354957674227, 790.517784151667, 889.1330952321434, 906.1301150895083, 921.1755478846395, 924.7528505731003, 5006.3992320921725, 5365.463453654681, 5388.305847076462, 5646.491815736604, 5773.913391299134, 531.9015982043335, 542.175892643188, 544.9509544141047, 550.086855819338, 551.5615086006634, 3550.55618047744, 3571.3035957987945, 3632.0919770057485, 3668.6147954464395, 3683.0055884910926, 10013.949930250303, 10273.486325683685, 10356.948215259026, 10362.481875906175, 10379.273450858367, 9711.36876103051, 9915.405075695482, 10195.643152489776, 10247.538860751283, 10295.124914382212, 8014.719484818003, 8027.237412445925, 8202.712905048294, 8210.261076503144, 8235.341172706168, 5913.069425104471, 5987.730552125077, 5995.400459953969, 6023.367546407564, 6154.5384096192065, 9319.16127548524, 9433.433993960385, 9434.858477122985, 9443.197328227177, 9606.0, 9605.903940960501, 9624.133827955518, 9640.662576809778, 9709.126178643955, 9812.362196457207, 6905.447564194752, 7148.749793757193, 7404.148522919933, 7444.441666875115, 7494.250574942462, 7962.323202527618, 8079.192080792109, 8088.029436467662, 8093.31206847401, 8095.473794203358, 6964.895526567207, 7067.434605231672, 7164.355208031302, 7186.928130718626, 7279.526830755984, 6932.0, 7066.964665176642, 7070.858582828212, 7156.105486814148, 7187.820304492429, 6575.901361479678, 6587.670616469253, 6677.165354330708, 6685.2646208916, 6697.832554186184, 5083.822066227664, 5125.282460455464, 5143.974280128576, 5169.198774190007, 5220.347456567929, 4976.60655016596, 5113.233015047773, 5172.9741351293005, 5203.765830537559, 5267.920981185362], "value": 4491.898341772729, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.29639333463086, 374.59169505239294, 383.4612369620683, 385.1507426125394, 387.45175576559154, 4604.286335617976, 4803.879903002422, 5034.874128146798, 5408.2698835657175, 5549.2231087647715, 4245.851395201161, 4404.559544045594, 4743.359646447729, 5265.710385928773, 5449.6375906023495, 3574.963260654407, 4095.215253627532, 4272.479237250014, 4312.116016216683, 4328.437303150586, 3916.236333914888, 3940.113474468236, 4319.904846149615, 4322.481302243735, 4328.199283132615, 4065.715399922007, 4095.856645017425, 4100.692448066392, 4105.67154627629, 4131.252716943229, 3255.5930508686415, 3578.944211457613, 4130.938035929464, 4159.272127377727, 4159.396887451303, 3606.637652337723, 3620.9094772630633, 3623.3477973964677, 3632.237230181665, 3642.471841582982, 5066.40408271397, 5232.790688372498, 5329.0, 5360.678359298455, 5392.487713667196, 3606.062423769839, 3803.7717736935874, 4332.588404101605, 4520.773961301955, 4739.289106633998, 4859.58693511052, 5527.695976721278, 5742.100239379087, 5750.108733146369, 5816.76054718932, 3471.316411540401, 3792.848286068554, 3848.1918797052654, 3970.8808735737975, 4002.8598999035185, 5598.608097433182, 5699.629524080921, 6075.210222671069, 6083.057126145454, 6129.016139509355, 3424.9315013700084, 3997.960020399817, 4017.192263481445, 4022.456968309299, 4026.697997650188, 3796.4684944107294, 4012.0, 4031.3952907064177, 4073.0835561998906, 4079.5920407958965, 4709.293605959134, 4725.527447255316, 4742.762861856893, 4749.667523273338, 4752.524747525289, 5818.2436283282905, 5826.572489739956, 5843.561732870052, 5855.795047173328, 5884.029135192645, 1152.2452793420323, 1154.2209008918946, 1154.6362895687832, 1269.9428525716364, 1332.566915752365, 776.4952780692482, 847.2882778466118, 882.4881568690145, 897.9942464439869, 901.454619954931, 5298.258243845788, 5301.363836339664, 5327.520523152934, 5340.385855626652, 5341.706206158621, 4745.976270118627, 4869.440014398318, 5198.454162312978, 5729.197912292282, 5798.0, 4880.486549427048, 4900.705957642553, 5011.624128190328, 5234.555062819703, 5273.2617433559335, 2727.59086137081, 2835.319523314431, 2867.34770337437, 2977.419403216352, 3005.7144571265694, 4958.975205124024, 5574.9721251393485, 5986.700664966735, 6010.158577799111, 6082.0, 5099.5410413063, 5155.6391052626705, 5580.911722214129, 5741.024025915604, 5956.54064754138, 5371.919421208685, 5900.085486749561, 5918.704064796743, 5999.910001349983, 6026.337102918676, 4539.5006549280215, 4956.851294461172, 5470.562355011669, 5558.833235002957, 5682.403347648437, 4770.761461926821, 5082.872928176826, 5090.949090509195, 5376.650517716336, 5464.644798088111, 5380.43505431924, 5913.5564832636865, 5942.940570594239, 5971.701414929323, 6051.667158306335, 3357.0, 3377.2137986161024, 3627.6553727395853, 3839.3856982882603, 3848.5189351331082, 5011.9976004798455, 5642.50473624498, 5757.992351338583, 5759.424057594207, 5772.305724336344, 4535.591796738309, 4975.303457515878, 5088.829569199179, 5411.431799660979, 5639.492445679908, 5073.188289873602, 5369.946300537101, 5490.80782172622, 5692.402297758676, 5734.426557344399, 4802.495737947465, 4980.950190498049, 5203.10086818301, 5509.393966663583, 5636.182753500638, 4888.535589119028, 5139.6145289104215, 5582.86042848932, 5829.941700583109, 5847.912281315868, 4842.95157048425, 5383.380911195106, 5767.884642307046, 5790.160426738184, 5796.362400136066], "value": 4106.709533056414, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.8819388897774, 747.5925620536807, 794.6821271491402, 836.8117173635933, 866.2982983783135, 15214.54356369308, 15851.365945362186, 16771.580710482245, 17006.14969251537, 17077.40229091979, 15242.466513672023, 15363.923180384083, 15438.305276262567, 15441.073535587873, 15467.0, 891.5497673674802, 908.0, 910.5765818894213, 910.6038873090226, 912.6805618033668, 715.5241764226782, 739.4121673269764, 867.2498288980019, 898.5148020069188, 910.0671811393308, 962.2542529539593, 962.711186644004, 966.917811985982, 966.9903300967006, 968.5786682792973, 941.5010044676333, 956.3926906414476, 956.6412595276771, 958.8513780363985, 960.4285450157146, 700.8633316503284, 709.6061685764398, 711.6761873347615, 717.6304203335286, 720.6144712578745, 15576.532704018897, 15951.76072358904, 16042.679146417006, 16466.506004819876, 16710.58223544421, 13863.37614807326, 14599.051061680957, 14912.850871491255, 15239.23803809805, 15290.541283761506, 11926.761464770656, 11952.402379881058, 11962.641120766304, 11969.162158648898, 12011.57959471423, 3775.2826962876966, 3909.863154789569, 3927.5483319418345, 4041.474608300902, 4048.5344185418758, 97737.51131244298, 97805.02194978102, 98404.50797746037, 98670.5066474663, 102299.48850255775, 9621.422714637145, 11471.4264286787, 11521.366324852213, 11833.644990650295, 12218.0, 24325.51348973046, 24759.87620061888, 24905.252842414757, 25066.498670026864, 25198.992040318182, 8077.555734434663, 8098.068722096917, 8113.8782918256275, 8180.181981801772, 8387.454815436977, 4341.261985462479, 4342.525979216677, 4437.42313499243, 4471.709338892962, 4486.641068714495, 3690.612485689018, 3745.3445647011663, 3777.2823163598828, 3783.4135708965155, 3814.9427758583643, 676.1379241467168, 682.6586706646676, 684.4387602166175, 688.683205725362, 690.9827254318682, 690.1683471417031, 690.7444245629049, 703.4477934821243, 706.3678008182648, 742.8514297140593, 10463.758570102704, 10595.576176952989, 10674.893251067546, 11127.833082503768, 11299.661010169708, 685.2804555217002, 703.1622437629079, 732.4689600040035, 741.068191187647, 746.4700062955254, 3849.364854798927, 4169.936666150173, 4171.499420069611, 4261.70168088237, 4285.61429471349, 107309.0, 110615.44692276487, 112044.31933521004, 113054.0, 113789.43105284586, 90383.54808225916, 97392.51303743436, 99414.0, 100826.0, 102004.48997754963, 80651.58045258651, 81595.5920220407, 81840.59079704563, 83451.5827420859, 87307.25385492254, 7911.327537159175, 7964.721234756756, 7989.640466179152, 8068.435209535276, 8151.225633565039, 45944.31083533683, 54145.0, 55486.72256638691, 55571.0, 55878.72060639671, 66933.66533167497, 72232.63883680548, 73629.0, 73913.6304318475, 74090.0, 11307.43462826839, 11412.372319522507, 11731.941340293244, 12425.875741242833, 13854.099483533539, 13791.896648268315, 14248.928755356157, 15117.09297442157, 15238.0, 15859.969102008794, 13031.348432578523, 13907.165570065828, 14497.85502144965, 15072.910053439238, 15223.086614803147, 14041.578752637437, 14050.297485125908, 14460.349284282443, 14464.927675361556, 14714.926425367805, 11712.472938717608, 13644.727105458034, 13870.306484675524, 13958.860411396163, 14152.22162781057, 9676.322657413913, 10172.949135254275, 10255.94872025635, 10356.067953884187, 10826.0, 10573.110300341928, 11340.546378144782, 11680.47437865281, 11728.70678233051, 11896.22674526153], "value": 7761.911079605351, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1518.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 121.0, 117.0, 117.0, 115.0, 116.0, 114.0, 110.0, 118.0, 117.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 113.0, 142.0, 112.0, 112.0, 110.0, 111.0, 130.0, 113.0], "value": 119.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 198.0, 200.0, 200.0, 197.0, 227.0, 221.0, 196.0, 199.0, 187.0], "value": 202.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 59.0, 87.0, 57.0, 61.0, 57.0, 67.0, 97.0, 80.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 83.0, 71.0, 70.0, 70.0, 80.0, 54.0, 74.0, 114.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 122.0, 90.0, 89.0, 89.0, 86.0, 89.0, 86.0, 89.0, 107.0], "value": 93.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 106.0, 126.0, 146.0, 96.0, 95.0, 96.0, 137.0], "value": 108.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 93.0, 145.0, 90.0, 142.0, 142.0, 91.0, 92.0, 111.0, 120.0], "value": 115.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 58.0, 56.0, 66.0, 85.0, 97.0, 94.0, 97.0], "value": 72.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 70.0, 80.0, 64.0, 67.0, 87.0, 73.0, 70.0, 69.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 82.0, 80.0, 86.0, 83.0, 82.0, 84.0, 86.0, 104.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 145.0, 165.0, 150.0, 155.0, 130.0, 131.0, 142.0, 179.0, 136.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 168.0, 165.0, 169.0, 169.0, 228.0, 177.0, 167.0, 164.0], "value": 174.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 64.0, 65.0, 65.0, 65.0, 63.0, 65.0, 66.0, 64.0], "value": 64.8, "unit": "score"}]}]} 07:50:40 INFO - Return code: 0 07:50:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:50:40 ERROR - # TBPL SUCCESS # 07:50:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:50:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:50:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:50:40 INFO - 2017-03-27 07:50:40.456 screenresolution[1659:10475] starting screenresolution argv=screenresolution get 07:50:40 INFO - 2017-03-27 07:50:40.458 screenresolution[1659:10475] Display 0: 1600x1200x32@60 07:50:40 INFO - 2017-03-27 07:50:40.464 screenresolution[1660:10478] starting screenresolution argv=screenresolution list 07:50:40 INFO - Available Modes on Display 0 07:50:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:50:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:50:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:50:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:50:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:50:40 INFO - Intel Iris: 07:50:40 INFO - Chipset Model: Intel Iris 07:50:40 INFO - Type: GPU 07:50:40 INFO - Bus: Built-In 07:50:40 INFO - VRAM (Dynamic, Max): 1536 MB 07:50:40 INFO - Vendor: Intel (0x8086) 07:50:40 INFO - Device ID: 0x0a2e 07:50:40 INFO - Revision ID: 0x0009 07:50:40 INFO - Displays: 07:50:40 INFO - Display: 07:50:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:50:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:50:40 INFO - Display Serial Number: 1600x1200 60 07:50:40 INFO - Main Display: Yes 07:50:40 INFO - Mirror: Off 07:50:40 INFO - Online: Yes 07:50:40 INFO - Rotation: Supported 07:50:40 INFO - Return code: 0 07:50:40 INFO - Running post-action listener: _resource_record_post_action 07:50:40 INFO - [mozharness: 2017-03-27 14:50:40.698657Z] Finished run-tests step (success) 07:50:40 INFO - Running post-run listener: _resource_record_post_run 07:50:40 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 41072640; Write bytes: 425309184; Read time: 408; Write time: 1638 07:50:40 INFO - TinderboxPrint: CPU usage
54.3% 07:50:40 INFO - TinderboxPrint: I/O read bytes / time
41,072,640 / 408 07:50:40 INFO - TinderboxPrint: I/O write bytes / time
425,309,184 / 1,638 07:50:40 INFO - TinderboxPrint: CPU idle
2,176.9 (45.7%) 07:50:40 INFO - TinderboxPrint: CPU system
956.5 (20.1%) 07:50:40 INFO - TinderboxPrint: CPU user
1,628.2 (34.2%) 07:50:40 INFO - TinderboxPrint: Swap in / out
162,725,888 / 0 07:50:40 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 122794496; Write bytes: 122511360; Read time: 11175; Write time: 247 07:50:40 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 36194304; Write bytes: 301388800; Read time: 372; Write time: 1384 07:50:41 INFO - Running post-run listener: _upload_blobber_files 07:50:41 INFO - Blob upload gear active. 07:50:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:50:41 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 07:50:41 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'] 07:50:41 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 07:50:41 INFO - (blobuploader) - INFO - Open directory for files ... 07:50:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:50:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:50:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:50:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:50:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:50:42 INFO - (blobuploader) - INFO - Done attempting. 07:50:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:50:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:50:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:50:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:50:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:50:43 INFO - (blobuploader) - INFO - Done attempting. 07:50:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:50:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:50:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:50:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:50:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:50:44 INFO - (blobuploader) - INFO - Done attempting. 07:50:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:50:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:50:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:50:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:50:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:50:46 INFO - (blobuploader) - INFO - Done attempting. 07:50:46 INFO - (blobuploader) - INFO - Iteration through files over. 07:50:46 INFO - Return code: 0 07:50:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:50:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:50:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/35983d50584dd84eddbd6f8d1ca427a07f15715397e1a1c5c2bda64ee2578311be8cbe66bfca062293af010face719de5e5d8ae4537b046b22e113bed2962038", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f9e299a7e59974e9a221232908b2fae65b10e386542b8ecefab2bb5fd4fb42539b04223e3deece5dfdf576fb4c9f5f96ea5225c0ece18bc49afd5b2ffd87e37a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f30ad0b3652eb73f57bf6260289345245844221e0aa86c20d3ad8f2ec5016572a87c2cb27fd1c3b232f2fe361a5058718507d63e65ac14134c5a1f712f3aea7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cfa0870aaae0bf683ba89027172e29b749967cb974565ef35f1ccaaf9112b9e5397d6aa04d4327d93d418729280c1cc4a277eeb8989b90f5cbec59060bb3f384"} 07:50:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:50:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:50:46 INFO - Contents: 07:50:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/35983d50584dd84eddbd6f8d1ca427a07f15715397e1a1c5c2bda64ee2578311be8cbe66bfca062293af010face719de5e5d8ae4537b046b22e113bed2962038", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f9e299a7e59974e9a221232908b2fae65b10e386542b8ecefab2bb5fd4fb42539b04223e3deece5dfdf576fb4c9f5f96ea5225c0ece18bc49afd5b2ffd87e37a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f30ad0b3652eb73f57bf6260289345245844221e0aa86c20d3ad8f2ec5016572a87c2cb27fd1c3b232f2fe361a5058718507d63e65ac14134c5a1f712f3aea7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cfa0870aaae0bf683ba89027172e29b749967cb974565ef35f1ccaaf9112b9e5397d6aa04d4327d93d418729280c1cc4a277eeb8989b90f5cbec59060bb3f384"} 07:50:46 INFO - Running post-run listener: copy_logs_to_upload_dir 07:50:46 INFO - Copying logs to upload dir... 07:50:46 INFO - mkdir: /builds/slave/test/build/upload/logs 07:50:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.571438 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-27 07:50:46.971256) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 07:50:46.974997) ========= 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.ktDCI657Lt/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.br2n8qAekO/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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/35983d50584dd84eddbd6f8d1ca427a07f15715397e1a1c5c2bda64ee2578311be8cbe66bfca062293af010face719de5e5d8ae4537b046b22e113bed2962038", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f9e299a7e59974e9a221232908b2fae65b10e386542b8ecefab2bb5fd4fb42539b04223e3deece5dfdf576fb4c9f5f96ea5225c0ece18bc49afd5b2ffd87e37a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f30ad0b3652eb73f57bf6260289345245844221e0aa86c20d3ad8f2ec5016572a87c2cb27fd1c3b232f2fe361a5058718507d63e65ac14134c5a1f712f3aea7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cfa0870aaae0bf683ba89027172e29b749967cb974565ef35f1ccaaf9112b9e5397d6aa04d4327d93d418729280c1cc4a277eeb8989b90f5cbec59060bb3f384"} build_url:https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011198 build_url: 'https://queue.taskcluster.net/v1/task/V-b6W62TRxa7JR37VyNOCg/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/35983d50584dd84eddbd6f8d1ca427a07f15715397e1a1c5c2bda64ee2578311be8cbe66bfca062293af010face719de5e5d8ae4537b046b22e113bed2962038", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f9e299a7e59974e9a221232908b2fae65b10e386542b8ecefab2bb5fd4fb42539b04223e3deece5dfdf576fb4c9f5f96ea5225c0ece18bc49afd5b2ffd87e37a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f30ad0b3652eb73f57bf6260289345245844221e0aa86c20d3ad8f2ec5016572a87c2cb27fd1c3b232f2fe361a5058718507d63e65ac14134c5a1f712f3aea7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cfa0870aaae0bf683ba89027172e29b749967cb974565ef35f1ccaaf9112b9e5397d6aa04d4327d93d418729280c1cc4a277eeb8989b90f5cbec59060bb3f384"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 07:50:47.009455) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 07:50:47.009871) ========= 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.ktDCI657Lt/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.br2n8qAekO/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.005428 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 07:50:47.058572) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 07:50:47.058975) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 07:50:47.059316) ========= ========= Total master_lag: 0.18 =========