builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0251 starttime: 1473733116.51 results: success (0) buildid: 20160912170908 builduid: 5d25e3ae61bf4b63b0ed544cbf9a2f1d revision: 2e35fd4a4932abe6ea89311f914233dbf251b6de ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 19:18:36.512526) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 19:18:36.513093) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 19:18:36.513447) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 19:18:36.702654) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 19:18:36.702954) ========= 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.yel7ilVxPP/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.4GxbaHnejw/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 --2016-09-12 19:18:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2016-09-12 19:18:37 (18.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.461016 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 19:18:37.187764) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 19:18:37.188076) ========= 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.yel7ilVxPP/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.4GxbaHnejw/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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.025637 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 19:18:37.236371) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-12 19:18:37.236723) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2e35fd4a4932abe6ea89311f914233dbf251b6de --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 2e35fd4a4932abe6ea89311f914233dbf251b6de --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yel7ilVxPP/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.4GxbaHnejw/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 2016-09-12 19:18:37,323 truncating revision to first 12 chars 2016-09-12 19:18:37,323 Setting DEBUG logging. 2016-09-12 19:18:37,323 attempt 1/10 2016-09-12 19:18:37,324 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2e35fd4a4932?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 19:18:38,608 unpacking tar archive at: mozilla-central-2e35fd4a4932/testing/mozharness/ program finished with exit code 0 elapsedTime=1.560079 ========= master_lag: 1.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-12 19:18:39.844851) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 19:18:39.845173) ========= script_repo_revision: 2e35fd4a4932abe6ea89311f914233dbf251b6de ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 19:18:39.845558) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-09-12 19:18:39.845837) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-09-12 19:18:40.927144) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-12 19:18:40.927530) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yel7ilVxPP/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.4GxbaHnejw/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:18:41 INFO - MultiFileLogger online at 20160912 19:18:41 in /builds/slave/test 19:18:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:18:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:18:41 INFO - {'append_to_log': False, 19:18:41 INFO - 'base_work_dir': '/builds/slave/test', 19:18:41 INFO - 'blob_upload_branch': 'Firefox', 19:18:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:18:41 INFO - 'branch': 'Firefox', 19:18:41 INFO - 'buildbot_json_path': 'buildprops.json', 19:18:41 INFO - 'config_files': ('talos/mac_config.py',), 19:18:41 INFO - 'default_actions': ('clobber', 19:18:41 INFO - 'read-buildbot-config', 19:18:41 INFO - 'download-and-extract', 19:18:41 INFO - 'populate-webroot', 19:18:41 INFO - 'create-virtualenv', 19:18:41 INFO - 'install', 19:18:41 INFO - 'run-tests'), 19:18:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:18:41 INFO - 'download_minidump_stackwalk': True, 19:18:41 INFO - 'download_symbols': 'ondemand', 19:18:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:18:41 INFO - 'tooltool.py': '/tools/tooltool.py', 19:18:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:18:41 INFO - '/tools/misc-python/virtualenv.py')}, 19:18:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:18:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:18:41 INFO - 'installer_path': 'installer.exe', 19:18:41 INFO - 'log_level': 'info', 19:18:41 INFO - 'log_name': 'talos', 19:18:41 INFO - 'log_to_console': True, 19:18:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:18:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:18:41 INFO - 'opt_config_files': (), 19:18:41 INFO - 'pip_index': False, 19:18:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:18:41 INFO - 'cmd': ('bash', 19:18:41 INFO - '-c', 19:18:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:18:41 INFO - 'enabled': True, 19:18:41 INFO - 'halt_on_failure': False, 19:18:41 INFO - 'name': 'check_screen_resolution'},), 19:18:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:18:41 INFO - 'cmd': ('bash', 19:18:41 INFO - '-c', 19:18:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:18:41 INFO - 'enabled': True, 19:18:41 INFO - 'halt_on_failure': False, 19:18:41 INFO - 'name': 'check_screen_resolution'},), 19:18:41 INFO - 'run_cmd_checks_enabled': True, 19:18:41 INFO - 'sps_profile': False, 19:18:41 INFO - 'sps_profile_interval': 0, 19:18:41 INFO - 'suite': 'dromaeojs', 19:18:41 INFO - 'system_bits': '32', 19:18:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:18:41 INFO - 'title': 't-yosemite-r7-0251', 19:18:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:18:41 INFO - 'use_talos_json': True, 19:18:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:18:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:18:41 INFO - 'work_dir': 'build'} 19:18:41 INFO - [mozharness: 2016-09-13 02:18:41.128427Z] Running clobber step. 19:18:41 INFO - Running pre-action listener: _resource_record_pre_action 19:18:41 INFO - Running main action method: clobber 19:18:41 INFO - rmtree: /builds/slave/test/build 19:18:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:18:43 INFO - Running post-action listener: _resource_record_post_action 19:18:43 INFO - [mozharness: 2016-09-13 02:18:43.228918Z] Finished clobber step (success) 19:18:43 INFO - [mozharness: 2016-09-13 02:18:43.229079Z] Running read-buildbot-config step. 19:18:43 INFO - Running pre-action listener: _resource_record_pre_action 19:18:43 INFO - Running main action method: read_buildbot_config 19:18:43 INFO - Using buildbot properties: 19:18:43 INFO - { 19:18:43 INFO - "project": "", 19:18:43 INFO - "product": "firefox", 19:18:43 INFO - "script_repo_revision": "production", 19:18:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:18:43 INFO - "repository": "", 19:18:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:18:43 INFO - "buildid": "20160912170908", 19:18:43 INFO - "pgo_build": "False", 19:18:43 INFO - "basedir": "/builds/slave/test", 19:18:43 INFO - "buildnumber": 40, 19:18:43 INFO - "slavename": "t-yosemite-r7-0251", 19:18:43 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 19:18:43 INFO - "platform": "yosemite_r7", 19:18:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:18:43 INFO - "branch": "mozilla-central", 19:18:43 INFO - "repo_path": "mozilla-central", 19:18:43 INFO - "revision": "2e35fd4a4932abe6ea89311f914233dbf251b6de", 19:18:43 INFO - "stage_platform": "macosx64", 19:18:43 INFO - "builduid": "5d25e3ae61bf4b63b0ed544cbf9a2f1d", 19:18:43 INFO - "slavebuilddir": "test" 19:18:43 INFO - } 19:18:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:18:43 INFO - Running post-action listener: _resource_record_post_action 19:18:43 INFO - [mozharness: 2016-09-13 02:18:43.232901Z] Finished read-buildbot-config step (success) 19:18:43 INFO - [mozharness: 2016-09-13 02:18:43.233061Z] Running download-and-extract step. 19:18:43 INFO - Running pre-action listener: _resource_record_pre_action 19:18:43 INFO - Running main action method: download_and_extract 19:18:43 INFO - mkdir: /builds/slave/test/build/tests 19:18: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')]} 19:18:43 INFO - trying https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:18:43 INFO - Downloading https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:18:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 19:18:44 INFO - Downloaded 1199 bytes. 19:18:44 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:18:44 INFO - Using the following test package requirements: 19:18:44 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:18:44 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:44 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:18:44 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:44 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:18:44 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:44 INFO - u'jsshell-mac.zip'], 19:18:44 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:44 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:18:44 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:18:44 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:44 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:18:44 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:44 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:18:44 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:44 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:18:44 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:44 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:18:44 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:18:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:18:45 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 19:18:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:18:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:18:52 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 19:19:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:19:00 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 19:19:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:01 INFO - trying https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:19:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:19:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 19:19:04 INFO - Downloaded 111631559 bytes. 19:19:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:19:04 INFO - mkdir: /builds/slave/test/properties 19:19:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:19:04 INFO - Writing to file /builds/slave/test/properties/build_url 19:19:04 INFO - Contents: 19:19:04 INFO - build_url:https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:19:05 INFO - Running post-action listener: _resource_record_post_action 19:19:05 INFO - Running post-action listener: set_extra_try_arguments 19:19:05 INFO - [mozharness: 2016-09-13 02:19:05.108572Z] Finished download-and-extract step (success) 19:19:05 INFO - [mozharness: 2016-09-13 02:19:05.108684Z] Running populate-webroot step. 19:19:05 INFO - Running pre-action listener: _resource_record_pre_action 19:19:05 INFO - Running main action method: populate_webroot 19:19:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:19:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:19:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:19:05 INFO - '--tppagecycles', 19:19:05 INFO - '7'], 19:19:05 INFO - 'tests': ['tsvgm']}}, 19:19:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:19:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:19:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:19:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:19:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:19:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:19:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:05 INFO - 'talos_options': ['--disable-e10s'], 19:19:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:19:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:19:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:05 INFO - 'talos_options': ['--disable-e10s'], 19:19:05 INFO - 'tests': ['damp', 'tps']}, 19:19:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:05 INFO - 'tests': ['damp', 'tps']}, 19:19:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:19:05 INFO - 'tests': ['dromaeo_dom']}, 19:19:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:19:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:19:05 INFO - 'tests': ['basic_compositor_video']}, 19:19:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:19:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:19:05 INFO - 'tests': ['a11yr', 19:19:05 INFO - 'ts_paint', 19:19:05 INFO - 'tpaint', 19:19:05 INFO - 'sessionrestore', 19:19:05 INFO - 'sessionrestore_no_auto_restore', 19:19:05 INFO - 'tabpaint']}, 19:19:05 INFO - 'other-e10s': {'tests': ['a11yr', 19:19:05 INFO - 'ts_paint', 19:19:05 INFO - 'tpaint', 19:19:05 INFO - 'sessionrestore', 19:19:05 INFO - 'sessionrestore_no_auto_restore', 19:19:05 INFO - 'tabpaint']}, 19:19:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:19:05 INFO - 'ts_paint', 19:19:05 INFO - 'tpaint', 19:19:05 INFO - 'sessionrestore', 19:19:05 INFO - 'sessionrestore_no_auto_restore', 19:19:05 INFO - 'tabpaint']}, 19:19:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:19:05 INFO - 'ts_paint', 19:19:05 INFO - 'tpaint', 19:19:05 INFO - 'sessionrestore', 19:19:05 INFO - 'sessionrestore_no_auto_restore', 19:19:05 INFO - 'tabpaint']}, 19:19:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:19:05 INFO - 'tests': ['a11yr', 19:19:05 INFO - 'ts_paint', 19:19:05 INFO - 'tpaint', 19:19:05 INFO - 'sessionrestore', 19:19:05 INFO - 'sessionrestore_no_auto_restore', 19:19:05 INFO - 'tabpaint']}, 19:19:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:19:05 INFO - 'tests': ['a11yr', 19:19:05 INFO - 'ts_paint', 19:19:05 INFO - 'tpaint', 19:19:05 INFO - 'sessionrestore', 19:19:05 INFO - 'sessionrestore_no_auto_restore', 19:19:05 INFO - 'tabpaint']}, 19:19:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:19:05 INFO - 'tests': ['tsvgx', 19:19:05 INFO - 'tsvgr_opacity', 19:19:05 INFO - 'tart', 19:19:05 INFO - 'tscrollx', 19:19:05 INFO - 'cart']}, 19:19:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:19:05 INFO - 'tsvgr_opacity', 19:19:05 INFO - 'tart', 19:19:05 INFO - 'tscrollx', 19:19:05 INFO - 'cart']}, 19:19:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:05 INFO - 'talos_options': ['--disable-e10s'], 19:19:05 INFO - 'tests': ['tp5o']}, 19:19:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:05 INFO - 'tests': ['tp5o']}, 19:19:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:05 INFO - 'talos_options': ['--disable-e10s', 19:19:05 INFO - '--xperf_path', 19:19:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:19:05 INFO - 'tests': ['tp5n']}, 19:19:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:05 INFO - 'talos_options': ['--xperf_path', 19:19:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:19:05 INFO - 'tests': ['tp5n']}}, 19:19:05 INFO - 'talos.zip': {'path': '', 19:19:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:19:05 INFO - Running post-action listener: _resource_record_post_action 19:19:05 INFO - [mozharness: 2016-09-13 02:19:05.127962Z] Finished populate-webroot step (success) 19:19:05 INFO - [mozharness: 2016-09-13 02:19:05.128083Z] Running create-virtualenv step. 19:19:05 INFO - Running pre-action listener: _resource_record_pre_action 19:19:05 INFO - Running main action method: create_virtualenv 19:19:05 INFO - Creating virtualenv /builds/slave/test/build/venv 19:19:05 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 19:19:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:19:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:19:05 INFO - Using real prefix '/tools/python27' 19:19:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:19:05 INFO - Installing distribute.............................................................................................................................................................................................done. 19:19:08 INFO - Installing pip.................done. 19:19:08 INFO - Return code: 0 19:19:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103b9c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6ad86510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b0d30>, '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 0x1037d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a8b20>, '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.yel7ilVxPP/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.4GxbaHnejw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yel7ilVxPP/Render', 19:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:08 INFO - 'HOME': '/Users/cltbld', 19:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:08 INFO - 'LOGNAME': 'cltbld', 19:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:08 INFO - 'MOZ_NO_REMOTE': '1', 19:19:08 INFO - 'NO_EM_RESTART': '1', 19:19:08 INFO - 'PAGER': '/bin/cat', 19:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:08 INFO - 'PWD': '/builds/slave/test', 19:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:08 INFO - 'SHELL': '/bin/bash', 19:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GxbaHnejw/Listeners', 19:19:08 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:08 INFO - 'USER': 'cltbld', 19:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:08 INFO - 'XPC_FLAGS': '0x0', 19:19:08 INFO - 'XPC_SERVICE_NAME': '0', 19:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:09 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 19:19:09 INFO - Cleaning up... 19:19:09 INFO - Return code: 0 19:19:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:09 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 0x103b9c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6ad86510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b0d30>, '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 0x1037d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a8b20>, '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.yel7ilVxPP/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.4GxbaHnejw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:19:09 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 19:19:09 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 19:19:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yel7ilVxPP/Render', 19:19:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:09 INFO - 'HOME': '/Users/cltbld', 19:19:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:09 INFO - 'LOGNAME': 'cltbld', 19:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:09 INFO - 'MOZ_NO_REMOTE': '1', 19:19:09 INFO - 'NO_EM_RESTART': '1', 19:19:09 INFO - 'PAGER': '/bin/cat', 19:19:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:09 INFO - 'PWD': '/builds/slave/test', 19:19:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:09 INFO - 'SHELL': '/bin/bash', 19:19:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GxbaHnejw/Listeners', 19:19:09 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:19:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:09 INFO - 'USER': 'cltbld', 19:19:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:09 INFO - 'XPC_FLAGS': '0x0', 19:19:09 INFO - 'XPC_SERVICE_NAME': '0', 19:19:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:09 INFO - Downloading/unpacking psutil>=3.1.1 19:19:09 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 19:19:09 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 19:19:09 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 19:19:09 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 19:19:09 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 19:19:09 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 19:19:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:19:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:19:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:19:13 INFO - Installing collected packages: psutil 19:19:13 INFO - Running setup.py install for psutil 19:19:13 INFO - building 'psutil._psutil_osx' extension 19:19:13 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 19:19:14 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 19:19:14 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 19:19:14 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 19:19:14 INFO - building 'psutil._psutil_posix' extension 19:19:14 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 19:19:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:19:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:19:14 INFO - ^ 19:19:14 INFO - 1 warning generated. 19:19:14 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 19:19:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:19:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:19:14 INFO - Successfully installed psutil 19:19:14 INFO - Cleaning up... 19:19:14 INFO - Return code: 0 19:19:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:14 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 0x103b9c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6ad86510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b0d30>, '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 0x1037d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a8b20>, '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.yel7ilVxPP/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.4GxbaHnejw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:19:14 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 19:19:14 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 19:19:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yel7ilVxPP/Render', 19:19:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:14 INFO - 'HOME': '/Users/cltbld', 19:19:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:14 INFO - 'LOGNAME': 'cltbld', 19:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:14 INFO - 'MOZ_NO_REMOTE': '1', 19:19:14 INFO - 'NO_EM_RESTART': '1', 19:19:14 INFO - 'PAGER': '/bin/cat', 19:19:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:14 INFO - 'PWD': '/builds/slave/test', 19:19:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:14 INFO - 'SHELL': '/bin/bash', 19:19:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GxbaHnejw/Listeners', 19:19:14 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:19:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:14 INFO - 'USER': 'cltbld', 19:19:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:14 INFO - 'XPC_FLAGS': '0x0', 19:19:14 INFO - 'XPC_SERVICE_NAME': '0', 19:19:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:19:15 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 19:19:15 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 19:19:15 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 19:19:15 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 19:19:15 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 19:19:15 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 19:19:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:19:17 INFO - Installing collected packages: mozsystemmonitor 19:19:17 INFO - Running setup.py install for mozsystemmonitor 19:19:17 INFO - Successfully installed mozsystemmonitor 19:19:17 INFO - Cleaning up... 19:19:17 INFO - Return code: 0 19:19:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:19:17 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')]} 19:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:17 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')]} 19:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:17 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 0x103b9c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6ad86510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b0d30>, '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 0x1037d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a8b20>, '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.yel7ilVxPP/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.4GxbaHnejw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:19:17 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 19:19:17 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 19:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yel7ilVxPP/Render', 19:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:17 INFO - 'HOME': '/Users/cltbld', 19:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:17 INFO - 'LOGNAME': 'cltbld', 19:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:17 INFO - 'MOZ_NO_REMOTE': '1', 19:19:17 INFO - 'NO_EM_RESTART': '1', 19:19:17 INFO - 'PAGER': '/bin/cat', 19:19:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:17 INFO - 'PWD': '/builds/slave/test', 19:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:17 INFO - 'SHELL': '/bin/bash', 19:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GxbaHnejw/Listeners', 19:19:17 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:17 INFO - 'USER': 'cltbld', 19:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:17 INFO - 'XPC_FLAGS': '0x0', 19:19:17 INFO - 'XPC_SERVICE_NAME': '0', 19:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:18 INFO - Downloading/unpacking jsonschema==2.5.1 19:19:18 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 19:19:18 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 19:19:18 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 19:19:18 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 19:19:18 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 19:19:18 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 19:19:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:19:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:21 INFO - Downloading functools32-3.2.3-2.tar.gz 19:19:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:19:21 INFO - warning: no files found matching '*.txt' 19:19:21 INFO - no previously-included directories found matching 'build' 19:19:21 INFO - no previously-included directories found matching 'dist' 19:19:21 INFO - no previously-included directories found matching '.git*' 19:19:21 INFO - Installing collected packages: jsonschema, functools32 19:19:21 INFO - Running setup.py install for functools32 19:19:21 INFO - warning: no files found matching '*.txt' 19:19:21 INFO - no previously-included directories found matching 'build' 19:19:21 INFO - no previously-included directories found matching 'dist' 19:19:21 INFO - no previously-included directories found matching '.git*' 19:19:21 INFO - Successfully installed jsonschema functools32 19:19:21 INFO - Cleaning up... 19:19:21 INFO - Return code: 0 19:19:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:19: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')]} 19:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19: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')]} 19:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19: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', '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 0x103b9c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6ad86510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b0d30>, '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 0x1037d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a8b20>, '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.yel7ilVxPP/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.4GxbaHnejw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:19: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:19: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 functools32==3.2.3-2 19:19:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yel7ilVxPP/Render', 19:19:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:21 INFO - 'HOME': '/Users/cltbld', 19:19:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:21 INFO - 'LOGNAME': 'cltbld', 19:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:21 INFO - 'MOZ_NO_REMOTE': '1', 19:19:21 INFO - 'NO_EM_RESTART': '1', 19:19:21 INFO - 'PAGER': '/bin/cat', 19:19:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:21 INFO - 'PWD': '/builds/slave/test', 19:19:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:21 INFO - 'SHELL': '/bin/bash', 19:19:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GxbaHnejw/Listeners', 19:19:21 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:19:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:21 INFO - 'USER': 'cltbld', 19:19:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:21 INFO - 'XPC_FLAGS': '0x0', 19:19:21 INFO - 'XPC_SERVICE_NAME': '0', 19:19:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:19:21 INFO - Cleaning up... 19:19:21 INFO - Return code: 0 19:19:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:19: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')]} 19:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19: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')]} 19:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19: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', '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 0x103b9c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6ad86510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b0d30>, '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 0x1037d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a8b20>, '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.yel7ilVxPP/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.4GxbaHnejw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:19: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:19: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 blobuploader==1.2.4 19:19:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yel7ilVxPP/Render', 19:19:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:21 INFO - 'HOME': '/Users/cltbld', 19:19:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:21 INFO - 'LOGNAME': 'cltbld', 19:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:21 INFO - 'MOZ_NO_REMOTE': '1', 19:19:21 INFO - 'NO_EM_RESTART': '1', 19:19:21 INFO - 'PAGER': '/bin/cat', 19:19:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:21 INFO - 'PWD': '/builds/slave/test', 19:19:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:21 INFO - 'SHELL': '/bin/bash', 19:19:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GxbaHnejw/Listeners', 19:19:21 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:19:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:21 INFO - 'USER': 'cltbld', 19:19:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:21 INFO - 'XPC_FLAGS': '0x0', 19:19:21 INFO - 'XPC_SERVICE_NAME': '0', 19:19:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:21 INFO - Downloading/unpacking blobuploader==1.2.4 19:19: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 19:19: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 19:19: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 19:19: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 19:19: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 19:19: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 19:19:24 INFO - Downloading blobuploader-1.2.4.tar.gz 19:19:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:19:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:19:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:25 INFO - Downloading docopt-0.6.1.tar.gz 19:19:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:19:25 INFO - Installing collected packages: blobuploader, requests, docopt 19:19:25 INFO - Running setup.py install for blobuploader 19:19:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:19:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:19:25 INFO - Running setup.py install for requests 19:19:26 INFO - Running setup.py install for docopt 19:19:26 INFO - Successfully installed blobuploader requests docopt 19:19:26 INFO - Cleaning up... 19:19:26 INFO - Return code: 0 19:19:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:19: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')]} 19:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19: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')]} 19:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:26 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 0x103b9c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6ad86510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b0d30>, '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 0x1037d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a8b20>, '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.yel7ilVxPP/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.4GxbaHnejw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:19:26 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 19:19:26 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 19:19:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yel7ilVxPP/Render', 19:19:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:26 INFO - 'HOME': '/Users/cltbld', 19:19:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:26 INFO - 'LOGNAME': 'cltbld', 19:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:26 INFO - 'MOZ_NO_REMOTE': '1', 19:19:26 INFO - 'NO_EM_RESTART': '1', 19:19:26 INFO - 'PAGER': '/bin/cat', 19:19:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:26 INFO - 'PWD': '/builds/slave/test', 19:19:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:26 INFO - 'SHELL': '/bin/bash', 19:19:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GxbaHnejw/Listeners', 19:19:26 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:19:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:26 INFO - 'USER': 'cltbld', 19:19:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:26 INFO - 'XPC_FLAGS': '0x0', 19:19:26 INFO - 'XPC_SERVICE_NAME': '0', 19:19:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:19:26 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-OS54C7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:19:26 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-_uGViH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:19:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-3MZTaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:19:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-HoVNsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:19:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-xoTlg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:19:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-8BP5nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:19:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-dYEm7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:19:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-H9x0iK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:19:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-_kh2eF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:19:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-ERaJ2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:19:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-jwcBTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:19:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-DMMjDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:19:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-XDkUEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:19:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-CecG54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-lUqfF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:19:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-jWkrL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:19:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-IpICJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:19:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:19:28 INFO - Running setup.py install for manifestparser 19:19:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:19:28 INFO - Running setup.py install for mozcrash 19:19:28 INFO - Running setup.py install for mozdebug 19:19:29 INFO - Running setup.py install for mozdevice 19:19:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:19:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:19:29 INFO - Running setup.py install for mozfile 19:19:29 INFO - Running setup.py install for mozhttpd 19:19:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:19:29 INFO - Running setup.py install for mozinfo 19:19:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:19:29 INFO - Running setup.py install for mozInstall 19:19:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:19:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:19:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:19:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:19:29 INFO - Running setup.py install for mozleak 19:19:30 INFO - Running setup.py install for mozlog 19:19:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:19:30 INFO - Running setup.py install for moznetwork 19:19:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:19:30 INFO - Running setup.py install for mozprocess 19:19:30 INFO - Running setup.py install for mozprofile 19:19:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:19:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:19:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:19:30 INFO - Running setup.py install for mozrunner 19:19:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:19:30 INFO - Running setup.py install for mozscreenshot 19:19:30 INFO - Running setup.py install for moztest 19:19:31 INFO - Running setup.py install for mozversion 19:19:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:19:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:19:31 INFO - Cleaning up... 19:19:31 INFO - Return code: 0 19:19:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:19:31 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')]} 19:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:31 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')]} 19:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:31 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 0x103b9c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6ad86510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b0d30>, '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 0x1037d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a8b20>, '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.yel7ilVxPP/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.4GxbaHnejw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:19:31 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 19:19:31 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 19:19:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yel7ilVxPP/Render', 19:19:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:31 INFO - 'HOME': '/Users/cltbld', 19:19:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:31 INFO - 'LOGNAME': 'cltbld', 19:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:31 INFO - 'MOZ_NO_REMOTE': '1', 19:19:31 INFO - 'NO_EM_RESTART': '1', 19:19:31 INFO - 'PAGER': '/bin/cat', 19:19:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:31 INFO - 'PWD': '/builds/slave/test', 19:19:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:31 INFO - 'SHELL': '/bin/bash', 19:19:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GxbaHnejw/Listeners', 19:19:31 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:19:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:31 INFO - 'USER': 'cltbld', 19:19:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:31 INFO - 'XPC_FLAGS': '0x0', 19:19:31 INFO - 'XPC_SERVICE_NAME': '0', 19:19:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:19:31 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-ncTFkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:19:31 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)) 19:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:19:31 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-iEzsxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 19:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:19:31 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Qzb7Ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:19:31 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)) 19:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:19:31 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-M0gQ9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 19:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:19:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-HIawOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:19:32 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)) 19:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:19:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-47a0m4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:19:32 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)) 19:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:19:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-BC98jC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:19:32 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)) 19:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:19:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-CA9QrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:19:32 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)) 19:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:19:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-3cMfSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:19:32 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)) 19:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:19:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-naNjGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 19:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:19:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-JU70vy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:19:32 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)) 19:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:19:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-3jnHer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 19:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:19:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-BwapNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:19:33 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)) 19:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:19:33 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-EFl3hZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:19:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 19:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:33 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-gRQf3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:33 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)) 19:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:19:33 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-ivWO4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:19:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 19:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:19:33 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-rjdjvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:19:33 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)) 19:19:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:19:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:19:33 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)) 19:19:33 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:19:33 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:19:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:19: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 19:19: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 19:19: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 19:19: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 19:19: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 19:19: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 19:19:35 INFO - Downloading blessings-1.6.tar.gz 19:19:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:19:36 INFO - Installing collected packages: blessings 19:19:36 INFO - Running setup.py install for blessings 19:19:36 INFO - Successfully installed blessings 19:19:36 INFO - Cleaning up... 19:19:36 INFO - Return code: 0 19:19:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:19:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:19:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:19:36 INFO - Reading from file tmpfile_stdout 19:19:36 INFO - Current package versions: 19:19:36 INFO - blessings == 1.6 19:19:36 INFO - blobuploader == 1.2.4 19:19:36 INFO - docopt == 0.6.1 19:19:36 INFO - functools32 == 3.2.3-2 19:19:36 INFO - jsonschema == 2.5.1 19:19:36 INFO - manifestparser == 1.1 19:19:36 INFO - mozInstall == 1.12 19:19:36 INFO - mozcrash == 0.17 19:19:36 INFO - mozdebug == 0.1 19:19:36 INFO - mozdevice == 0.48 19:19:36 INFO - mozfile == 1.2 19:19:36 INFO - mozhttpd == 0.7 19:19:36 INFO - mozinfo == 0.9 19:19:36 INFO - mozleak == 0.1 19:19:36 INFO - mozlog == 3.3 19:19:36 INFO - moznetwork == 0.27 19:19:36 INFO - mozprocess == 0.23 19:19:36 INFO - mozprofile == 0.28 19:19:36 INFO - mozrunner == 6.12 19:19:36 INFO - mozscreenshot == 0.1 19:19:36 INFO - mozsystemmonitor == 0.3 19:19:36 INFO - moztest == 0.7 19:19:36 INFO - mozversion == 1.4 19:19:36 INFO - psutil == 3.1.1 19:19:36 INFO - requests == 1.2.3 19:19:36 INFO - wsgiref == 0.1.2 19:19:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:36 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 0x103b9c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6ad86510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b0d30>, '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 0x1037d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a8b20>, '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.yel7ilVxPP/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.4GxbaHnejw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:19:36 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 19:19:36 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 19:19:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yel7ilVxPP/Render', 19:19:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:36 INFO - 'HOME': '/Users/cltbld', 19:19:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:36 INFO - 'LOGNAME': 'cltbld', 19:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:36 INFO - 'MOZ_NO_REMOTE': '1', 19:19:36 INFO - 'NO_EM_RESTART': '1', 19:19:36 INFO - 'PAGER': '/bin/cat', 19:19:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:36 INFO - 'PWD': '/builds/slave/test', 19:19:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:36 INFO - 'SHELL': '/bin/bash', 19:19:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GxbaHnejw/Listeners', 19:19:36 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:19:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:36 INFO - 'USER': 'cltbld', 19:19:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:36 INFO - 'XPC_FLAGS': '0x0', 19:19:36 INFO - 'XPC_SERVICE_NAME': '0', 19:19:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:36 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)) 19:19:36 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)) 19:19:36 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)) 19:19:36 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)) 19:19:36 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)) 19:19:36 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)) 19:19:36 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)) 19:19:36 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)) 19:19:36 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)) 19:19:36 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)) 19:19:36 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)) 19:19:36 INFO - Cleaning up... 19:19:36 INFO - Return code: 0 19:19:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103b9c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb6ad86510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039b0d30>, '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 0x1037d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a8b20>, '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.yel7ilVxPP/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.4GxbaHnejw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:19:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:19:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:19:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yel7ilVxPP/Render', 19:19:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:36 INFO - 'HOME': '/Users/cltbld', 19:19:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:36 INFO - 'LOGNAME': 'cltbld', 19:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:36 INFO - 'MOZ_NO_REMOTE': '1', 19:19:36 INFO - 'NO_EM_RESTART': '1', 19:19:36 INFO - 'PAGER': '/bin/cat', 19:19:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:36 INFO - 'PWD': '/builds/slave/test', 19:19:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:36 INFO - 'SHELL': '/bin/bash', 19:19:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GxbaHnejw/Listeners', 19:19:36 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:19:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:36 INFO - 'USER': 'cltbld', 19:19:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:36 INFO - 'XPC_FLAGS': '0x0', 19:19:36 INFO - 'XPC_SERVICE_NAME': '0', 19:19:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:19:37 INFO - Cleaning up... 19:19:37 INFO - Return code: 0 19:19:37 INFO - Running post-action listener: _resource_record_post_action 19:19:37 INFO - Running post-action listener: _start_resource_monitoring 19:19:37 INFO - Starting resource monitoring. 19:19:37 INFO - [mozharness: 2016-09-13 02:19:37.146903Z] Finished create-virtualenv step (success) 19:19:37 INFO - [mozharness: 2016-09-13 02:19:37.147850Z] Running install step. 19:19:37 INFO - Running pre-action listener: _resource_record_pre_action 19:19:37 INFO - Running main action method: install 19:19:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:19:37 INFO - Reading from file tmpfile_stdout 19:19:37 INFO - Detecting whether we're running mozinstall >=1.0... 19:19:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:19:37 INFO - Reading from file tmpfile_stdout 19:19:37 INFO - Output received: 19:19:37 INFO - Usage: mozinstall [options] installer 19:19:37 INFO - Options: 19:19:37 INFO - -h, --help show this help message and exit 19:19:37 INFO - -d DEST, --destination=DEST 19:19:37 INFO - Directory to install application into. [default: 19:19:37 INFO - "/builds/slave/test"] 19:19:37 INFO - --app=APP Application being installed. [default: firefox] 19:19:37 INFO - mkdir: /builds/slave/test/build/application 19:19:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:20:12 INFO - Reading from file tmpfile_stdout 19:20:12 INFO - Output received: 19:20:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:20:12 INFO - Running post-action listener: _resource_record_post_action 19:20:12 INFO - [mozharness: 2016-09-13 02:20:12.009149Z] Finished install step (success) 19:20:12 INFO - [mozharness: 2016-09-13 02:20:12.009311Z] Running run-tests step. 19:20:12 INFO - Running pre-action listener: _resource_record_pre_action 19:20:12 INFO - Running main action method: run_tests 19:20:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:20:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:20:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:20:12 INFO - 2016-09-12 19:20:12.023 screenresolution[1651:8886] starting screenresolution argv=screenresolution get 19:20:12 INFO - 2016-09-12 19:20:12.026 screenresolution[1651:8886] Display 0: 1600x1200x32@60 19:20:12 INFO - 2016-09-12 19:20:12.032 screenresolution[1652:8888] starting screenresolution argv=screenresolution list 19:20:12 INFO - Available Modes on Display 0 19:20:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:20:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:20:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:20:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:20:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:20:12 INFO - Intel Iris: 19:20:12 INFO - Chipset Model: Intel Iris 19:20:12 INFO - Type: GPU 19:20:12 INFO - Bus: Built-In 19:20:12 INFO - VRAM (Dynamic, Max): 1536 MB 19:20:12 INFO - Vendor: Intel (0x8086) 19:20:12 INFO - Device ID: 0x0a2e 19:20:12 INFO - Revision ID: 0x0009 19:20:12 INFO - Displays: 19:20:12 INFO - Display: 19:20:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:20:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:20:12 INFO - Display Serial Number: 1600x1200 60 19:20:12 INFO - Main Display: Yes 19:20:12 INFO - Mirror: Off 19:20:12 INFO - Online: Yes 19:20:12 INFO - Rotation: Supported 19:20:12 INFO - Return code: 0 19:20:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:20:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:20:12 INFO - Python 2.7.3 19:20:12 INFO - Return code: 0 19:20:12 INFO - grabbing minidump binary from tooltool 19:20: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')]} 19:20:12 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 0x1039b0d30>, '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 0x1037d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:20:12 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 19:20:12 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 19:20:12 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 19:20:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:20:12 INFO - Return code: 0 19:20:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:20:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:20:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:20:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:20:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:20:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0251', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:20:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0251 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:20:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yel7ilVxPP/Render', 19:20:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:20:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:20:12 INFO - 'HOME': '/Users/cltbld', 19:20:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:20:12 INFO - 'LOGNAME': 'cltbld', 19:20:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:20:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:20:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:20:12 INFO - 'MOZ_NO_REMOTE': '1', 19:20:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:20:12 INFO - 'NO_EM_RESTART': '1', 19:20:12 INFO - 'PAGER': '/bin/cat', 19:20:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:20:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:20:12 INFO - 'PWD': '/builds/slave/test', 19:20:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:20:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:20:12 INFO - 'SHELL': '/bin/bash', 19:20:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4GxbaHnejw/Listeners', 19:20:12 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:20:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:20:12 INFO - 'USER': 'cltbld', 19:20:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:20:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:20:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:20:12 INFO - 'XPC_FLAGS': '0x0', 19:20:12 INFO - 'XPC_SERVICE_NAME': '0', 19:20:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:20:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0251', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:20:12 INFO - mozversion application_buildid: 20160912170908 19:20:12 INFO - mozversion application_changeset: 2e35fd4a4932abe6ea89311f914233dbf251b6de 19:20:12 INFO - mozversion application_display_name: Nightly 19:20:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:20:12 INFO - mozversion application_name: Firefox 19:20:12 INFO - mozversion application_remotingname: firefox 19:20:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:20:12 INFO - mozversion application_vendor: Mozilla 19:20:12 INFO - mozversion application_version: 51.0a1 19:20:12 INFO - mozversion platform_buildid: 20160912170908 19:20:12 INFO - mozversion platform_changeset: 2e35fd4a4932abe6ea89311f914233dbf251b6de 19:20:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:20:12 INFO - mozversion platform_version: 51.0a1 19:20:12 INFO - using testdate: 1473733212 19:20:12 INFO - actual date: 1473733212 19:20:12 INFO - starting webserver on 'localhost:49275' 19:20:12 INFO - SUITE-START | Running 2 tests 19:20:12 INFO - TEST-START | dromaeo_css 19:20:12 INFO - operating with platform_type : mac_ 19:20:12 INFO - Initialising browser for dromaeo_css test... 19:20:12 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpXlPINO/profile http://localhost:49275/getInfo.html) 19:20:14 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 19:20:14 INFO - PROCESS | 1662 | colorDepth:24 19:20:14 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 19:20:14 INFO - PROCESS | 1662 | __metrics 19:20:15 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:20:15 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:20:15 INFO - TEST-INFO | 1662: exit 0 19:20:15 INFO - Browser initialized. 19:20:15 INFO - Running cycle 1/1 for dromaeo_css test... 19:20:15 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpXlPINO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:20:27 INFO - PROCESS | 1665 | RSS: Main: 233627648 19:20:27 INFO - PROCESS | 1665 | 19:21:19 INFO - PROCESS | 1665 | 1473733279128 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:23:30 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 19:23:32 INFO - PROCESS | 1665 | RSS: Main: 395661312 19:23:32 INFO - PROCESS | 1665 | 19:26:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 19:26:35 INFO - PROCESS | 1665 | RSS: Main: 368234496 19:26:35 INFO - PROCESS | 1665 | 19:29:37 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 19:29:38 INFO - PROCESS | 1665 | RSS: Main: 353173504 19:29:38 INFO - PROCESS | 1665 | 19:32:40 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 19:32:41 INFO - PROCESS | 1665 | RSS: Main: 340930560 19:32:41 INFO - PROCESS | 1665 | 19:35:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 19:35:45 INFO - PROCESS | 1665 | RSS: Main: 329490432 19:35:45 INFO - PROCESS | 1665 | 19:38:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 19:38:49 INFO - PROCESS | 1665 | RSS: Main: 405618688 19:38:49 INFO - PROCESS | 1665 | 19:38:49 INFO - PROCESS | 1665 | __start_tp_report 19:38:49 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 19:38:49 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 19:38:49 INFO - PROCESS | 1665 | |i|pagename|runs| 19:38:49 INFO - PROCESS | 1665 | |0;dojo.html;3339.3488269787395;3352.3462924729674;3372.7301815854717;3409.1817963688723;3430.0395889151046;5654.46282603152;5739.4547517985775;5779.595428320019;5796.217510636068;5818.505427038702;5744.885102297952;5840.386759390256;5845.912311315334;5893.705314734268;5925.911111333334;4283.700140990132;5162.174052151657;5194.558462530689;5248.5276325130735;5287.894242115175;5364.463553644624;5372.677639341652;5373.581461788737;5388;5435.619506634537;5196.0387328343995;5323.068463018956;5340.49265319794;5358.59810514211;5383.973080134594;5241;5303.7082960437165;5308.309919710413;5310;5312.867178320535;5229.346331708536;5236.24074509194;5262.368515778131;5283.788648454057;5290.100682883919;5775.537956963432;5779.624324418942;5782.161586569924;5785.595008349417;5791.189233507313;6184.721687524072;6217.067439884008;6237.126802247689;6239.87520249597;6258.030005349178;6209;6217.409346112112;6256.562040657156;6264.906026409608;6284.088807122942;5919.4080591940465;5923.644581325091;5933.3473317934995;5936.7328470218945;5938.881222375615;6184.288806787187;6216.347283535254;6234.844128896723;6251.12484252205;6276.184096067574;6244.968775156095;6245.5628106032145;6263.592866463665;6265.624062556261;6267.4986001119805;6276.403741644536;6281.685915704196;6285.811425657238;6302.653354065479;6308.148399966038;5177.559907407913;6126.456603435028;6177.351378105324;6214.906776398358;6305.432511074026;6292.653904035231;6305.3379395163765;6317.431431171125;6318.368163183645;6329.208848893888;5044.697318160922;5057.115004874132;5060.822871199491;5065.56942659878;5098.643094983316;4427.136708341907;4446.177457170434;4478.462584489817;4533.048059907391;4572.588467037983;5734.741936612779;5769.048107062372;5786.450287222707;5809.186713860063;5882.058870580687;6194.4115309045565;6263.530235232284;6267.4986001119805;6267.71795269214;6272.18461599989;5657.4059723728415;5801.158831969341;5811.070228763378;5819.5344372450945;5843.532517398683;5382.327209098863;5475.698836563947;5506.311711036121;5507.917381239285;5512.366077901012;6349;6353.4599559038;6378.202724659418;6379.043143528508;6381.8085457436355;6211.409916057967;6262.279837818709;6267.2165979252595;6278.121063051085;6285.905711414424;6202.41077097675;6252.437280644764;6294.433500984934;6295.9370406297185;6356.745730170926;6200.3179650237535;6275.152854364603;6275.435210831047;6290.21372328459;6296.212973378328;6094.725737341919;6244.5316601256;6266.874662506633;6269.874602508015;6284.937150628618;6044.939550604614;6090.330063693079;6194.659293738888;6203.96898015507;6267.059941008885;6121.7273510529185;6146.555559443616;6146.8935591593945;6149.169862068564;6168.784092556739;6117.736059405397;6130.816075517741;6135.570510064253;6146.770645870754;6152.446279834837;6198.349173336735;6210.192674952227;6229.750809967551;6243.500519958483;6243.937560624336;5921.111833225051;5925.170476133431;5930.822075337746;6123.938760612515;6128 19:38:49 INFO - PROCESS | 1665 | |1;ext.html;469.46012086101;486.33372279976413;525.0182159361992;526.4261954469628;528.5850607273289;31805.840970795143;32186.678133218662;41455.963600909956;42689.14621707564;44221;43242.56757432433;43583;43671.56328436715;43674.06933375996;43956.12087758243;8701;9802.509874506264;9874.654387096452;9976.002399760038;9980.451075190862;8735.694250701226;8927.330450216226;12113.878861211364;12387.690307742334;12430;12165.330906800122;12261.816072758917;12267.386630668476;12268.877311226863;12285.570005049825;12198.512059517609;12271.509139634403;12281;12281.57014504497;12284.5700400486;12044.518219271305;12069.336186509738;12125.39373031345;12135.696607584792;12139.635810925687;40884;41151.7942410289;41180;41199.38200926989;41256.38115428271;3310.5696259486353;3726.2920045191327;3772.6227377262053;4016.3373043447796;4115;28698.569521456986;28795.28011799722;30504.694953050628;31677.8416107918;31967.84016079928;3182.8726850925937;3300.7029367356813;3323.11937336606;3349.6768776333456;3399.7450191235757;505985;506966;507542;508699;511430.9999999963;61439;61689;61699.383006169366;61701.074483882774;61931;64251.678741605996;64450;65048;65098.02352964709;65099.67450162719;16368.918155409385;16597.668046639;16598.834011659972;16599.50201493957;16602;11696.590619328283;11952.22310549829;12780.297083660302;12840;12858.421371038326;17547.649047019244;17960.55098622519;17971.191296391433;18386.448406547825;18420.078996050142;6111.419415155553;6217.96891015542;6259.6870156492;6288.622682638965;6312.4434603985455;854.9914500854912;933.1927882381691;960.7406000379998;1027.525500906187;1049.338916482611;29553.704462955095;29709.702902971127;29879.850600746857;30375.696243037728;30655.540166897514;1028.5885645741764;1044.5665049004579;1054.225144518778;1071.3090056913404;1073.1146803886668;15188.392464301525;15355.539333820003;15370.615734606501;15489.915705900705;15834.129122898352;641929;645872;648026;648037.7598112073;649094;47291.763541182074;57525.42474575285;57726;57802.42197578055;57856;1311.8819306262483;1341.8456877459023;1358.4634069542674;1363.8977076719286;1364.1542243808901;26049.6092558608;29018.27454313659;29260.853695731385;29520.671569779843;30380.544291836082;51313.48686513087;51713.48286517087;51718.74140629273;51724.482755171965;51851.481485186174;522354;550889.4911050838;574854;577264;579046;35224.29551409009;35841.82079089588;40898.182036358514;41061.58938410578;41404.1719165621;45978.31032534582;46974.76512617415;47711.522884772094;48044;48064.75967620139;29833;30057.849710751307;38789.3067692646;38864;39245;39193.608063919;39363.409548856216;39817.2036559273;40457.1908561833;40689;37833;38362;38433;38739.03152421212;38853.80573097229;31593.36813263768;34098;34348.48477272793;34367.82816085903;34475.82762086173;24967.001319947725;25108.62337064909;25314.367140821625;25531.106411276258;25609.103681371063 19:38:49 INFO - PROCESS | 1665 | |2;jquery.html;358.1136686700418;370.11542413631423;376.8040618878183;380.5262448251927;404.8886556197047;12651.987840972723;15192.088474691527;15614.76577851333;20617.484562885937;20841.583168336663;19339.70990435141;19937.900310498426;20273.59452810943;20568.794312056874;20858.68711969318;4730.95918897842;5867.266591676052;6122.540809439288;6126.111713801496;6147.047207682816;5991.910121348184;6063;6095.299040610343;6113.296970848365;6121.418465245794;5782.537397008229;5855;5862.9446699594055;6042.214512113441;6053.818385448443;5853.239078919734;5938.584299099065;5950;5953.523718102541;5959.7020148992815;4911.214205727067;5124.897502049938;5178.844634660929;5190.974045129788;5192.766325515361;25070.49859002828;25131.87434062836;25421.49157016849;25535.74464255371;25548.48903021929;2177.912883484659;2185.6830759539775;2390.71311442628;2442.511497700467;3004.158835526056;14622.195779232137;14728.926355368261;15257.313420896086;15585.766213506806;15590.376384944702;1674.1545519512567;1707.4450803488917;1727.3954116059404;1749.5303944686398;1910.2168111074532;119975;124304;126189.36905315507;128419;128726;8814.955925220333;10259.897401025893;10559.683209503728;10856.457177141112;11042.503087361089;13610.727785444433;13629.318534073256;13659.385327660279;13673.24797136167;13688;7347.669354879044;8578.270846977952;8586.957065214634;8746.38775285724;8774.122587741174;8654.307655387554;8663.48019118855;8668.60991255395;8834.646614135363;8880.37837351392;6439.581427207217;6570.737170513126;6586.934130658632;6613.60318380889;6711.530192886451;1795.434438151978;1826.360773729184;1833.6974399224246;1841.0610588599911;1855.3784482198346;861.5562985062697;902.4585248850638;935.3826474526779;941.2940294778916;987.8666380038746;20637.58724825495;21993.120275188812;22073.896305184677;22126.336209913727;22140.003699833545;888.6889588644052;950.8526178442214;984.7538115471132;1009.1926458832904;1011.1556850030161;9574.377665451862;10779.946100269448;11166.715827679758;11541.307521548566;11648.767024659459;563505;566797;578425;580435;581760;100125.49811752829;111906.44046779876;115637;118544;122101;979.5445118020154;996.2776986684596;1096.6929259807266;1098.7363032872054;1098.96153634624;18967.525811854815;19460;19489.805101948798;19500.414987550397;19515.024248787788;50897.74551127221;51584.74207628938;51796.482035179164;52311.47688523218;52555.21167182419;71513.21216969617;73281;73459.63270183615;74632;75319;7678.500897441649;8032.718854840053;8044.35090806395;8044.798880028046;8303.750887473385;12711.936440317739;12990.740185196431;13429.462821487421;13475.19148851072;13527.120737152054;8072.475289106189;11064.557417703523;11171.72070698239;11378.772424551627;11672.358020308964;10972;11221.382823944761;11389.202755807013;11421.573974001778;11685.941570292094;9107.27141828639;11529.711757206136;11600.6519804406;11790.056795456514;11867.58463453775;7756.650950707119;7903.367730581424;7929.801754956172;7980.840383192188;8260.380471464545;10617.416042117758;11296.43517824122;11549.480273387886;11627.767444651228;11837 19:38:49 INFO - PROCESS | 1665 | |3;mootools.html;1331.454103817435;1338.9489250938016;1466.6553359960408;1621.505440840544;1846.1599972012743;7781.338586220171;8264.669413223472;8622.482651040935;8804.075572064938;8910.420822646523;7388.704451821921;8709.346798990086;8810.29517638589;8822.647094116244;8834.86747698785;2362.456634973957;2395.3652282145244;2403.3030421177837;2405.434722840131;2408.614621660535;2353.9411514712183;2385.9642105368434;2408.4821763320883;2408.759124087595;2411.1560953666162;2362.8109751219945;2383.392234980078;2391.473875747333;2400.699912510936;2405.5910495215767;2337.0301324950124;2361.421451744325;2362.232274510791;2365.9526809463714;2377.1561095811085;2322.222055611372;2347.225415612844;2361.4686695493547;2365.5387199496104;2368.2421625079983;8770.12298770118;8806.64773409063;8890.555472226362;8892.688755893578;8895.955520222358;5224.764885580158;5291.1798671206025;5405.918911216335;5421.376541697677;5435.130379139358;3662.4140137578124;3755.5681096673684;3759.906002349936;3771.7925514096714;3772.641599048086;1081.1674212210307;1101.0971003776906;1123.6235860986578;1131.21945857359;1135.4152611405134;11714;11781.469833857496;11798.587049453314;11804.232724872856;11810.822837657355;4314.331278651814;4618.976905115453;4682.601978831769;4683.414573178353;4685.484596694361;7867.252611001945;9210.309226808016;9217.44695318283;9222.216111630452;9254.491002994766;5283.709395983258;5287.339082614673;5288.497592728685;5291.1798671206025;5307.416184219733;3956.208758248362;4432.047109871393;4905.53397427251;4911.926321105187;4945.159322915113;3684.871029513954;3755.455458958436;3818.8281527331337;3846.3268478212067;3854.3447613905705;1167.1946357013633;1172.2966220267772;1173.565780661161;1180.8464899563173;1190.7142285851332;803.2128514056141;887.3744010472601;892.2683399612256;906.5014242166914;939.8919124300656;5513.393526712139;5782.161586569924;6017.819465416044;6134.957057300269;6293.685315734195;763.050002746573;831.846108469935;836.8870202522703;897.1656359585518;924.3206243411088;3260.5272235525717;3870.5935876732538;3900.980495097563;3961.8415263389143;4039.6162364575907;10375.584976601003;10704.571817127227;10715.946420268005;10730.463476826128;10866.945665271624;10901.236913416144;10973.670789876316;10975.83536246957;10983.286086404518;11002.229843910849;8230.300424463987;8360.289375403037;8360.913081299514;8362.038365587914;8382.79043023917;5758.769649213985;5767.942320576741;5805.622634528742;5955.821325360246;6080.969595151996;9723.416595004323;9955.751106222402;10274.33216840903;10279.229057820843;10309.175265978854;10400.323978941344;10420.06219440254;10421.895781042092;10432.165426765992;10457.372557646566;5914.526837852875;7152.964235178791;7372.594507301936;7510.248975102446;7648.80877978055;8195.795105122419;8283.58582070906;8513.659453622033;8515.148485151436;8515.48907065578;7328.926710732824;7444.664990075351;7543.811404714926;7562.168161502121;7577.355924746458;7242.601656908921;7245.601491917995;7395.889061663972;7399.852002960019;7417.925820741723;6913.965430173018;7066.611336376549;7068.222495525591;7068.29317068289;7073.2573079827935;4893.657443979029;4935.925961110514;5029.44676085623;5078.7206703631655;5121.66709163918;4155.293600088053;4955.925661115159;5108.642395032312;5145.665531740425;5232.136697444879 19:38:49 INFO - PROCESS | 1665 | |4;prototype.html;249.99250022499325;305.5523657841262;313.66907912152675;317.5855508561328;321.39577594123045;4980.27785971034;5259.474052594738;5291.391489978653;5295.92056119158;5396.8111116110895;3960.9801950990204;4712.6069230154235;4760.11937791508;5105.514976077276;5228.398734145574;3811.399212330814;3944.0928586425066;3964.6431821136093;3983.9203215935663;3987.063040185554;3962.544307404642;3989.441478193041;3995.1809878974886;4008.8596899108393;4015.3976903464422;3471.0454624978206;3738.738288319819;3901.648851603355;3961.98019009906;3986.182832519341;3205.5512228288057;3446.327966046622;3782.5650050244026;3957;3966.7619942803526;2999.6700362960055;3161.573187619665;3455.706264967456;3467.2372078142766;3487.302539492112;4330.198913201068;4597.793099310539;5112.642115051948;5153.3558305211845;5156.716380599066;3626.9455958160647;4364.345348197764;4528.343390208434;4552.749598772066;4560.065186636732;4716.646251531149;4774.806298425394;5243.318368612094;5500.58745594082;5505.9724701376235;3805.1818858945458;3833.252515759428;3846.3845784674318;3856.743804874152;3859.1702783901596;4683.859484215479;4783.760811959388;5634.323881134249;5693.3452652945025;5739.311282646067;3224.2584205632365;3290.68738469845;3808.590821396101;3852.5376954765607;3902.414637804295;3624.9850041988284;3949.8420063197723;3950.7827069511454;3963.7225394222705;3993.800309984489;3777;4178.185253875526;4513.909721805546;4529.32060190974;4542.954570454319;4329.718568293051;4687.789049492781;5482.643628164236;5581.134924086773;5588.469095435927;1080.7190130565953;1297.48100759695;1318.7296604196117;1318.9934050329687;1333.7732585460494;799.988015160822;835.3609488741118;854.5513605357113;858.4205661178679;862.3963225742006;4676.836310729108;5044.369453818273;5090.541851233407;5191.714455704897;5202.843914682566;4617.030423611012;5583.497485226268;5670.319561652545;5713.800101978631;5783.566232532577;4197.349410841263;4471.821127154943;4660.347551342815;4835.347228124158;5143.331366922276;2650.5229058769605;2933.252020734699;2961.540961151025;2989.6412430508476;2993.850307484617;4689.95310046902;5002.574781143643;5384.946150538444;5822.60393402978;5891.322497912709;5246.658967167122;5490.149026900757;5739.2252045973255;5752.511036561939;5790.855228619234;5107.416700822757;5339.946600533945;5709.257796486513;5789.652620842763;5811.355833096016;5081.316045866864;5261.3686357637325;5631;5654.85862853432;5665.291838520185;5033.080813128304;5231.503007214308;5468.769526856426;5562.415946325577;5608.663480191201;5800.59395842287;5806.6806325650805;5822.621529600562;5847.158145184253;5871.647701137946;3440.587129544471;3580.409232476612;3657.524521812147;3758.5865554788406;3777.2445510897383;4643.117807616542;4853.684510506806;4908.410990680998;5610.34494824036;5698.344690360495;5449.855530338673;5453.2004438534395;5453.6821850712495;5520.779168833202;5571.98816851866;5253.369595648546;5434.619576629756;5473.808416705556;5491.176323551499;5512.6968016759465;5204.241751871514;5297.735113244245;5379.327584051994;5438.2386465894015;5453.209284653623;4470.195364834361;4564.995700945787;4840.6127509799835;5070.467600901852;5394.784208631768;4936.481669424658;4937.802487900443;5352;5383.16560933056;5459.69971651563 19:38:49 INFO - PROCESS | 1665 | |5;yui.html;722.4762047515555;775.4317857708762;797.0873350014234;819.8688209886418;825.3149885594956;15450.1502417367;15765.527034188979;16440.342386304532;16472.505824825246;16516.917415412907;14788;14942.252887355644;14959.476418325361;14973.401063957455;14982.250887455639;679.2697849811448;695.3220609905352;829.2992421403919;838.4633834346023;856.092541905582;844.957752112395;857.2456238510133;857.7083791510885;857.7126662568016;861.4271509446211;886.6586364249778;897.5287973813735;901.5131828812433;901.733988473402;905.4657751926362;886.7073865624328;887.5917078144062;889.1286539191564;890.8262888736699;891.8929728432597;530.2046929605592;547.3513886045049;547.8904219156185;666.2870728320651;667.1960287853125;10804.78390432187;12422.130450868442;12456.252624842538;12463.501459941592;14108.294585270798;14302.213378264192;14896.180710060942;14910.478133265393;14970.62573435662;14984.925075374662;11880.476656220646;11883.524659013628;11924.165308428413;12026.819597706042;12220.572279970247;3185.2514659055105;3617.3488772021024;3645.908852278688;3714.572824125233;3738.6261373862394;119073.40463297628;122428.77571224263;122697.77302227043;123167;123336.38331808374;11067.114630829514;11108.50011749457;11289.49197286124;11441.485133169028;11730.70673233176;21798.128074876826;23907.402314942265;23926.64110038351;23984;24005.399865003164;7733.149353571102;7948.920510794934;8022.879656805153;8069.6368663410285;8146.75559733209;4340.197063543254;4345.97827010863;4376.474823021258;4452.888677783081;4472.785970944404;3515.209077957439;3637.454381842692;3640.344737947195;3640.4539319102346;3641.2717456508803;531.4446403508343;641.6759536434071;652.0675434129146;652.3183273479226;653.0009086098278;634.7383066053442;650.8372906773307;668.4785867023729;676.202081543783;688.7004153193377;9057.77355566116;9178.86231706525;9281.164695177466;9507.66723164686;9602.807943841224;536.4287034308448;553.9532570860268;596.8179705189958;627.8210709947643;641.9711112999832;4390.7585082820115;4641.094986477672;4657.278121891112;4716.316134160527;4810.687305325143;124317;158794.2060289691;160519;167558;175618.36572451424;136932.31533842266;140500;141966;142508.28745856203;143436.28281858523;115137;120607.19089213668;123987.76012239943;124032;126545.10182347088;8969.192772650493;9096.226820720312;9204.263658907137;9257.166854983085;9311.009097076678;53914.92170156496;58949.5262618438;59447.70276148764;62873.883286922224;62907.6854615724;57309.42690573041;94039.52980235055;97989;98305.52541712023;98838;11813.527458901892;11979.34113623759;11982.64052078439;12245;12697.746045079231;11087.33475991443;13003.284819335027;13036.804447933098;13202.867971320164;13393.866061339262;12035.336394490058;12255.448504817126;12406.503739850667;12590.307533085768;12596.181248218836;11741.882581174079;12283.201591896499;12429.129960903012;12547.24716517012;12867.485300587872;11832.230904991147;11906.642800715992;12041.759164816829;12079.818802717791;12511.874881251071;10085.546150423395;10171.389716617026;10646.414447205478;10688.679339619825;10701.732456688645;10194.796104078025;10571.36571805694;10577.841332379867;10579;10593.099586534923 19:38:49 INFO - PROCESS | 1665 | __end_tp_report 19:38:49 INFO - PROCESS | 1665 | __start_cc_report 19:38:49 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1517 19:38:49 INFO - PROCESS | 1665 | __end_cc_report 19:38:49 INFO - PROCESS | 1665 | __startTimestamp1473734329185__endTimestamp 19:38:49 INFO - PROCESS | 1665 | 19:38:49 INFO - PROCESS | 1665 | ------- Summary: start ------- 19:38:49 INFO - PROCESS | 1665 | Number of tests: 6 19:38:49 INFO - PROCESS | 1665 | 19:38:49 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5803.67 Median:6117.74 stddev:639.65 (10.5%) stddev-sans-first:611.70 19:38:49 INFO - PROCESS | 1665 | Values: 3339.3 3352.3 3372.7 3409.2 3430.0 5654.5 5739.5 5779.6 5796.2 5818.5 5744.9 5840.4 5845.9 5893.7 5925.9 4283.7 5162.2 5194.6 5248.5 5287.9 5364.5 5372.7 5373.6 5388.0 5435.6 5196.0 5323.1 5340.5 5358.6 5384.0 5241.0 5303.7 5308.3 5310.0 5312.9 5229.3 5236.2 5262.4 5283.8 5290.1 5775.5 5779.6 5782.2 5785.6 5791.2 6184.7 6217.1 6237.1 6239.9 6258.0 6209.0 6217.4 6256.6 6264.9 6284.1 5919.4 5923.6 5933.3 5936.7 5938.9 6184.3 6216.3 6234.8 6251.1 6276.2 6245.0 6245.6 6263.6 6265.6 6267.5 6276.4 6281.7 6285.8 6302.7 6308.1 5177.6 6126.5 6177.4 6214.9 6305.4 6292.7 6305.3 6317.4 6318.4 6329.2 5044.7 5057.1 5060.8 5065.6 5098.6 4427.1 4446.2 4478.5 4533.0 4572.6 5734.7 5769.0 5786.5 5809.2 5882.1 6194.4 6263.5 6267.5 6267.7 6272.2 5657.4 5801.2 5811.1 5819.5 5843.5 5382.3 5475.7 5506.3 5507.9 5512.4 6349.0 6353.5 6378.2 6379.0 6381.8 6211.4 6262.3 6267.2 6278.1 6285.9 6202.4 6252.4 6294.4 6295.9 6356.7 6200.3 6275.2 6275.4 6290.2 6296.2 6094.7 6244.5 6266.9 6269.9 6284.9 6044.9 6090.3 6194.7 6204.0 6267.1 6121.7 6146.6 6146.9 6149.2 6168.8 6117.7 6130.8 6135.6 6146.8 6152.4 6198.3 6210.2 6229.8 6243.5 6243.9 5921.1 5925.2 5930.8 6123.9 6128.0 19:38:49 INFO - PROCESS | 1665 | 19:38:49 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:71048.68 Median:38397.50 stddev:153431.82 (399.6%) stddev-sans-first:153770.72 19:38:49 INFO - PROCESS | 1665 | Values: 469.5 486.3 525.0 526.4 528.6 31805.8 32186.7 41456.0 42689.1 44221.0 43242.6 43583.0 43671.6 43674.1 43956.1 8701.0 9802.5 9874.7 9976.0 9980.5 8735.7 8927.3 12113.9 12387.7 12430.0 12165.3 12261.8 12267.4 12268.9 12285.6 12198.5 12271.5 12281.0 12281.6 12284.6 12044.5 12069.3 12125.4 12135.7 12139.6 40884.0 41151.8 41180.0 41199.4 41256.4 3310.6 3726.3 3772.6 4016.3 4115.0 28698.6 28795.3 30504.7 31677.8 31967.8 3182.9 3300.7 3323.1 3349.7 3399.7 505985.0 506966.0 507542.0 508699.0 511431.0 61439.0 61689.0 61699.4 61701.1 61931.0 64251.7 64450.0 65048.0 65098.0 65099.7 16368.9 16597.7 16598.8 16599.5 16602.0 11696.6 11952.2 12780.3 12840.0 12858.4 17547.6 17960.6 17971.2 18386.4 18420.1 6111.4 6218.0 6259.7 6288.6 6312.4 855.0 933.2 960.7 1027.5 1049.3 29553.7 29709.7 29879.9 30375.7 30655.5 1028.6 1044.6 1054.2 1071.3 1073.1 15188.4 15355.5 15370.6 15489.9 15834.1 641929.0 645872.0 648026.0 648037.8 649094.0 47291.8 57525.4 57726.0 57802.4 57856.0 1311.9 1341.8 1358.5 1363.9 1364.2 26049.6 29018.3 29260.9 29520.7 30380.5 51313.5 51713.5 51718.7 51724.5 51851.5 522354.0 550889.5 574854.0 577264.0 579046.0 35224.3 35841.8 40898.2 41061.6 41404.2 45978.3 46974.8 47711.5 48044.0 48064.8 29833.0 30057.8 38789.3 38864.0 39245.0 39193.6 39363.4 39817.2 40457.2 40689.0 37833.0 38362.0 38433.0 38739.0 38853.8 31593.4 34098.0 34348.5 34367.8 34475.8 24967.0 25108.6 25314.4 25531.1 25609.1 19:38:49 INFO - PROCESS | 1665 | 19:38:49 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:34119.91 Median:25101.19 stddev:95821.72 (381.7%) stddev-sans-first:96056.99 19:38:49 INFO - PROCESS | 1665 | Values: 358.1 370.1 376.8 380.5 404.9 12652.0 15192.1 15614.8 20617.5 20841.6 19339.7 19937.9 20273.6 20568.8 20858.7 4731.0 5867.3 6122.5 6126.1 6147.0 5991.9 6063.0 6095.3 6113.3 6121.4 5782.5 5855.0 5862.9 6042.2 6053.8 5853.2 5938.6 5950.0 5953.5 5959.7 4911.2 5124.9 5178.8 5191.0 5192.8 25070.5 25131.9 25421.5 25535.7 25548.5 2177.9 2185.7 2390.7 2442.5 3004.2 14622.2 14728.9 15257.3 15585.8 15590.4 1674.2 1707.4 1727.4 1749.5 1910.2 119975.0 124304.0 126189.4 128419.0 128726.0 8815.0 10259.9 10559.7 10856.5 11042.5 13610.7 13629.3 13659.4 13673.2 13688.0 7347.7 8578.3 8587.0 8746.4 8774.1 8654.3 8663.5 8668.6 8834.6 8880.4 6439.6 6570.7 6586.9 6613.6 6711.5 1795.4 1826.4 1833.7 1841.1 1855.4 861.6 902.5 935.4 941.3 987.9 20637.6 21993.1 22073.9 22126.3 22140.0 888.7 950.9 984.8 1009.2 1011.2 9574.4 10779.9 11166.7 11541.3 11648.8 563505.0 566797.0 578425.0 580435.0 581760.0 100125.5 111906.4 115637.0 118544.0 122101.0 979.5 996.3 1096.7 1098.7 1099.0 18967.5 19460.0 19489.8 19500.4 19515.0 50897.7 51584.7 51796.5 52311.5 52555.2 71513.2 73281.0 73459.6 74632.0 75319.0 7678.5 8032.7 8044.4 8044.8 8303.8 12711.9 12990.7 13429.5 13475.2 13527.1 8072.5 11064.6 11171.7 11378.8 11672.4 10972.0 11221.4 11389.2 11421.6 11685.9 9107.3 11529.7 11600.7 11790.1 11867.6 7756.7 7903.4 7929.8 7980.8 8260.4 10617.4 11296.4 11549.5 11627.8 11837.0 19:38:49 INFO - PROCESS | 1665 | 19:38:49 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5691.74 Median:4950.54 stddev:3210.33 (64.8%) stddev-sans-first:3202.61 19:38:49 INFO - PROCESS | 1665 | Values: 1331.5 1338.9 1466.7 1621.5 1846.2 7781.3 8264.7 8622.5 8804.1 8910.4 7388.7 8709.3 8810.3 8822.6 8834.9 2362.5 2395.4 2403.3 2405.4 2408.6 2353.9 2386.0 2408.5 2408.8 2411.2 2362.8 2383.4 2391.5 2400.7 2405.6 2337.0 2361.4 2362.2 2366.0 2377.2 2322.2 2347.2 2361.5 2365.5 2368.2 8770.1 8806.6 8890.6 8892.7 8896.0 5224.8 5291.2 5405.9 5421.4 5435.1 3662.4 3755.6 3759.9 3771.8 3772.6 1081.2 1101.1 1123.6 1131.2 1135.4 11714.0 11781.5 11798.6 11804.2 11810.8 4314.3 4619.0 4682.6 4683.4 4685.5 7867.3 9210.3 9217.4 9222.2 9254.5 5283.7 5287.3 5288.5 5291.2 5307.4 3956.2 4432.0 4905.5 4911.9 4945.2 3684.9 3755.5 3818.8 3846.3 3854.3 1167.2 1172.3 1173.6 1180.8 1190.7 803.2 887.4 892.3 906.5 939.9 5513.4 5782.2 6017.8 6135.0 6293.7 763.1 831.8 836.9 897.2 924.3 3260.5 3870.6 3901.0 3961.8 4039.6 10375.6 10704.6 10715.9 10730.5 10866.9 10901.2 10973.7 10975.8 10983.3 11002.2 8230.3 8360.3 8360.9 8362.0 8382.8 5758.8 5767.9 5805.6 5955.8 6081.0 9723.4 9955.8 10274.3 10279.2 10309.2 10400.3 10420.1 10421.9 10432.2 10457.4 5914.5 7153.0 7372.6 7510.2 7648.8 8195.8 8283.6 8513.7 8515.1 8515.5 7328.9 7444.7 7543.8 7562.2 7577.4 7242.6 7245.6 7395.9 7399.9 7417.9 6914.0 7066.6 7068.2 7068.3 7073.3 4893.7 4935.9 5029.4 5078.7 5121.7 4155.3 4955.9 5108.6 5145.7 5232.1 19:38:49 INFO - PROCESS | 1665 | 19:38:49 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4388.88 Median:4837.98 stddev:1350.93 (27.9%) stddev-sans-first:1318.52 19:38:49 INFO - PROCESS | 1665 | Values: 250.0 305.6 313.7 317.6 321.4 4980.3 5259.5 5291.4 5295.9 5396.8 3961.0 4712.6 4760.1 5105.5 5228.4 3811.4 3944.1 3964.6 3983.9 3987.1 3962.5 3989.4 3995.2 4008.9 4015.4 3471.0 3738.7 3901.6 3962.0 3986.2 3205.6 3446.3 3782.6 3957.0 3966.8 2999.7 3161.6 3455.7 3467.2 3487.3 4330.2 4597.8 5112.6 5153.4 5156.7 3626.9 4364.3 4528.3 4552.7 4560.1 4716.6 4774.8 5243.3 5500.6 5506.0 3805.2 3833.3 3846.4 3856.7 3859.2 4683.9 4783.8 5634.3 5693.3 5739.3 3224.3 3290.7 3808.6 3852.5 3902.4 3625.0 3949.8 3950.8 3963.7 3993.8 3777.0 4178.2 4513.9 4529.3 4543.0 4329.7 4687.8 5482.6 5581.1 5588.5 1080.7 1297.5 1318.7 1319.0 1333.8 800.0 835.4 854.6 858.4 862.4 4676.8 5044.4 5090.5 5191.7 5202.8 4617.0 5583.5 5670.3 5713.8 5783.6 4197.3 4471.8 4660.3 4835.3 5143.3 2650.5 2933.3 2961.5 2989.6 2993.9 4690.0 5002.6 5384.9 5822.6 5891.3 5246.7 5490.1 5739.2 5752.5 5790.9 5107.4 5339.9 5709.3 5789.7 5811.4 5081.3 5261.4 5631.0 5654.9 5665.3 5033.1 5231.5 5468.8 5562.4 5608.7 5800.6 5806.7 5822.6 5847.2 5871.6 3440.6 3580.4 3657.5 3758.6 3777.2 4643.1 4853.7 4908.4 5610.3 5698.3 5449.9 5453.2 5453.7 5520.8 5572.0 5253.4 5434.6 5473.8 5491.2 5512.7 5204.2 5297.7 5379.3 5438.2 5453.2 4470.2 4565.0 4840.6 5070.5 5394.8 4936.5 4937.8 5352.0 5383.2 5459.7 19:38:49 INFO - PROCESS | 1665 | 19:38:49 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:25799.50 Median:3350.23 stddev:42813.79 (1277.9%) stddev-sans-first:42892.50 19:38:49 INFO - PROCESS | 1665 | Values: 722.5 775.4 797.1 819.9 825.3 15450.2 15765.5 16440.3 16472.5 16516.9 14788.0 14942.3 14959.5 14973.4 14982.3 679.3 695.3 829.3 838.5 856.1 845.0 857.2 857.7 857.7 861.4 886.7 897.5 901.5 901.7 905.5 886.7 887.6 889.1 890.8 891.9 530.2 547.4 547.9 666.3 667.2 10804.8 12422.1 12456.3 12463.5 14108.3 14302.2 14896.2 14910.5 14970.6 14984.9 11880.5 11883.5 11924.2 12026.8 12220.6 3185.3 3617.3 3645.9 3714.6 3738.6 119073.4 122428.8 122697.8 123167.0 123336.4 11067.1 11108.5 11289.5 11441.5 11730.7 21798.1 23907.4 23926.6 23984.0 24005.4 7733.1 7948.9 8022.9 8069.6 8146.8 4340.2 4346.0 4376.5 4452.9 4472.8 3515.2 3637.5 3640.3 3640.5 3641.3 531.4 641.7 652.1 652.3 653.0 634.7 650.8 668.5 676.2 688.7 9057.8 9178.9 9281.2 9507.7 9602.8 536.4 554.0 596.8 627.8 642.0 4390.8 4641.1 4657.3 4716.3 4810.7 124317.0 158794.2 160519.0 167558.0 175618.4 136932.3 140500.0 141966.0 142508.3 143436.3 115137.0 120607.2 123987.8 124032.0 126545.1 8969.2 9096.2 9204.3 9257.2 9311.0 53914.9 58949.5 59447.7 62873.9 62907.7 57309.4 94039.5 97989.0 98305.5 98838.0 11813.5 11979.3 11982.6 12245.0 12697.7 11087.3 13003.3 13036.8 13202.9 13393.9 12035.3 12255.4 12406.5 12590.3 12596.2 11741.9 12283.2 12429.1 12547.2 12867.5 11832.2 11906.6 12041.8 12079.8 12511.9 10085.5 10171.4 10646.4 10688.7 10701.7 10194.8 10571.4 10577.8 10579.0 10593.1 19:38:49 INFO - PROCESS | 1665 | -------- Summary: end -------- 19:38:49 INFO - PROCESS | 1665 | 19:38:49 INFO - TEST-INFO | 1665: exit 0 19:38:49 INFO - TEST-OK | dromaeo_css | took 1117205ms 19:38:49 INFO - TEST-START | kraken 19:38:49 INFO - operating with platform_type : mac_ 19:38:49 INFO - Initialising browser for kraken test... 19:38:49 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpfKMihC/profile http://localhost:49275/getInfo.html) 19:38:52 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 19:38:52 INFO - PROCESS | 1674 | colorDepth:24 19:38:52 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 19:38:52 INFO - PROCESS | 1674 | __metrics 19:38:52 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:38:52 INFO - PROCESS | 1674 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:38:53 INFO - TEST-INFO | 1674: exit 0 19:38:53 INFO - Browser initialized. 19:38:53 INFO - Running cycle 1/1 for kraken test... 19:38:53 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpfKMihC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:39:05 INFO - PROCESS | 1675 | RSS: Main: 233979904 19:39:05 INFO - PROCESS | 1675 | 19:39:07 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 19:39:08 INFO - PROCESS | 1675 | RSS: Main: 292515840 19:39:08 INFO - PROCESS | 1675 | 19:39:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 19:39:11 INFO - PROCESS | 1675 | RSS: Main: 287825920 19:39:11 INFO - PROCESS | 1675 | 19:39:15 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 19:39:16 INFO - PROCESS | 1675 | RSS: Main: 376086528 19:39:16 INFO - PROCESS | 1675 | 19:39:18 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 19:39:18 INFO - PROCESS | 1675 | RSS: Main: 278142976 19:39:18 INFO - PROCESS | 1675 | 19:39:20 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:39:21 INFO - PROCESS | 1675 | RSS: Main: 283095040 19:39:21 INFO - PROCESS | 1675 | 19:39:24 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 19:39:24 INFO - PROCESS | 1675 | RSS: Main: 321753088 19:39:24 INFO - PROCESS | 1675 | 19:39:27 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 19:39:28 INFO - PROCESS | 1675 | RSS: Main: 320729088 19:39:28 INFO - PROCESS | 1675 | 19:39:31 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 19:39:31 INFO - PROCESS | 1675 | RSS: Main: 322191360 19:39:31 INFO - PROCESS | 1675 | 19:39:33 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:39:33 INFO - PROCESS | 1675 | RSS: Main: 298246144 19:39:33 INFO - PROCESS | 1675 | 19:39:35 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:39:36 INFO - PROCESS | 1675 | RSS: Main: 305123328 19:39:36 INFO - PROCESS | 1675 | 19:39:38 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:39:39 INFO - PROCESS | 1675 | RSS: Main: 307265536 19:39:39 INFO - PROCESS | 1675 | 19:39:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:39:43 INFO - PROCESS | 1675 | RSS: Main: 320598016 19:39:43 INFO - PROCESS | 1675 | 19:39:46 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:39:46 INFO - PROCESS | 1675 | RSS: Main: 316661760 19:39:46 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 19:39:48 INFO - PROCESS | 1675 | RSS: Main: 360042496 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | __start_tp_report 19:39:48 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 19:39:48 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 19:39:48 INFO - PROCESS | 1675 | |i|pagename|runs| 19:39:48 INFO - PROCESS | 1675 | |0;ai-astar;113;114;116;112;112;114;113;112;113;122 19:39:48 INFO - PROCESS | 1675 | |1;audio-beat-detection;145;116;167;160;138;162;128;133;161;107 19:39:48 INFO - PROCESS | 1675 | |2;audio-dft;185;183;183;183;185;223;177;181;183;184 19:39:48 INFO - PROCESS | 1675 | |3;audio-fft;75;74;77;156;59;58;58;64;144;57 19:39:48 INFO - PROCESS | 1675 | |4;audio-oscillator;83;84;130;71;70;79;119;70;96;150 19:39:48 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;86;83;89;83;91;90;89;83;89;83 19:39:48 INFO - PROCESS | 1675 | |6;imaging-darkroom;92;97;93;93;92;92;94;92;93;93 19:39:48 INFO - PROCESS | 1675 | |7;imaging-desaturate;83;85;83;84;84;83;86;83;84;84 19:39:48 INFO - PROCESS | 1675 | |8;json-parse-financial;53;53;57;55;55;54;55;53;52;52 19:39:48 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;65;65;70;70;72;74;69;69;70;71 19:39:48 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;95;102;88;83;87;87;82;88;90;87 19:39:48 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;158;154;149;156;148;148;126;161;199;156 19:39:48 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;169;167;170;222;238;170;168;169;172;168 19:39:48 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;67;67;69;80;87;86;87;70;66;66 19:39:48 INFO - PROCESS | 1675 | __end_tp_report 19:39:48 INFO - PROCESS | 1675 | __start_cc_report 19:39:48 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,2021 19:39:48 INFO - PROCESS | 1675 | __end_cc_report 19:39:48 INFO - PROCESS | 1675 | __startTimestamp1473734388799__endTimestamp 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | ------- Summary: start ------- 19:39:48 INFO - PROCESS | 1675 | Number of tests: 14 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:114.10 Median:113.50 stddev:3.03 (2.7%) stddev-sans-first:3.19 19:39:48 INFO - PROCESS | 1675 | Values: 113.0 114.0 116.0 112.0 112.0 114.0 113.0 112.0 113.0 122.0 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:152.50 stddev:20.85 (13.7%) stddev-sans-first:22.08 19:39:48 INFO - PROCESS | 1675 | Values: 145.0 116.0 167.0 160.0 138.0 162.0 128.0 133.0 161.0 107.0 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:186.70 Median:183.50 stddev:12.96 (7.1%) stddev-sans-first:13.73 19:39:48 INFO - PROCESS | 1675 | Values: 185.0 183.0 183.0 183.0 185.0 223.0 177.0 181.0 183.0 184.0 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:82.20 Median:61.50 stddev:36.65 (59.6%) stddev-sans-first:38.78 19:39:48 INFO - PROCESS | 1675 | Values: 75.0 74.0 77.0 156.0 59.0 58.0 58.0 64.0 144.0 57.0 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:75.00 stddev:28.23 (37.6%) stddev-sans-first:29.60 19:39:48 INFO - PROCESS | 1675 | Values: 83.0 84.0 130.0 71.0 70.0 79.0 119.0 70.0 96.0 150.0 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:89.00 stddev:3.34 (3.8%) stddev-sans-first:3.54 19:39:48 INFO - PROCESS | 1675 | Values: 86.0 83.0 89.0 83.0 91.0 90.0 89.0 83.0 89.0 83.0 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:1.52 (1.6%) stddev-sans-first:1.56 19:39:48 INFO - PROCESS | 1675 | Values: 92.0 97.0 93.0 93.0 92.0 92.0 94.0 92.0 93.0 93.0 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:84.00 stddev:0.99 (1.2%) stddev-sans-first:1.00 19:39:48 INFO - PROCESS | 1675 | Values: 83.0 85.0 83.0 84.0 84.0 83.0 86.0 83.0 84.0 84.0 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.50 stddev:1.60 (2.9%) stddev-sans-first:1.66 19:39:48 INFO - PROCESS | 1675 | Values: 53.0 53.0 57.0 55.0 55.0 54.0 55.0 53.0 52.0 52.0 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:70.00 stddev:2.80 (4.0%) stddev-sans-first:2.45 19:39:48 INFO - PROCESS | 1675 | Values: 65.0 65.0 70.0 70.0 72.0 74.0 69.0 69.0 70.0 71.0 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:87.50 stddev:5.82 (6.7%) stddev-sans-first:5.74 19:39:48 INFO - PROCESS | 1675 | Values: 95.0 102.0 88.0 83.0 87.0 87.0 82.0 88.0 90.0 87.0 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:155.50 Median:156.00 stddev:18.12 (11.6%) stddev-sans-first:19.20 19:39:48 INFO - PROCESS | 1675 | Values: 158.0 154.0 149.0 156.0 148.0 148.0 126.0 161.0 199.0 156.0 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.30 Median:170.00 stddev:25.98 (15.3%) stddev-sans-first:27.17 19:39:48 INFO - PROCESS | 1675 | Values: 169.0 167.0 170.0 222.0 238.0 170.0 168.0 169.0 172.0 168.0 19:39:48 INFO - PROCESS | 1675 | 19:39:48 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.50 Median:75.00 stddev:9.32 (12.4%) stddev-sans-first:9.49 19:39:48 INFO - PROCESS | 1675 | Values: 67.0 67.0 69.0 80.0 87.0 86.0 87.0 70.0 66.0 66.0 19:39:48 INFO - PROCESS | 1675 | -------- Summary: end -------- 19:39:48 INFO - PROCESS | 1675 | 19:39:49 INFO - TEST-INFO | 1675: exit 0 19:39:49 INFO - TEST-OK | kraken | took 59914ms 19:39:49 INFO - SUITE-END | took 1177s 19:39:49 INFO - Completed test suite (00:19:37) 19:39:49 INFO - javascript benchmark 19:39:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3339.3488269787395, 3352.3462924729674, 3372.7301815854717, 3409.1817963688723, 3430.0395889151046, 5654.46282603152, 5739.4547517985775, 5779.595428320019, 5796.217510636068, 5818.505427038702, 5744.885102297952, 5840.386759390256, 5845.912311315334, 5893.705314734268, 5925.911111333334, 4283.700140990132, 5162.174052151657, 5194.558462530689, 5248.5276325130735, 5287.894242115175, 5364.463553644624, 5372.677639341652, 5373.581461788737, 5388.0, 5435.619506634537, 5196.0387328343995, 5323.068463018956, 5340.49265319794, 5358.59810514211, 5383.973080134594, 5241.0, 5303.7082960437165, 5308.309919710413, 5310.0, 5312.867178320535, 5229.346331708536, 5236.24074509194, 5262.368515778131, 5283.788648454057, 5290.100682883919, 5775.537956963432, 5779.624324418942, 5782.161586569924, 5785.595008349417, 5791.189233507313, 6184.721687524072, 6217.067439884008, 6237.126802247689, 6239.87520249597, 6258.030005349178, 6209.0, 6217.409346112112, 6256.562040657156, 6264.906026409608, 6284.088807122942, 5919.4080591940465, 5923.644581325091, 5933.3473317934995, 5936.7328470218945, 5938.881222375615, 6184.288806787187, 6216.347283535254, 6234.844128896723, 6251.12484252205, 6276.184096067574, 6244.968775156095, 6245.5628106032145, 6263.592866463665, 6265.624062556261, 6267.4986001119805, 6276.403741644536, 6281.685915704196, 6285.811425657238, 6302.653354065479, 6308.148399966038, 5177.559907407913, 6126.456603435028, 6177.351378105324, 6214.906776398358, 6305.432511074026, 6292.653904035231, 6305.3379395163765, 6317.431431171125, 6318.368163183645, 6329.208848893888, 5044.697318160922, 5057.115004874132, 5060.822871199491, 5065.56942659878, 5098.643094983316, 4427.136708341907, 4446.177457170434, 4478.462584489817, 4533.048059907391, 4572.588467037983, 5734.741936612779, 5769.048107062372, 5786.450287222707, 5809.186713860063, 5882.058870580687, 6194.4115309045565, 6263.530235232284, 6267.4986001119805, 6267.71795269214, 6272.18461599989, 5657.4059723728415, 5801.158831969341, 5811.070228763378, 5819.5344372450945, 5843.532517398683, 5382.327209098863, 5475.698836563947, 5506.311711036121, 5507.917381239285, 5512.366077901012, 6349.0, 6353.4599559038, 6378.202724659418, 6379.043143528508, 6381.8085457436355, 6211.409916057967, 6262.279837818709, 6267.2165979252595, 6278.121063051085, 6285.905711414424, 6202.41077097675, 6252.437280644764, 6294.433500984934, 6295.9370406297185, 6356.745730170926, 6200.3179650237535, 6275.152854364603, 6275.435210831047, 6290.21372328459, 6296.212973378328, 6094.725737341919, 6244.5316601256, 6266.874662506633, 6269.874602508015, 6284.937150628618, 6044.939550604614, 6090.330063693079, 6194.659293738888, 6203.96898015507, 6267.059941008885, 6121.7273510529185, 6146.555559443616, 6146.8935591593945, 6149.169862068564, 6168.784092556739, 6117.736059405397, 6130.816075517741, 6135.570510064253, 6146.770645870754, 6152.446279834837, 6198.349173336735, 6210.192674952227, 6229.750809967551, 6243.500519958483, 6243.937560624336, 5921.111833225051, 5925.170476133431, 5930.822075337746, 6123.938760612515, 6128.0], "value": 5762.615741696215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.46012086101, 486.33372279976413, 525.0182159361992, 526.4261954469628, 528.5850607273289, 31805.840970795143, 32186.678133218662, 41455.963600909956, 42689.14621707564, 44221.0, 43242.56757432433, 43583.0, 43671.56328436715, 43674.06933375996, 43956.12087758243, 8701.0, 9802.509874506264, 9874.654387096452, 9976.002399760038, 9980.451075190862, 8735.694250701226, 8927.330450216226, 12113.878861211364, 12387.690307742334, 12430.0, 12165.330906800122, 12261.816072758917, 12267.386630668476, 12268.877311226863, 12285.570005049825, 12198.512059517609, 12271.509139634403, 12281.0, 12281.57014504497, 12284.5700400486, 12044.518219271305, 12069.336186509738, 12125.39373031345, 12135.696607584792, 12139.635810925687, 40884.0, 41151.7942410289, 41180.0, 41199.38200926989, 41256.38115428271, 3310.5696259486353, 3726.2920045191327, 3772.6227377262053, 4016.3373043447796, 4115.0, 28698.569521456986, 28795.28011799722, 30504.694953050628, 31677.8416107918, 31967.84016079928, 3182.8726850925937, 3300.7029367356813, 3323.11937336606, 3349.6768776333456, 3399.7450191235757, 505985.0, 506966.0, 507542.0, 508699.0, 511430.9999999963, 61439.0, 61689.0, 61699.383006169366, 61701.074483882774, 61931.0, 64251.678741605996, 64450.0, 65048.0, 65098.02352964709, 65099.67450162719, 16368.918155409385, 16597.668046639, 16598.834011659972, 16599.50201493957, 16602.0, 11696.590619328283, 11952.22310549829, 12780.297083660302, 12840.0, 12858.421371038326, 17547.649047019244, 17960.55098622519, 17971.191296391433, 18386.448406547825, 18420.078996050142, 6111.419415155553, 6217.96891015542, 6259.6870156492, 6288.622682638965, 6312.4434603985455, 854.9914500854912, 933.1927882381691, 960.7406000379998, 1027.525500906187, 1049.338916482611, 29553.704462955095, 29709.702902971127, 29879.850600746857, 30375.696243037728, 30655.540166897514, 1028.5885645741764, 1044.5665049004579, 1054.225144518778, 1071.3090056913404, 1073.1146803886668, 15188.392464301525, 15355.539333820003, 15370.615734606501, 15489.915705900705, 15834.129122898352, 641929.0, 645872.0, 648026.0, 648037.7598112073, 649094.0, 47291.763541182074, 57525.42474575285, 57726.0, 57802.42197578055, 57856.0, 1311.8819306262483, 1341.8456877459023, 1358.4634069542674, 1363.8977076719286, 1364.1542243808901, 26049.6092558608, 29018.27454313659, 29260.853695731385, 29520.671569779843, 30380.544291836082, 51313.48686513087, 51713.48286517087, 51718.74140629273, 51724.482755171965, 51851.481485186174, 522354.0, 550889.4911050838, 574854.0, 577264.0, 579046.0, 35224.29551409009, 35841.82079089588, 40898.182036358514, 41061.58938410578, 41404.1719165621, 45978.31032534582, 46974.76512617415, 47711.522884772094, 48044.0, 48064.75967620139, 29833.0, 30057.849710751307, 38789.3067692646, 38864.0, 39245.0, 39193.608063919, 39363.409548856216, 39817.2036559273, 40457.1908561833, 40689.0, 37833.0, 38362.0, 38433.0, 38739.03152421212, 38853.80573097229, 31593.36813263768, 34098.0, 34348.48477272793, 34367.82816085903, 34475.82762086173, 24967.001319947725, 25108.62337064909, 25314.367140821625, 25531.106411276258, 25609.103681371063], "value": 20595.208132455362, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.1136686700418, 370.11542413631423, 376.8040618878183, 380.5262448251927, 404.8886556197047, 12651.987840972723, 15192.088474691527, 15614.76577851333, 20617.484562885937, 20841.583168336663, 19339.70990435141, 19937.900310498426, 20273.59452810943, 20568.794312056874, 20858.68711969318, 4730.95918897842, 5867.266591676052, 6122.540809439288, 6126.111713801496, 6147.047207682816, 5991.910121348184, 6063.0, 6095.299040610343, 6113.296970848365, 6121.418465245794, 5782.537397008229, 5855.0, 5862.9446699594055, 6042.214512113441, 6053.818385448443, 5853.239078919734, 5938.584299099065, 5950.0, 5953.523718102541, 5959.7020148992815, 4911.214205727067, 5124.897502049938, 5178.844634660929, 5190.974045129788, 5192.766325515361, 25070.49859002828, 25131.87434062836, 25421.49157016849, 25535.74464255371, 25548.48903021929, 2177.912883484659, 2185.6830759539775, 2390.71311442628, 2442.511497700467, 3004.158835526056, 14622.195779232137, 14728.926355368261, 15257.313420896086, 15585.766213506806, 15590.376384944702, 1674.1545519512567, 1707.4450803488917, 1727.3954116059404, 1749.5303944686398, 1910.2168111074532, 119975.0, 124304.0, 126189.36905315507, 128419.0, 128726.0, 8814.955925220333, 10259.897401025893, 10559.683209503728, 10856.457177141112, 11042.503087361089, 13610.727785444433, 13629.318534073256, 13659.385327660279, 13673.24797136167, 13688.0, 7347.669354879044, 8578.270846977952, 8586.957065214634, 8746.38775285724, 8774.122587741174, 8654.307655387554, 8663.48019118855, 8668.60991255395, 8834.646614135363, 8880.37837351392, 6439.581427207217, 6570.737170513126, 6586.934130658632, 6613.60318380889, 6711.530192886451, 1795.434438151978, 1826.360773729184, 1833.6974399224246, 1841.0610588599911, 1855.3784482198346, 861.5562985062697, 902.4585248850638, 935.3826474526779, 941.2940294778916, 987.8666380038746, 20637.58724825495, 21993.120275188812, 22073.896305184677, 22126.336209913727, 22140.003699833545, 888.6889588644052, 950.8526178442214, 984.7538115471132, 1009.1926458832904, 1011.1556850030161, 9574.377665451862, 10779.946100269448, 11166.715827679758, 11541.307521548566, 11648.767024659459, 563505.0, 566797.0, 578425.0, 580435.0, 581760.0, 100125.49811752829, 111906.44046779876, 115637.0, 118544.0, 122101.0, 979.5445118020154, 996.2776986684596, 1096.6929259807266, 1098.7363032872054, 1098.96153634624, 18967.525811854815, 19460.0, 19489.805101948798, 19500.414987550397, 19515.024248787788, 50897.74551127221, 51584.74207628938, 51796.482035179164, 52311.47688523218, 52555.21167182419, 71513.21216969617, 73281.0, 73459.63270183615, 74632.0, 75319.0, 7678.500897441649, 8032.718854840053, 8044.35090806395, 8044.798880028046, 8303.750887473385, 12711.936440317739, 12990.740185196431, 13429.462821487421, 13475.19148851072, 13527.120737152054, 8072.475289106189, 11064.557417703523, 11171.72070698239, 11378.772424551627, 11672.358020308964, 10972.0, 11221.382823944761, 11389.202755807013, 11421.573974001778, 11685.941570292094, 9107.27141828639, 11529.711757206136, 11600.6519804406, 11790.056795456514, 11867.58463453775, 7756.650950707119, 7903.367730581424, 7929.801754956172, 7980.840383192188, 8260.380471464545, 10617.416042117758, 11296.43517824122, 11549.480273387886, 11627.767444651228, 11837.0], "value": 9672.066378342797, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.454103817435, 1338.9489250938016, 1466.6553359960408, 1621.505440840544, 1846.1599972012743, 7781.338586220171, 8264.669413223472, 8622.482651040935, 8804.075572064938, 8910.420822646523, 7388.704451821921, 8709.346798990086, 8810.29517638589, 8822.647094116244, 8834.86747698785, 2362.456634973957, 2395.3652282145244, 2403.3030421177837, 2405.434722840131, 2408.614621660535, 2353.9411514712183, 2385.9642105368434, 2408.4821763320883, 2408.759124087595, 2411.1560953666162, 2362.8109751219945, 2383.392234980078, 2391.473875747333, 2400.699912510936, 2405.5910495215767, 2337.0301324950124, 2361.421451744325, 2362.232274510791, 2365.9526809463714, 2377.1561095811085, 2322.222055611372, 2347.225415612844, 2361.4686695493547, 2365.5387199496104, 2368.2421625079983, 8770.12298770118, 8806.64773409063, 8890.555472226362, 8892.688755893578, 8895.955520222358, 5224.764885580158, 5291.1798671206025, 5405.918911216335, 5421.376541697677, 5435.130379139358, 3662.4140137578124, 3755.5681096673684, 3759.906002349936, 3771.7925514096714, 3772.641599048086, 1081.1674212210307, 1101.0971003776906, 1123.6235860986578, 1131.21945857359, 1135.4152611405134, 11714.0, 11781.469833857496, 11798.587049453314, 11804.232724872856, 11810.822837657355, 4314.331278651814, 4618.976905115453, 4682.601978831769, 4683.414573178353, 4685.484596694361, 7867.252611001945, 9210.309226808016, 9217.44695318283, 9222.216111630452, 9254.491002994766, 5283.709395983258, 5287.339082614673, 5288.497592728685, 5291.1798671206025, 5307.416184219733, 3956.208758248362, 4432.047109871393, 4905.53397427251, 4911.926321105187, 4945.159322915113, 3684.871029513954, 3755.455458958436, 3818.8281527331337, 3846.3268478212067, 3854.3447613905705, 1167.1946357013633, 1172.2966220267772, 1173.565780661161, 1180.8464899563173, 1190.7142285851332, 803.2128514056141, 887.3744010472601, 892.2683399612256, 906.5014242166914, 939.8919124300656, 5513.393526712139, 5782.161586569924, 6017.819465416044, 6134.957057300269, 6293.685315734195, 763.050002746573, 831.846108469935, 836.8870202522703, 897.1656359585518, 924.3206243411088, 3260.5272235525717, 3870.5935876732538, 3900.980495097563, 3961.8415263389143, 4039.6162364575907, 10375.584976601003, 10704.571817127227, 10715.946420268005, 10730.463476826128, 10866.945665271624, 10901.236913416144, 10973.670789876316, 10975.83536246957, 10983.286086404518, 11002.229843910849, 8230.300424463987, 8360.289375403037, 8360.913081299514, 8362.038365587914, 8382.79043023917, 5758.769649213985, 5767.942320576741, 5805.622634528742, 5955.821325360246, 6080.969595151996, 9723.416595004323, 9955.751106222402, 10274.33216840903, 10279.229057820843, 10309.175265978854, 10400.323978941344, 10420.06219440254, 10421.895781042092, 10432.165426765992, 10457.372557646566, 5914.526837852875, 7152.964235178791, 7372.594507301936, 7510.248975102446, 7648.80877978055, 8195.795105122419, 8283.58582070906, 8513.659453622033, 8515.148485151436, 8515.48907065578, 7328.926710732824, 7444.664990075351, 7543.811404714926, 7562.168161502121, 7577.355924746458, 7242.601656908921, 7245.601491917995, 7395.889061663972, 7399.852002960019, 7417.925820741723, 6913.965430173018, 7066.611336376549, 7068.222495525591, 7068.29317068289, 7073.2573079827935, 4893.657443979029, 4935.925961110514, 5029.44676085623, 5078.7206703631655, 5121.66709163918, 4155.293600088053, 4955.925661115159, 5108.642395032312, 5145.665531740425, 5232.136697444879], "value": 4557.433081269856, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [249.99250022499325, 305.5523657841262, 313.66907912152675, 317.5855508561328, 321.39577594123045, 4980.27785971034, 5259.474052594738, 5291.391489978653, 5295.92056119158, 5396.8111116110895, 3960.9801950990204, 4712.6069230154235, 4760.11937791508, 5105.514976077276, 5228.398734145574, 3811.399212330814, 3944.0928586425066, 3964.6431821136093, 3983.9203215935663, 3987.063040185554, 3962.544307404642, 3989.441478193041, 3995.1809878974886, 4008.8596899108393, 4015.3976903464422, 3471.0454624978206, 3738.738288319819, 3901.648851603355, 3961.98019009906, 3986.182832519341, 3205.5512228288057, 3446.327966046622, 3782.5650050244026, 3957.0, 3966.7619942803526, 2999.6700362960055, 3161.573187619665, 3455.706264967456, 3467.2372078142766, 3487.302539492112, 4330.198913201068, 4597.793099310539, 5112.642115051948, 5153.3558305211845, 5156.716380599066, 3626.9455958160647, 4364.345348197764, 4528.343390208434, 4552.749598772066, 4560.065186636732, 4716.646251531149, 4774.806298425394, 5243.318368612094, 5500.58745594082, 5505.9724701376235, 3805.1818858945458, 3833.252515759428, 3846.3845784674318, 3856.743804874152, 3859.1702783901596, 4683.859484215479, 4783.760811959388, 5634.323881134249, 5693.3452652945025, 5739.311282646067, 3224.2584205632365, 3290.68738469845, 3808.590821396101, 3852.5376954765607, 3902.414637804295, 3624.9850041988284, 3949.8420063197723, 3950.7827069511454, 3963.7225394222705, 3993.800309984489, 3777.0, 4178.185253875526, 4513.909721805546, 4529.32060190974, 4542.954570454319, 4329.718568293051, 4687.789049492781, 5482.643628164236, 5581.134924086773, 5588.469095435927, 1080.7190130565953, 1297.48100759695, 1318.7296604196117, 1318.9934050329687, 1333.7732585460494, 799.988015160822, 835.3609488741118, 854.5513605357113, 858.4205661178679, 862.3963225742006, 4676.836310729108, 5044.369453818273, 5090.541851233407, 5191.714455704897, 5202.843914682566, 4617.030423611012, 5583.497485226268, 5670.319561652545, 5713.800101978631, 5783.566232532577, 4197.349410841263, 4471.821127154943, 4660.347551342815, 4835.347228124158, 5143.331366922276, 2650.5229058769605, 2933.252020734699, 2961.540961151025, 2989.6412430508476, 2993.850307484617, 4689.95310046902, 5002.574781143643, 5384.946150538444, 5822.60393402978, 5891.322497912709, 5246.658967167122, 5490.149026900757, 5739.2252045973255, 5752.511036561939, 5790.855228619234, 5107.416700822757, 5339.946600533945, 5709.257796486513, 5789.652620842763, 5811.355833096016, 5081.316045866864, 5261.3686357637325, 5631.0, 5654.85862853432, 5665.291838520185, 5033.080813128304, 5231.503007214308, 5468.769526856426, 5562.415946325577, 5608.663480191201, 5800.59395842287, 5806.6806325650805, 5822.621529600562, 5847.158145184253, 5871.647701137946, 3440.587129544471, 3580.409232476612, 3657.524521812147, 3758.5865554788406, 3777.2445510897383, 4643.117807616542, 4853.684510506806, 4908.410990680998, 5610.34494824036, 5698.344690360495, 5449.855530338673, 5453.2004438534395, 5453.6821850712495, 5520.779168833202, 5571.98816851866, 5253.369595648546, 5434.619576629756, 5473.808416705556, 5491.176323551499, 5512.6968016759465, 5204.241751871514, 5297.735113244245, 5379.327584051994, 5438.2386465894015, 5453.209284653623, 4470.195364834361, 4564.995700945787, 4840.6127509799835, 5070.467600901852, 5394.784208631768, 4936.481669424658, 4937.802487900443, 5352.0, 5383.16560933056, 5459.69971651563], "value": 3959.9496281472857, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.4762047515555, 775.4317857708762, 797.0873350014234, 819.8688209886418, 825.3149885594956, 15450.1502417367, 15765.527034188979, 16440.342386304532, 16472.505824825246, 16516.917415412907, 14788.0, 14942.252887355644, 14959.476418325361, 14973.401063957455, 14982.250887455639, 679.2697849811448, 695.3220609905352, 829.2992421403919, 838.4633834346023, 856.092541905582, 844.957752112395, 857.2456238510133, 857.7083791510885, 857.7126662568016, 861.4271509446211, 886.6586364249778, 897.5287973813735, 901.5131828812433, 901.733988473402, 905.4657751926362, 886.7073865624328, 887.5917078144062, 889.1286539191564, 890.8262888736699, 891.8929728432597, 530.2046929605592, 547.3513886045049, 547.8904219156185, 666.2870728320651, 667.1960287853125, 10804.78390432187, 12422.130450868442, 12456.252624842538, 12463.501459941592, 14108.294585270798, 14302.213378264192, 14896.180710060942, 14910.478133265393, 14970.62573435662, 14984.925075374662, 11880.476656220646, 11883.524659013628, 11924.165308428413, 12026.819597706042, 12220.572279970247, 3185.2514659055105, 3617.3488772021024, 3645.908852278688, 3714.572824125233, 3738.6261373862394, 119073.40463297628, 122428.77571224263, 122697.77302227043, 123167.0, 123336.38331808374, 11067.114630829514, 11108.50011749457, 11289.49197286124, 11441.485133169028, 11730.70673233176, 21798.128074876826, 23907.402314942265, 23926.64110038351, 23984.0, 24005.399865003164, 7733.149353571102, 7948.920510794934, 8022.879656805153, 8069.6368663410285, 8146.75559733209, 4340.197063543254, 4345.97827010863, 4376.474823021258, 4452.888677783081, 4472.785970944404, 3515.209077957439, 3637.454381842692, 3640.344737947195, 3640.4539319102346, 3641.2717456508803, 531.4446403508343, 641.6759536434071, 652.0675434129146, 652.3183273479226, 653.0009086098278, 634.7383066053442, 650.8372906773307, 668.4785867023729, 676.202081543783, 688.7004153193377, 9057.77355566116, 9178.86231706525, 9281.164695177466, 9507.66723164686, 9602.807943841224, 536.4287034308448, 553.9532570860268, 596.8179705189958, 627.8210709947643, 641.9711112999832, 4390.7585082820115, 4641.094986477672, 4657.278121891112, 4716.316134160527, 4810.687305325143, 124317.0, 158794.2060289691, 160519.0, 167558.0, 175618.36572451424, 136932.31533842266, 140500.0, 141966.0, 142508.28745856203, 143436.28281858523, 115137.0, 120607.19089213668, 123987.76012239943, 124032.0, 126545.10182347088, 8969.192772650493, 9096.226820720312, 9204.263658907137, 9257.166854983085, 9311.009097076678, 53914.92170156496, 58949.5262618438, 59447.70276148764, 62873.883286922224, 62907.6854615724, 57309.42690573041, 94039.52980235055, 97989.0, 98305.52541712023, 98838.0, 11813.527458901892, 11979.34113623759, 11982.64052078439, 12245.0, 12697.746045079231, 11087.33475991443, 13003.284819335027, 13036.804447933098, 13202.867971320164, 13393.866061339262, 12035.336394490058, 12255.448504817126, 12406.503739850667, 12590.307533085768, 12596.181248218836, 11741.882581174079, 12283.201591896499, 12429.129960903012, 12547.24716517012, 12867.485300587872, 11832.230904991147, 11906.642800715992, 12041.759164816829, 12079.818802717791, 12511.874881251071, 10085.546150423395, 10171.389716617026, 10646.414447205478, 10688.679339619825, 10701.732456688645, 10194.796104078025, 10571.36571805694, 10577.841332379867, 10579.0, 10593.099586534923], "value": 7714.028411626418, "unit": "score"}], "name": "dromaeo_css", "value": 7366.720608289937, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 116.0, 112.0, 112.0, 114.0, 113.0, 112.0, 113.0, 122.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 167.0, 160.0, 138.0, 162.0, 128.0, 133.0, 161.0, 107.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 183.0, 183.0, 185.0, 223.0, 177.0, 181.0, 183.0, 184.0], "value": 186.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 77.0, 156.0, 59.0, 58.0, 58.0, 64.0, 144.0, 57.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 130.0, 71.0, 70.0, 79.0, 119.0, 70.0, 96.0, 150.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 89.0, 83.0, 91.0, 90.0, 89.0, 83.0, 89.0, 83.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 97.0, 93.0, 93.0, 92.0, 92.0, 94.0, 92.0, 93.0, 93.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 83.0, 84.0, 84.0, 83.0, 86.0, 83.0, 84.0, 84.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 57.0, 55.0, 55.0, 54.0, 55.0, 53.0, 52.0, 52.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 70.0, 70.0, 72.0, 74.0, 69.0, 69.0, 70.0, 71.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 102.0, 88.0, 83.0, 87.0, 87.0, 82.0, 88.0, 90.0, 87.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 154.0, 149.0, 156.0, 148.0, 148.0, 126.0, 161.0, 199.0, 156.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 170.0, 222.0, 238.0, 170.0, 168.0, 169.0, 172.0, 168.0], "value": 181.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 80.0, 87.0, 86.0, 87.0, 70.0, 66.0, 66.0], "value": 74.5, "unit": "score"}], "name": "kraken", "value": 1507.1000000000001, "alertThreshold": 2.0}]} 19:39:50 INFO - Return code: 0 19:39:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:39:50 INFO - # TBPL SUCCESS # 19:39:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:39:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:39:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:39:50 INFO - 2016-09-12 19:39:50.102 screenresolution[1679:11676] starting screenresolution argv=screenresolution get 19:39:50 INFO - 2016-09-12 19:39:50.104 screenresolution[1679:11676] Display 0: 1600x1200x32@60 19:39:50 INFO - 2016-09-12 19:39:50.110 screenresolution[1680:11679] starting screenresolution argv=screenresolution list 19:39:50 INFO - Available Modes on Display 0 19:39:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:39:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:39:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:39:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:39:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:39:50 INFO - Intel Iris: 19:39:50 INFO - Chipset Model: Intel Iris 19:39:50 INFO - Type: GPU 19:39:50 INFO - Bus: Built-In 19:39:50 INFO - VRAM (Dynamic, Max): 1536 MB 19:39:50 INFO - Vendor: Intel (0x8086) 19:39:50 INFO - Device ID: 0x0a2e 19:39:50 INFO - Revision ID: 0x0009 19:39:50 INFO - Displays: 19:39:50 INFO - Display: 19:39:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:39:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:39:50 INFO - Display Serial Number: 1600x1200 60 19:39:50 INFO - Main Display: Yes 19:39:50 INFO - Mirror: Off 19:39:50 INFO - Online: Yes 19:39:50 INFO - Rotation: Supported 19:39:50 INFO - Return code: 0 19:39:50 INFO - Running post-action listener: _resource_record_post_action 19:39:50 INFO - [mozharness: 2016-09-13 02:39:50.353590Z] Finished run-tests step (success) 19:39:50 INFO - Running post-run listener: _resource_record_post_run 19:39:50 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 32046080; Write bytes: 708509184; Read time: 207; Write time: 2024 19:39:50 INFO - TinderboxPrint: CPU usage
53.5% 19:39:50 INFO - TinderboxPrint: I/O read bytes / time
32,046,080 / 207 19:39:50 INFO - TinderboxPrint: I/O write bytes / time
708,509,184 / 2,024 19:39:50 INFO - TinderboxPrint: CPU idle
2,256.4 (46.5%) 19:39:50 INFO - TinderboxPrint: CPU system
968.0 (20.0%) 19:39:50 INFO - TinderboxPrint: CPU user
1,625.2 (33.5%) 19:39:50 INFO - TinderboxPrint: Swap in / out
434,728,960 / 0 19:39:50 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 388205056; Write bytes: 382105600; Read time: 29022; Write time: 827 19:39:50 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 31116288; Write bytes: 298295296; Read time: 196; Write time: 1131 19:39:50 INFO - Running post-run listener: _upload_blobber_files 19:39:50 INFO - Blob upload gear active. 19:39:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:39:50 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 19:39:50 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:39:50 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:39:51 INFO - (blobuploader) - INFO - Open directory for files ... 19:39:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:39:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:39:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:52 INFO - (blobuploader) - INFO - Done attempting. 19:39:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:39:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:39:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:53 INFO - (blobuploader) - INFO - Done attempting. 19:39:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:39:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:39:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:56 INFO - (blobuploader) - INFO - Done attempting. 19:39:56 INFO - (blobuploader) - INFO - Iteration through files over. 19:39:56 INFO - Return code: 0 19:39:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:39:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:39:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/725665031ddd8f080763db8a28c03582f331de8c2526253cc4e6ae563e600acd5848dda2c9c6c4ff6b9729deae7ffc7e4c9d802f2367a1ce85218e1bd45bb7c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2330a6a2759f4e9856f2c190db60bd0408a9974e15d0ac22fb74db7e5033dc34bf29833a077fd2f1fc3f58b8e9350465ecc1d9bf2dd72039c18569b7dde9b5f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e09f41d6f334e70e44b394619797ff33f13ec77a0a2389a96aa8c4e759e1b9b78d47d23d9fa171eb2038fe19abc192b172520a4f944dd96c436497bf14d48ff0"} 19:39:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:39:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:39:56 INFO - Contents: 19:39:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/725665031ddd8f080763db8a28c03582f331de8c2526253cc4e6ae563e600acd5848dda2c9c6c4ff6b9729deae7ffc7e4c9d802f2367a1ce85218e1bd45bb7c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2330a6a2759f4e9856f2c190db60bd0408a9974e15d0ac22fb74db7e5033dc34bf29833a077fd2f1fc3f58b8e9350465ecc1d9bf2dd72039c18569b7dde9b5f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e09f41d6f334e70e44b394619797ff33f13ec77a0a2389a96aa8c4e759e1b9b78d47d23d9fa171eb2038fe19abc192b172520a4f944dd96c436497bf14d48ff0"} 19:39:56 INFO - Running post-run listener: copy_logs_to_upload_dir 19:39:56 INFO - Copying logs to upload dir... 19:39:56 INFO - mkdir: /builds/slave/test/build/upload/logs 19:39:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.112568 ========= master_lag: 0.97 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-12 19:39:57.012478) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 19:39:57.016125) ========= 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.yel7ilVxPP/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.4GxbaHnejw/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/725665031ddd8f080763db8a28c03582f331de8c2526253cc4e6ae563e600acd5848dda2c9c6c4ff6b9729deae7ffc7e4c9d802f2367a1ce85218e1bd45bb7c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2330a6a2759f4e9856f2c190db60bd0408a9974e15d0ac22fb74db7e5033dc34bf29833a077fd2f1fc3f58b8e9350465ecc1d9bf2dd72039c18569b7dde9b5f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e09f41d6f334e70e44b394619797ff33f13ec77a0a2389a96aa8c4e759e1b9b78d47d23d9fa171eb2038fe19abc192b172520a4f944dd96c436497bf14d48ff0"} build_url:https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011501 build_url: 'https://queue.taskcluster.net/v1/task/Vju7eF6bRCGSdOghDn0Bzg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/725665031ddd8f080763db8a28c03582f331de8c2526253cc4e6ae563e600acd5848dda2c9c6c4ff6b9729deae7ffc7e4c9d802f2367a1ce85218e1bd45bb7c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2330a6a2759f4e9856f2c190db60bd0408a9974e15d0ac22fb74db7e5033dc34bf29833a077fd2f1fc3f58b8e9350465ecc1d9bf2dd72039c18569b7dde9b5f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e09f41d6f334e70e44b394619797ff33f13ec77a0a2389a96aa8c4e759e1b9b78d47d23d9fa171eb2038fe19abc192b172520a4f944dd96c436497bf14d48ff0"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 19:39:57.112247) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 19:39:57.112626) ========= 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.yel7ilVxPP/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.4GxbaHnejw/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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.005278 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 19:39:57.161592) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 19:39:57.161935) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 19:39:57.162299) ========= ========= Total master_lag: 2.20 =========