builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0054 starttime: 1473180467.5 results: success (0) buildid: 20160906073354 builduid: cd2b5714eef541adb2ca291af8b47685 revision: 394f02edb7cb33ad70074c77b523451749c5ed0b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 09:47:47.495395) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 09:47:47.495839) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 09:47:47.496356) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 09:47:47.548431) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 09:47:47.548980) ========= 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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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-06 09:47:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 41.2M=0s 2016-09-06 09:47:48 (41.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.513166 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 09:47:48.087415) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 09:47:48.087781) ========= 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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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.027839 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 09:47:48.138974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 09:47:48.139316) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 394f02edb7cb33ad70074c77b523451749c5ed0b --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 394f02edb7cb33ad70074c77b523451749c5ed0b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.epEB9S5Sxl/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.QpwsQAuHbS/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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-06 09:47:48,219 truncating revision to first 12 chars 2016-09-06 09:47:48,219 Setting DEBUG logging. 2016-09-06 09:47:48,220 attempt 1/10 2016-09-06 09:47:48,220 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/394f02edb7cb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 09:47:49,449 unpacking tar archive at: mozilla-central-394f02edb7cb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.489548 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 09:47:49.650961) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 09:47:49.651280) ========= script_repo_revision: 394f02edb7cb33ad70074c77b523451749c5ed0b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 09:47:49.651714) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 09:47:49.652016) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 09:47:49.678749) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-06 09:47:49.679311) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:47:49 INFO - MultiFileLogger online at 20160906 09:47:49 in /builds/slave/test 09:47:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:47:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:47:49 INFO - {'append_to_log': False, 09:47:49 INFO - 'base_work_dir': '/builds/slave/test', 09:47:49 INFO - 'blob_upload_branch': 'Firefox', 09:47:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:47:49 INFO - 'branch': 'Firefox', 09:47:49 INFO - 'buildbot_json_path': 'buildprops.json', 09:47:49 INFO - 'config_files': ('talos/mac_config.py',), 09:47:49 INFO - 'default_actions': ('clobber', 09:47:49 INFO - 'read-buildbot-config', 09:47:49 INFO - 'download-and-extract', 09:47:49 INFO - 'populate-webroot', 09:47:49 INFO - 'create-virtualenv', 09:47:49 INFO - 'install', 09:47:49 INFO - 'run-tests'), 09:47:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:47:49 INFO - 'download_minidump_stackwalk': True, 09:47:49 INFO - 'download_symbols': 'ondemand', 09:47:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:47:49 INFO - 'tooltool.py': '/tools/tooltool.py', 09:47:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:47:49 INFO - '/tools/misc-python/virtualenv.py')}, 09:47:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:47:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:47:49 INFO - 'installer_path': 'installer.exe', 09:47:49 INFO - 'log_level': 'info', 09:47:49 INFO - 'log_name': 'talos', 09:47:49 INFO - 'log_to_console': True, 09:47:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:47:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:47:49 INFO - 'opt_config_files': (), 09:47:49 INFO - 'pip_index': False, 09:47:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:47:49 INFO - 'cmd': ('bash', 09:47:49 INFO - '-c', 09:47:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:47:49 INFO - 'enabled': True, 09:47:49 INFO - 'halt_on_failure': False, 09:47:49 INFO - 'name': 'check_screen_resolution'},), 09:47:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:47:49 INFO - 'cmd': ('bash', 09:47:49 INFO - '-c', 09:47:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:47:49 INFO - 'enabled': True, 09:47:49 INFO - 'halt_on_failure': False, 09:47:49 INFO - 'name': 'check_screen_resolution'},), 09:47:49 INFO - 'run_cmd_checks_enabled': True, 09:47:49 INFO - 'sps_profile': False, 09:47:49 INFO - 'sps_profile_interval': 0, 09:47:49 INFO - 'suite': 'dromaeojs-e10s', 09:47:49 INFO - 'system_bits': '32', 09:47:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:47:49 INFO - 'title': 't-yosemite-r7-0054', 09:47:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:47:49 INFO - 'use_talos_json': True, 09:47:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:47:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:47:49 INFO - 'work_dir': 'build'} 09:47:49 INFO - [mozharness: 2016-09-06 16:47:49.879492Z] Running clobber step. 09:47:49 INFO - Running pre-action listener: _resource_record_pre_action 09:47:49 INFO - Running main action method: clobber 09:47:49 INFO - rmtree: /builds/slave/test/build 09:47:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:47:51 INFO - Running post-action listener: _resource_record_post_action 09:47:51 INFO - [mozharness: 2016-09-06 16:47:51.960741Z] Finished clobber step (success) 09:47:51 INFO - [mozharness: 2016-09-06 16:47:51.960933Z] Running read-buildbot-config step. 09:47:51 INFO - Running pre-action listener: _resource_record_pre_action 09:47:51 INFO - Running main action method: read_buildbot_config 09:47:51 INFO - Using buildbot properties: 09:47:51 INFO - { 09:47:51 INFO - "project": "", 09:47:51 INFO - "product": "firefox", 09:47:51 INFO - "script_repo_revision": "production", 09:47:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:47:51 INFO - "repository": "", 09:47:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:47:51 INFO - "buildid": "20160906073354", 09:47:51 INFO - "pgo_build": "False", 09:47:51 INFO - "basedir": "/builds/slave/test", 09:47:51 INFO - "buildnumber": 48, 09:47:51 INFO - "slavename": "t-yosemite-r7-0054", 09:47:51 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:47:51 INFO - "platform": "yosemite_r7", 09:47:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:47:51 INFO - "branch": "mozilla-central", 09:47:51 INFO - "repo_path": "mozilla-central", 09:47:51 INFO - "revision": "394f02edb7cb33ad70074c77b523451749c5ed0b", 09:47:51 INFO - "stage_platform": "macosx64", 09:47:51 INFO - "builduid": "cd2b5714eef541adb2ca291af8b47685", 09:47:51 INFO - "slavebuilddir": "test" 09:47:51 INFO - } 09:47:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:47:51 INFO - Running post-action listener: _resource_record_post_action 09:47:51 INFO - [mozharness: 2016-09-06 16:47:51.964452Z] Finished read-buildbot-config step (success) 09:47:51 INFO - [mozharness: 2016-09-06 16:47:51.964568Z] Running download-and-extract step. 09:47:51 INFO - Running pre-action listener: _resource_record_pre_action 09:47:51 INFO - Running main action method: download_and_extract 09:47:51 INFO - mkdir: /builds/slave/test/build/tests 09:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:51 INFO - trying https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:47:51 INFO - Downloading https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/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 09:47:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/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 09:47:52 INFO - Downloaded 1199 bytes. 09:47:52 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:47:52 INFO - Using the following test package requirements: 09:47:52 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:47:52 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:47:52 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:47:52 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:47:52 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:47:52 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:47:52 INFO - u'jsshell-mac.zip'], 09:47:52 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:47:52 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:47:52 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:47:52 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:47:52 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:47:52 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:47:52 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:47:52 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:47:52 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:47:52 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:47:52 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:47:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:47:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:47:53 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:48:00 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 09:48:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:48:00 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:48:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:48:08 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:08 INFO - trying https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:48:08 INFO - Downloading https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:48:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/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 09:48:11 INFO - Downloaded 111521679 bytes. 09:48:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:48:11 INFO - mkdir: /builds/slave/test/properties 09:48:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:48:11 INFO - Writing to file /builds/slave/test/properties/build_url 09:48:11 INFO - Contents: 09:48:11 INFO - build_url:https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:48:12 INFO - Running post-action listener: _resource_record_post_action 09:48:12 INFO - Running post-action listener: set_extra_try_arguments 09:48:12 INFO - [mozharness: 2016-09-06 16:48:12.127981Z] Finished download-and-extract step (success) 09:48:12 INFO - [mozharness: 2016-09-06 16:48:12.128099Z] Running populate-webroot step. 09:48:12 INFO - Running pre-action listener: _resource_record_pre_action 09:48:12 INFO - Running main action method: populate_webroot 09:48:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:48:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:48:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:48:12 INFO - '--tppagecycles', 09:48:12 INFO - '7'], 09:48:12 INFO - 'tests': ['tsvgm']}}, 09:48:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:48:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:48:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:48:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:48:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:48:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:48:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:12 INFO - 'talos_options': ['--disable-e10s'], 09:48:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:12 INFO - 'talos_options': ['--disable-e10s'], 09:48:12 INFO - 'tests': ['damp', 'tps']}, 09:48:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:12 INFO - 'tests': ['damp', 'tps']}, 09:48:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:48:12 INFO - 'tests': ['dromaeo_dom']}, 09:48:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:48:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:48:12 INFO - 'tests': ['basic_compositor_video']}, 09:48:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:48:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:48:12 INFO - 'tests': ['a11yr', 09:48:12 INFO - 'ts_paint', 09:48:12 INFO - 'tpaint', 09:48:12 INFO - 'sessionrestore', 09:48:12 INFO - 'sessionrestore_no_auto_restore', 09:48:12 INFO - 'tabpaint']}, 09:48:12 INFO - 'other-e10s': {'tests': ['a11yr', 09:48:12 INFO - 'ts_paint', 09:48:12 INFO - 'tpaint', 09:48:12 INFO - 'sessionrestore', 09:48:12 INFO - 'sessionrestore_no_auto_restore', 09:48:12 INFO - 'tabpaint']}, 09:48:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:48:12 INFO - 'ts_paint', 09:48:12 INFO - 'tpaint', 09:48:12 INFO - 'sessionrestore', 09:48:12 INFO - 'sessionrestore_no_auto_restore', 09:48:12 INFO - 'tabpaint']}, 09:48:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:48:12 INFO - 'ts_paint', 09:48:12 INFO - 'tpaint', 09:48:12 INFO - 'sessionrestore', 09:48:12 INFO - 'sessionrestore_no_auto_restore', 09:48:12 INFO - 'tabpaint']}, 09:48:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:48:12 INFO - 'tests': ['a11yr', 09:48:12 INFO - 'ts_paint', 09:48:12 INFO - 'tpaint', 09:48:12 INFO - 'sessionrestore', 09:48:12 INFO - 'sessionrestore_no_auto_restore', 09:48:12 INFO - 'tabpaint']}, 09:48:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:48:12 INFO - 'tests': ['a11yr', 09:48:12 INFO - 'ts_paint', 09:48:12 INFO - 'tpaint', 09:48:12 INFO - 'sessionrestore', 09:48:12 INFO - 'sessionrestore_no_auto_restore', 09:48:12 INFO - 'tabpaint']}, 09:48:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:48:12 INFO - 'tests': ['tsvgx', 09:48:12 INFO - 'tsvgr_opacity', 09:48:12 INFO - 'tart', 09:48:12 INFO - 'tscrollx', 09:48:12 INFO - 'cart']}, 09:48:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:48:12 INFO - 'tsvgr_opacity', 09:48:12 INFO - 'tart', 09:48:12 INFO - 'tscrollx', 09:48:12 INFO - 'cart']}, 09:48:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:12 INFO - 'talos_options': ['--disable-e10s'], 09:48:12 INFO - 'tests': ['tp5o']}, 09:48:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:12 INFO - 'tests': ['tp5o']}, 09:48:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:12 INFO - 'talos_options': ['--disable-e10s', 09:48:12 INFO - '--xperf_path', 09:48:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:12 INFO - 'tests': ['tp5n']}, 09:48:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:12 INFO - 'talos_options': ['--xperf_path', 09:48:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:12 INFO - 'tests': ['tp5n']}}, 09:48:12 INFO - 'talos.zip': {'path': '', 09:48:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:48:12 INFO - Running post-action listener: _resource_record_post_action 09:48:12 INFO - [mozharness: 2016-09-06 16:48:12.146783Z] Finished populate-webroot step (success) 09:48:12 INFO - [mozharness: 2016-09-06 16:48:12.146911Z] Running create-virtualenv step. 09:48:12 INFO - Running pre-action listener: _resource_record_pre_action 09:48:12 INFO - Running main action method: create_virtualenv 09:48:12 INFO - Creating virtualenv /builds/slave/test/build/venv 09:48:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:48:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:48:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:48:12 INFO - Using real prefix '/tools/python27' 09:48:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:48:13 INFO - Installing distribute.............................................................................................................................................................................................done. 09:48:15 INFO - Installing pip.................done. 09:48:15 INFO - Return code: 0 09:48:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:15 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 0x1100ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a9493020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110063d30>, '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 0x10fdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11005bb20>, '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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:48:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epEB9S5Sxl/Render', 09:48:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:15 INFO - 'HOME': '/Users/cltbld', 09:48:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:15 INFO - 'LOGNAME': 'cltbld', 09:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:15 INFO - 'MOZ_NO_REMOTE': '1', 09:48:15 INFO - 'NO_EM_RESTART': '1', 09:48:15 INFO - 'PAGER': '/bin/cat', 09:48:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:15 INFO - 'PWD': '/builds/slave/test', 09:48:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:15 INFO - 'SHELL': '/bin/bash', 09:48:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QpwsQAuHbS/Listeners', 09:48:15 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 09:48:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:15 INFO - 'USER': 'cltbld', 09:48:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:15 INFO - 'XPC_FLAGS': '0x0', 09:48:15 INFO - 'XPC_SERVICE_NAME': '0', 09:48:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:48:16 INFO - Cleaning up... 09:48:16 INFO - Return code: 0 09:48:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1100ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a9493020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110063d30>, '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 0x10fdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11005bb20>, '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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:48:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:48:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epEB9S5Sxl/Render', 09:48:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:16 INFO - 'HOME': '/Users/cltbld', 09:48:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:16 INFO - 'LOGNAME': 'cltbld', 09:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:16 INFO - 'MOZ_NO_REMOTE': '1', 09:48:16 INFO - 'NO_EM_RESTART': '1', 09:48:16 INFO - 'PAGER': '/bin/cat', 09:48:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:16 INFO - 'PWD': '/builds/slave/test', 09:48:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:16 INFO - 'SHELL': '/bin/bash', 09:48:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QpwsQAuHbS/Listeners', 09:48:16 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 09:48:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:16 INFO - 'USER': 'cltbld', 09:48:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:16 INFO - 'XPC_FLAGS': '0x0', 09:48:16 INFO - 'XPC_SERVICE_NAME': '0', 09:48:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:16 INFO - Downloading/unpacking psutil>=3.1.1 09:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:48:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:20 INFO - Installing collected packages: psutil 09:48:20 INFO - Running setup.py install for psutil 09:48:20 INFO - building 'psutil._psutil_osx' extension 09:48:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:48:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:48:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:48:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:48:21 INFO - building 'psutil._psutil_posix' extension 09:48:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:48:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:48:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:48:21 INFO - ^ 09:48:21 INFO - 1 warning generated. 09:48:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:48:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:21 INFO - Successfully installed psutil 09:48:21 INFO - Cleaning up... 09:48:21 INFO - Return code: 0 09:48:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1100ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a9493020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110063d30>, '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 0x10fdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11005bb20>, '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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:48: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 mozsystemmonitor==0.3 09:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epEB9S5Sxl/Render', 09:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:21 INFO - 'HOME': '/Users/cltbld', 09:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:21 INFO - 'LOGNAME': 'cltbld', 09:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:21 INFO - 'MOZ_NO_REMOTE': '1', 09:48:21 INFO - 'NO_EM_RESTART': '1', 09:48:21 INFO - 'PAGER': '/bin/cat', 09:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:21 INFO - 'PWD': '/builds/slave/test', 09:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:21 INFO - 'SHELL': '/bin/bash', 09:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QpwsQAuHbS/Listeners', 09:48:21 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 09:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:21 INFO - 'USER': 'cltbld', 09:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:21 INFO - 'XPC_FLAGS': '0x0', 09:48:21 INFO - 'XPC_SERVICE_NAME': '0', 09:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:48:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:48:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:48:24 INFO - Installing collected packages: mozsystemmonitor 09:48:24 INFO - Running setup.py install for mozsystemmonitor 09:48:24 INFO - Successfully installed mozsystemmonitor 09:48:24 INFO - Cleaning up... 09:48:24 INFO - Return code: 0 09:48:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:24 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 0x1100ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a9493020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110063d30>, '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 0x10fdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11005bb20>, '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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:48:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epEB9S5Sxl/Render', 09:48:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:24 INFO - 'HOME': '/Users/cltbld', 09:48:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:24 INFO - 'LOGNAME': 'cltbld', 09:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:24 INFO - 'MOZ_NO_REMOTE': '1', 09:48:24 INFO - 'NO_EM_RESTART': '1', 09:48:24 INFO - 'PAGER': '/bin/cat', 09:48:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:24 INFO - 'PWD': '/builds/slave/test', 09:48:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:24 INFO - 'SHELL': '/bin/bash', 09:48:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QpwsQAuHbS/Listeners', 09:48:24 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 09:48:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:24 INFO - 'USER': 'cltbld', 09:48:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:24 INFO - 'XPC_FLAGS': '0x0', 09:48:24 INFO - 'XPC_SERVICE_NAME': '0', 09:48:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:24 INFO - Downloading/unpacking jsonschema==2.5.1 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:48:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:48:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:48:27 INFO - warning: no files found matching '*.txt' 09:48:27 INFO - no previously-included directories found matching 'build' 09:48:27 INFO - no previously-included directories found matching 'dist' 09:48:27 INFO - no previously-included directories found matching '.git*' 09:48:27 INFO - Installing collected packages: jsonschema, functools32 09:48:27 INFO - Running setup.py install for functools32 09:48:27 INFO - warning: no files found matching '*.txt' 09:48:27 INFO - no previously-included directories found matching 'build' 09:48:27 INFO - no previously-included directories found matching 'dist' 09:48:27 INFO - no previously-included directories found matching '.git*' 09:48:27 INFO - Successfully installed jsonschema functools32 09:48:27 INFO - Cleaning up... 09:48:27 INFO - Return code: 0 09:48:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:48:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:27 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 0x1100ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a9493020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110063d30>, '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 0x10fdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11005bb20>, '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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:48:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:48:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epEB9S5Sxl/Render', 09:48:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:27 INFO - 'HOME': '/Users/cltbld', 09:48:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:27 INFO - 'LOGNAME': 'cltbld', 09:48:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:27 INFO - 'MOZ_NO_REMOTE': '1', 09:48:27 INFO - 'NO_EM_RESTART': '1', 09:48:27 INFO - 'PAGER': '/bin/cat', 09:48:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:27 INFO - 'PWD': '/builds/slave/test', 09:48:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:27 INFO - 'SHELL': '/bin/bash', 09:48:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QpwsQAuHbS/Listeners', 09:48:27 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 09:48:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:27 INFO - 'USER': 'cltbld', 09:48:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:27 INFO - 'XPC_FLAGS': '0x0', 09:48:27 INFO - 'XPC_SERVICE_NAME': '0', 09:48:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:48:28 INFO - Cleaning up... 09:48:28 INFO - Return code: 0 09:48:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:28 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 0x1100ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a9493020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110063d30>, '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 0x10fdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11005bb20>, '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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:48:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:48:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epEB9S5Sxl/Render', 09:48:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:28 INFO - 'HOME': '/Users/cltbld', 09:48:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:28 INFO - 'LOGNAME': 'cltbld', 09:48:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:28 INFO - 'MOZ_NO_REMOTE': '1', 09:48:28 INFO - 'NO_EM_RESTART': '1', 09:48:28 INFO - 'PAGER': '/bin/cat', 09:48:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:28 INFO - 'PWD': '/builds/slave/test', 09:48:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:28 INFO - 'SHELL': '/bin/bash', 09:48:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QpwsQAuHbS/Listeners', 09:48:28 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 09:48:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:28 INFO - 'USER': 'cltbld', 09:48:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:28 INFO - 'XPC_FLAGS': '0x0', 09:48:28 INFO - 'XPC_SERVICE_NAME': '0', 09:48:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:30 INFO - Downloading blobuploader-1.2.4.tar.gz 09:48:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:48:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:48:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:32 INFO - Downloading docopt-0.6.1.tar.gz 09:48:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:48:32 INFO - Installing collected packages: blobuploader, requests, docopt 09:48:32 INFO - Running setup.py install for blobuploader 09:48:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:48:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:48:32 INFO - Running setup.py install for requests 09:48:32 INFO - Running setup.py install for docopt 09:48:32 INFO - Successfully installed blobuploader requests docopt 09:48:32 INFO - Cleaning up... 09:48:33 INFO - Return code: 0 09:48:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:33 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 0x1100ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a9493020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110063d30>, '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 0x10fdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11005bb20>, '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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:48:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:48:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epEB9S5Sxl/Render', 09:48:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:33 INFO - 'HOME': '/Users/cltbld', 09:48:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:33 INFO - 'LOGNAME': 'cltbld', 09:48:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:33 INFO - 'MOZ_NO_REMOTE': '1', 09:48:33 INFO - 'NO_EM_RESTART': '1', 09:48:33 INFO - 'PAGER': '/bin/cat', 09:48:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:33 INFO - 'PWD': '/builds/slave/test', 09:48:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:33 INFO - 'SHELL': '/bin/bash', 09:48:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QpwsQAuHbS/Listeners', 09:48:33 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 09:48:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:33 INFO - 'USER': 'cltbld', 09:48:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:33 INFO - 'XPC_FLAGS': '0x0', 09:48:33 INFO - 'XPC_SERVICE_NAME': '0', 09:48:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:48:33 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-KnGobr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:48:33 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-MyGfe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:48:33 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-hvuv19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:48:33 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-rO3e5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:48:33 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-L2AYBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:48:33 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-qx4IJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:48:33 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-7Y6izK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:48:34 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-MhClv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:48:34 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-1hC0WJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:48:34 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-SxPFIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:48:34 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-zKjSDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:48:34 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-evk8vp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:48:34 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-kgeYws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:48:34 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-_XeVBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:34 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-9RjlAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:48:34 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-1gzq3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:48:34 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-Ijhahn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:48:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:48:35 INFO - Running setup.py install for manifestparser 09:48:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:48:35 INFO - Running setup.py install for mozcrash 09:48:35 INFO - Running setup.py install for mozdebug 09:48:35 INFO - Running setup.py install for mozdevice 09:48:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:48:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:48:35 INFO - Running setup.py install for mozfile 09:48:35 INFO - Running setup.py install for mozhttpd 09:48:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:48:35 INFO - Running setup.py install for mozinfo 09:48:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:48:36 INFO - Running setup.py install for mozInstall 09:48:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:48:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:48:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:48:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:48:36 INFO - Running setup.py install for mozleak 09:48:36 INFO - Running setup.py install for mozlog 09:48:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:48:36 INFO - Running setup.py install for moznetwork 09:48:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:48:36 INFO - Running setup.py install for mozprocess 09:48:36 INFO - Running setup.py install for mozprofile 09:48:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:48:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:48:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:48:37 INFO - Running setup.py install for mozrunner 09:48:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:48:37 INFO - Running setup.py install for mozscreenshot 09:48:37 INFO - Running setup.py install for moztest 09:48:37 INFO - Running setup.py install for mozversion 09:48:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:48:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:48:37 INFO - Cleaning up... 09:48:37 INFO - Return code: 0 09:48:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:37 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 0x1100ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a9493020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110063d30>, '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 0x10fdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11005bb20>, '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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:48:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:48:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epEB9S5Sxl/Render', 09:48:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:37 INFO - 'HOME': '/Users/cltbld', 09:48:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:37 INFO - 'LOGNAME': 'cltbld', 09:48:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:37 INFO - 'MOZ_NO_REMOTE': '1', 09:48:37 INFO - 'NO_EM_RESTART': '1', 09:48:37 INFO - 'PAGER': '/bin/cat', 09:48:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:37 INFO - 'PWD': '/builds/slave/test', 09:48:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:37 INFO - 'SHELL': '/bin/bash', 09:48:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QpwsQAuHbS/Listeners', 09:48:37 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 09:48:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:37 INFO - 'USER': 'cltbld', 09:48:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:37 INFO - 'XPC_FLAGS': '0x0', 09:48:37 INFO - 'XPC_SERVICE_NAME': '0', 09:48:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:48:37 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-fN9e82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:48:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:48:38 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-Khjtzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:48:38 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)) 09:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:48:38 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-_t84SN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:48:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:48:38 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-wKNTfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:48:38 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)) 09:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:48:38 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-hAZYbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:48:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:48:38 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-219NzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:48:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:48:38 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-4xJE5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:48:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:48:38 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-rh8A6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:48:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:48:38 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-Qc8qvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:48:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:48:38 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-2SpGO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:48:38 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)) 09:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:48:38 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-CV64gH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:48:39 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-WAtPNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:48:39 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)) 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:48:39 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-j3Lcwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:48:39 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-w6LvJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:48:39 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)) 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:39 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-UNI3HG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:48:39 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-d8QFbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:48:39 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)) 09:48:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:48:39 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-Wh5_Zu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:48:39 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)) 09:48:39 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)) 09:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:48:39 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)) 09:48:39 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)) 09:48:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:42 INFO - Downloading blessings-1.6.tar.gz 09:48:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:48:42 INFO - Installing collected packages: blessings 09:48:42 INFO - Running setup.py install for blessings 09:48:42 INFO - Successfully installed blessings 09:48:42 INFO - Cleaning up... 09:48:42 INFO - Return code: 0 09:48:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:48:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:48:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:48:42 INFO - Reading from file tmpfile_stdout 09:48:42 INFO - Current package versions: 09:48:42 INFO - blessings == 1.6 09:48:42 INFO - blobuploader == 1.2.4 09:48:42 INFO - docopt == 0.6.1 09:48:42 INFO - functools32 == 3.2.3-2 09:48:42 INFO - jsonschema == 2.5.1 09:48:42 INFO - manifestparser == 1.1 09:48:42 INFO - mozInstall == 1.12 09:48:42 INFO - mozcrash == 0.17 09:48:42 INFO - mozdebug == 0.1 09:48:42 INFO - mozdevice == 0.48 09:48:42 INFO - mozfile == 1.2 09:48:42 INFO - mozhttpd == 0.7 09:48:42 INFO - mozinfo == 0.9 09:48:42 INFO - mozleak == 0.1 09:48:42 INFO - mozlog == 3.3 09:48:42 INFO - moznetwork == 0.27 09:48:42 INFO - mozprocess == 0.23 09:48:42 INFO - mozprofile == 0.28 09:48:42 INFO - mozrunner == 6.12 09:48:42 INFO - mozscreenshot == 0.1 09:48:42 INFO - mozsystemmonitor == 0.3 09:48:42 INFO - moztest == 0.7 09:48:42 INFO - mozversion == 1.4 09:48:42 INFO - psutil == 3.1.1 09:48:42 INFO - requests == 1.2.3 09:48:42 INFO - wsgiref == 0.1.2 09:48:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:42 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 0x1100ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a9493020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110063d30>, '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 0x10fdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11005bb20>, '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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:48:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:48:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epEB9S5Sxl/Render', 09:48:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:42 INFO - 'HOME': '/Users/cltbld', 09:48:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:42 INFO - 'LOGNAME': 'cltbld', 09:48:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:42 INFO - 'MOZ_NO_REMOTE': '1', 09:48:42 INFO - 'NO_EM_RESTART': '1', 09:48:42 INFO - 'PAGER': '/bin/cat', 09:48:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:42 INFO - 'PWD': '/builds/slave/test', 09:48:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:42 INFO - 'SHELL': '/bin/bash', 09:48:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QpwsQAuHbS/Listeners', 09:48:42 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 09:48:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:42 INFO - 'USER': 'cltbld', 09:48:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:42 INFO - 'XPC_FLAGS': '0x0', 09:48:42 INFO - 'XPC_SERVICE_NAME': '0', 09:48:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:48:43 INFO - Cleaning up... 09:48:43 INFO - Return code: 0 09:48:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:43 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 0x1100ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a9493020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110063d30>, '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 0x10fdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11005bb20>, '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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:48:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epEB9S5Sxl/Render', 09:48:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:43 INFO - 'HOME': '/Users/cltbld', 09:48:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:43 INFO - 'LOGNAME': 'cltbld', 09:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:43 INFO - 'MOZ_NO_REMOTE': '1', 09:48:43 INFO - 'NO_EM_RESTART': '1', 09:48:43 INFO - 'PAGER': '/bin/cat', 09:48:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:43 INFO - 'PWD': '/builds/slave/test', 09:48:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:43 INFO - 'SHELL': '/bin/bash', 09:48:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QpwsQAuHbS/Listeners', 09:48:43 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 09:48:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:43 INFO - 'USER': 'cltbld', 09:48:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:43 INFO - 'XPC_FLAGS': '0x0', 09:48:43 INFO - 'XPC_SERVICE_NAME': '0', 09:48:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:48:43 INFO - Cleaning up... 09:48:43 INFO - Return code: 0 09:48:43 INFO - Running post-action listener: _resource_record_post_action 09:48:43 INFO - Running post-action listener: _start_resource_monitoring 09:48:43 INFO - Starting resource monitoring. 09:48:43 INFO - [mozharness: 2016-09-06 16:48:43.494167Z] Finished create-virtualenv step (success) 09:48:43 INFO - [mozharness: 2016-09-06 16:48:43.495218Z] Running install step. 09:48:43 INFO - Running pre-action listener: _resource_record_pre_action 09:48:43 INFO - Running main action method: install 09:48:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:48:43 INFO - Reading from file tmpfile_stdout 09:48:43 INFO - Detecting whether we're running mozinstall >=1.0... 09:48:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:48:43 INFO - Reading from file tmpfile_stdout 09:48:43 INFO - Output received: 09:48:43 INFO - Usage: mozinstall [options] installer 09:48:43 INFO - Options: 09:48:43 INFO - -h, --help show this help message and exit 09:48:43 INFO - -d DEST, --destination=DEST 09:48:43 INFO - Directory to install application into. [default: 09:48:43 INFO - "/builds/slave/test"] 09:48:43 INFO - --app=APP Application being installed. [default: firefox] 09:48:43 INFO - mkdir: /builds/slave/test/build/application 09:48:43 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'] 09:48:43 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 09:49:18 INFO - Reading from file tmpfile_stdout 09:49:18 INFO - Output received: 09:49:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:49:18 INFO - Running post-action listener: _resource_record_post_action 09:49:18 INFO - [mozharness: 2016-09-06 16:49:18.455136Z] Finished install step (success) 09:49:18 INFO - [mozharness: 2016-09-06 16:49:18.455261Z] Running run-tests step. 09:49:18 INFO - Running pre-action listener: _resource_record_pre_action 09:49:18 INFO - Running main action method: run_tests 09:49:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:49:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:49:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:49:18 INFO - 2016-09-06 09:49:18.468 screenresolution[1643:7629] starting screenresolution argv=screenresolution get 09:49:18 INFO - 2016-09-06 09:49:18.471 screenresolution[1643:7629] Display 0: 1600x1200x32@60 09:49:18 INFO - 2016-09-06 09:49:18.476 screenresolution[1644:7632] starting screenresolution argv=screenresolution list 09:49:18 INFO - Available Modes on Display 0 09:49:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:49:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:49:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:49:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:49:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:49:18 INFO - Intel Iris: 09:49:18 INFO - Chipset Model: Intel Iris 09:49:18 INFO - Type: GPU 09:49:18 INFO - Bus: Built-In 09:49:18 INFO - VRAM (Dynamic, Max): 1536 MB 09:49:18 INFO - Vendor: Intel (0x8086) 09:49:18 INFO - Device ID: 0x0a2e 09:49:18 INFO - Revision ID: 0x0009 09:49:18 INFO - Displays: 09:49:18 INFO - Display: 09:49:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:49:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:49:18 INFO - Display Serial Number: 1600x1200 60 09:49:18 INFO - Main Display: Yes 09:49:18 INFO - Mirror: Off 09:49:18 INFO - Online: Yes 09:49:18 INFO - Rotation: Supported 09:49:18 INFO - Return code: 0 09:49:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:49:18 INFO - Python 2.7.3 09:49:18 INFO - Return code: 0 09:49:18 INFO - grabbing minidump binary from tooltool 09:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:18 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 0x110063d30>, '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 0x10fdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11005bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:49:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:49:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:49:18 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:49:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:49:18 INFO - Return code: 0 09:49:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:49:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:49:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:49:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:49:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:49:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0054 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:49:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epEB9S5Sxl/Render', 09:49:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:18 INFO - 'HOME': '/Users/cltbld', 09:49:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:18 INFO - 'LOGNAME': 'cltbld', 09:49:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:49:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:18 INFO - 'MOZ_NO_REMOTE': '1', 09:49:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:49:18 INFO - 'NO_EM_RESTART': '1', 09:49:18 INFO - 'PAGER': '/bin/cat', 09:49:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:18 INFO - 'PWD': '/builds/slave/test', 09:49:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:49:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:18 INFO - 'SHELL': '/bin/bash', 09:49:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QpwsQAuHbS/Listeners', 09:49:18 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 09:49:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:18 INFO - 'USER': 'cltbld', 09:49:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:18 INFO - 'XPC_FLAGS': '0x0', 09:49:18 INFO - 'XPC_SERVICE_NAME': '0', 09:49:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:49:19 INFO - mozversion application_buildid: 20160906073354 09:49:19 INFO - mozversion application_changeset: 394f02edb7cb33ad70074c77b523451749c5ed0b 09:49:19 INFO - mozversion application_display_name: Nightly 09:49:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:49:19 INFO - mozversion application_name: Firefox 09:49:19 INFO - mozversion application_remotingname: firefox 09:49:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:49:19 INFO - mozversion application_vendor: Mozilla 09:49:19 INFO - mozversion application_version: 51.0a1 09:49:19 INFO - mozversion platform_buildid: 20160906073354 09:49:19 INFO - mozversion platform_changeset: 394f02edb7cb33ad70074c77b523451749c5ed0b 09:49:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:49:19 INFO - mozversion platform_version: 51.0a1 09:49:19 INFO - using testdate: 1473180559 09:49:19 INFO - actual date: 1473180559 09:49:19 INFO - starting webserver on 'localhost:49268' 09:49:19 INFO - SUITE-START | Running 2 tests 09:49:19 INFO - TEST-START | dromaeo_css 09:49:19 INFO - operating with platform_type : mac_ 09:49:19 INFO - Initialising browser for dromaeo_css test... 09:49:19 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmp39NCy6/profile http://localhost:49268/getInfo.html) 09:49:21 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 09:49:21 INFO - PROCESS | 1654 | colorDepth:24 09:49:21 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 09:49:21 INFO - PROCESS | 1654 | __metrics 09:49:22 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 09:49:22 INFO - TEST-INFO | 1654: exit 0 09:49:22 INFO - Browser initialized. 09:49:22 INFO - Running cycle 1/1 for dromaeo_css test... 09:49:22 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmp39NCy6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:34 INFO - PROCESS | 1657 | RSS: Main: 244068352 09:49:34 INFO - PROCESS | 1657 | 09:50:24 INFO - PROCESS | 1657 | 1473180624425 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] 09:52:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 09:52:37 INFO - PROCESS | 1657 | RSS: Main: 266366976 09:52:37 INFO - PROCESS | 1657 | 09:55:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 09:55:40 INFO - PROCESS | 1657 | RSS: Main: 275550208 09:55:40 INFO - PROCESS | 1657 | 09:58:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 09:58:43 INFO - PROCESS | 1657 | RSS: Main: 276193280 09:58:43 INFO - PROCESS | 1657 | 10:01:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 10:01:46 INFO - PROCESS | 1657 | RSS: Main: 271560704 10:01:46 INFO - PROCESS | 1657 | 10:04:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 10:04:49 INFO - PROCESS | 1657 | RSS: Main: 276946944 10:04:49 INFO - PROCESS | 1657 | 10:07:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 10:07:52 INFO - PROCESS | 1657 | RSS: Main: 279576576 10:07:52 INFO - PROCESS | 1657 | 10:07:52 INFO - PROCESS | 1657 | __start_tp_report 10:07:52 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 10:07:52 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 10:07:52 INFO - PROCESS | 1657 | |i|pagename|runs| 10:07:52 INFO - PROCESS | 1657 | |0;dojo.html;2921.4157168566294;3409.4715319125535;3420.4527275635887;3454.0846675630964;3465.8093804840723;5715.171300161474;5809.7385617647205;5815.0114480538305;5887.941120588793;5906.350301466835;5797.275340582427;5827.766889324433;5851.063829787246;5892.14563888237;5899.734511946962;5206.869828254305;5304.177852432879;5318.1225097858805;5375.623706340558;5412.783488660449;5359.062164121283;5393.514583687467;5419.078756611366;5453.590980676465;5465.562754979612;4228.450301460821;5222.712750798704;5250.107481728096;5272.762725677354;5275.630705850592;5210.6613070150315;5211.296474975906;5258.106121959276;5275.657082289639;5328.92006619901;5212.582993360522;5246.501582349671;5265.789368425297;5289.523942845162;5319.281896943901;5713.457221563945;5827.067669172912;5839.416058394169;5867.090600956859;5917.733701983421;6216.437426386917;6245.6252624842655;6267.05994100884;6269.655168965705;6275.968620156916;6268;6291.905621415683;6298.748050077991;6306.842328941767;6327.8418039548915;5806.883862322772;5862.765489380419;5934.821955341346;5949.583529152917;6028.728707208186;6374.936250637527;6429.067785171124;6430.324815894357;6435.839104022343;6436.678166091677;6182.6599537025895;6239.500839932794;6265.310815810257;6275.02737075754;6289.559730818799;6162.7226774795245;6211.09939058834;6214.130021796952;6263.655498947602;6314.210723659543;6112.755489780359;6175.6912154393;6189.473894718909;6258.374162583706;6270.7805226816845;6210.813675589739;6307.653079080695;6310.7791227306825;6317.747290108344;6328.5886417383645;4921.3356196912955;4976.054549635558;5031.84904452867;5042.294078828967;5057.494250574913;3849.7305188637088;4455.465344158657;4520.434945631796;4524.773761311922;4552.544745525421;5301.708406037705;5860.794872179454;5943.494802941712;5953.077273022688;5982.012967860254;5336.959292937918;6178.104174894615;6257.937420625735;6278.748850046039;6282.120503129566;4957.256411538298;4965.975170124127;5167.431582525919;5969.283975791005;5996.310424301174;4665.6034237090225;5527.53015993637;5538.695371754592;5540.473655002768;5542.91685624716;5318.97340513295;6281.685915704288;6505.60966342021;6570.638614876228;6573.9999999999045;6197.442230199304;6211.2857021443115;6246;6279.717412716347;6290.025046117859;5348.251244825649;6482.448991835747;6496.512761542997;6542.803715888531;6543;6120.44915957566;6293.527985401021;6302.558820882494;6313.463355614824;6319.431251187415;6154.446099851035;6175.073738939195;6198.81403557894;6264.310925798068;6288.339724329063;6154.476869466145;6226.50187984951;6231.906521402273;6234.937650623436;6251.78118765841;5838.357780644211;6088.512918966383;6117.877642447215;6203.286622038343;6212.844678883064;6016.699165041818;6038.154658347923;6090.451859332681;6116.731440059472;6131.724072416663;6189.926374664357;6190.845228869314;6213.254409470893;6217.347178546368;6241.719122639583;5029.64792464524;5917.082852157922;6027.819165425044;6060.272767267956;6102.816915492543 10:07:52 INFO - PROCESS | 1657 | |1;ext.html;455.357945297131;474.91688954432965;537.8010136249588;553.4990833295866;569.4504802865234;38721.80639096804;41277.79361103194;41314.793426032826;41350;41351.79324103379;42119.578804211946;42261.7886910565;42765.7861710691;42815.57184428155;42846;9622.278329125309;9653.469059201741;9786.804263914717;9789.902100979007;9807.4605896676;9598.568064437117;9640.855387169164;9761.365511241747;9770.218382529381;9775.951120244388;9601.039896010412;9711.757206069835;9755.024497550223;9759.365641233297;9785.412875227508;9683.418994860296;9715.854262186072;9734.805303893952;9757.12185903265;9761.755956101084;9588.712338629852;9675.274354423376;9762.463064531448;9773.267004974656;9823.508824558743;40292.79121626356;40599.39100913489;40645;40758.388624170664;40788.18423631541;3251.479763237894;3285.5400243965864;3309.5035744638258;3314.7182489488423;3769.5476542815036;25818.612720809204;31477.213069673213;31535;32244.355112897847;32850.671493285;2879.2226098953165;3233.4341490239112;3249.2039450334582;3292.703656670887;3344.832758362072;454037;458187.9999999967;458984;460969;461160;57125;57402.13896791552;57424.85150296971;57492.42507574871;57535.712321438965;27938.86030569834;33079.84220552269;33275;33549.49675754866;59502.80994380088;12304;12921.28932908699;16070.276837542338;16500.83499165017;16571.91714041422;12773.489060437665;12838.16551924122;12842.614721558368;12844.90818280438;12891.48434062627;16816.159192040348;17502.687298452667;18747.437576872715;18990.62018759617;19001.23995040183;7980.521168729895;8065.556394398247;8068.878966815502;8098.51408915467;8105.797355066171;734.1227233456076;844.5439462690201;973.3527204408989;1028.3007554862616;1044.3942513342242;28956;30532.931347402733;30635;30754;31421.685783141875;1052.6368402900987;1132.9660110196708;1133.0878642692712;1138.2942575603179;1208.546794951893;15129.319180636898;15609.765853512206;15708.60728481797;15782.36870525189;15927.283272252776;646808;661330;667206;670452;675696;57585.71207144021;57956.420435795946;58327;58539.70730146322;58764;1351.6688411339285;1353.5397964692052;1389.4095009620669;1395.4069520453766;1399.3423091147145;28983.420331593672;30269.243268917573;30276.091717247637;30325.241868953453;30539;48907.510924890295;49323;49644.75177624089;49723.005539889724;49839;574894;588336;589279;593338;593395;24567.754322456545;26086.82609282549;26180.73819261783;26225.868870655526;26343;46867.062658747316;46997;48386;48768.26847597218;48854.75572622114;39067.41398879076;39362.21275574415;40433.797831010655;40785.77642670725;41389.75830725083;38429;38432.231355372176;39113;39245.803770980965;40295.39556906708;29379.97170099036;31691.52462713107;38470.615293847826;39126.217475649755;39141;33302.66697332996;34504;35187.82406087953;35383.64616353803;35449;25602.359941001625;25790.61314080253;25922;26115.824787884972;26550.601740973514 10:07:52 INFO - PROCESS | 1657 | |2;jquery.html;321.1793866670657;357.5566297790739;376.8172436368361;379.2377321583616;383.87524054682234;19602.803971960275;20195.899020504894;20471.795282047213;20864.956752162387;21045.894770526163;20215.292464763737;20503.897480512613;20766.584668306627;20766.584668306627;21183;5587.469190426903;5756.647187910838;5831.7958871439505;6012.038073908176;6093.116498107772;5996.370381109986;6055.636661800284;6082.087686846986;6098.176746139281;6106.358832322609;5758.3090029196555;5937.16879636849;5966.701664916758;5992.400759923995;6071.726772295236;5584.888302233932;5842.591018628697;5963.701814909259;6006.369331220225;6031.366706495821;5061.949380506221;5070.69575825448;5114.923276150861;5167.715775632339;5169.379674439054;20068.49828754278;20495.89752051245;24614.015439382405;24884.626730599055;25240.747592524207;2141.935741927745;2143.1748776721074;2203.6253836847773;2555.8083143764293;2655.884528498016;12155.237490563906;14669.266536673123;15237.314320855477;15237.847621523864;15648.843511564852;1865.216609024213;1866.0762922353385;1889.8488120950428;1915.2913422033798;1958.392898201562;92850;96150.51924740331;96597.65310560972;96691.51654241754;96874;10477.947610262052;10882.401467919182;11075.944620276847;11195;11336;13272;13565.59303220905;13579.456821727217;13596.38816253271;13670;8280.130586288473;8535.231829135408;8551.358648101417;8608.053114157437;8681.609327580274;8660.26387757035;8663.306935445149;8683.348748843735;8752.649894004297;8823.602937867812;5517.36550296721;6440.516961227927;6479.6760161991715;6501.707423165969;6596.307387724316;1878.2017642501883;1883.849292056632;1888.707250376194;1909.4367161687335;1938.1084701037398;729.6133049483782;787.2678409079497;897.5243121145803;962.8892677342055;1008.6923488336128;16811.15944202785;18834.340798072;19258.90370548166;21582.89208553947;21753.564928701337;830.4975489828687;945.6595625574788;1006.4866917871792;1028.681108856257;1052.4527245832123;9192.954035229915;11071.5571377144;11477.024452921596;11675.007624352025;11821.468033938494;582058;588097;590476;600534;606206;58875;90984.54507727419;111497;113128.43435782769;133678;978.9559469823876;979.9412035277763;996.9930370325878;1039.6101461951769;1042.2443728296923;13810.792838107638;17884.91057544704;18989.24043038263;19111.139998699815;19189;37365.81317093397;48383.5161648379;49916;51384;52788;69922;75015.87349201817;76138;76784;77475.61262193653;5320.8935821282585;5350.785968561214;7776.572288524186;7928.151130380997;8252.257296843312;11576.768464630828;12476.251424914533;12554.309512976874;12756.234625922474;12900.032497562908;8874.778130546789;9353.485558294087;10990.725231869268;11144.996950274513;11174.66476005721;9758.804823903623;10569.312994655324;10787.6224332148;11002.834957475805;11213.439328033402;8707.216350528483;10754.946225268823;11371;11436.885631143914;11562.884371156517;7898.486598371088;8033.437659363788;8114.148014458633;8155.062167850773;8214.932058832313;10280.584062745398;10334.793304133726;11164.720881978015;11221.943890280496;11290.548378064785 10:07:52 INFO - PROCESS | 1657 | |3;mootools.html;1517.9620509487265;1546.3350759173557;1756.7013607686692;1871.055117165831;1903.4004288649066;8026.478278911866;8512.233898949093;8769.298456123512;9087.636854471833;9108.817823643523;8712.433691810027;8751.212390884819;8920.152585504367;8932.151445612673;8954.417962832427;2388.9283321500384;2414.2274472168915;2416.335507735378;2427.66012758214;2433.148398060682;2398.4723360860585;2411.7347091819884;2431.416460049584;2432.4405386761055;2436.2082323245018;2407.4222186675247;2424.9151279705216;2425.721042080157;2430.5989511730545;2434.586120359534;2112.725345705064;2400.195934361991;2404.7114346278386;2408.4821763320883;2417.178159425795;2390.270967354956;2405.230326295586;2408.0969636386353;2410.3251089694913;2426.2478631624253;8932.240759535447;9011.324150688659;9017.368784185044;9019.368644194908;9037.825082739268;5287.339082614673;5386.407495175527;5430.076886929192;5450.972745136249;5472.644278121909;2894.956575651367;3630.6006339302658;3637.9818100909592;3686.4286035664513;3703.6481534254203;942.185009966381;1029.6665817765936;1057.9047885690325;1075.9462026898702;1085.8045551800674;11583.247088939192;11670.94164529172;11686.76626467466;11695.766084678344;11732.70668233301;4700.905981880412;4776.090199819;4789.44921334051;4884.3650325458175;4899.240617704261;10016.49917504122;10101.797964040677;10125.392476451436;10130;10142.391456512632;5379.6234263601955;5391.487808658171;5412.350517937873;5426.023315803114;5435.130379139318;4405.933910991338;4507.301368287921;4872.074305881871;4884.3161957326;4951.405831300267;3956.793178080713;4052.6423648078226;4097.385392191136;4128.669706423479;4133.565975572582;1307.895368370528;1315.5790147153011;1318.4133060787858;1325.5161865919063;1326.635175326793;758.9962050189714;860.5181098584812;898.725888603969;903.6566104880103;926.4070994563485;5365.141577347684;5653.869226154786;5815.621984570989;6062.818115456544;6171.9199140150295;903.3586153830854;925.1488630459993;949.6818565780541;966.9951650241704;1014.1329163565193;3999.5800440953863;4018.477597912253;4089.8957281533817;4173.937390939138;4186.623203911602;8087.433879628369;10682.092022178202;10695.144388449062;10707.036366727032;10747.462626868626;10777.407242601575;10799.650043744532;10841.457927103613;10842.349459032326;10845.13238940883;6050.092486127028;7768;8070.82972968928;8263.380246481538;8270.007599088269;5722.971385143048;5774.39368866263;5937.139114828239;5970.820875373745;5973.1637570741;9935.516120969758;10020.34867733595;10065.396076235449;10148.949255253676;10221.9488902555;9260.53697315118;10310.226732995205;10322.896771032194;10325.586976520855;10343.172546196436;6543.116679248431;6808.897866532105;6997.335253150943;7034.050403195708;7173.569585824867;7217.314355136254;8472.61873215719;8553.44402613828;8555.230029297392;8562.52906090171;7444.516106453063;7476.439267054884;7626.237376262329;7698.461107722405;7709.383249340151;6467;7518.624068796647;7538.623068846426;7559.130699969354;7695.538267703956;6864.5881247125335;6903.723851045902;6914.9308506914285;6925.342092501204;7046.365827075588;4992.475790042138;5013;5028.396592408934;5078.492150784892;5117.718525481134;4951.133551628379;4957.876053098701;5075.847724568269;5122.078025955364;5155.948440515697 10:07:52 INFO - PROCESS | 1657 | |4;prototype.html;294.45378822284664;299.1563790111884;305.31152251672484;311.36481577581725;313.7364613724471;4553.726776393419;5303.761330740116;5475.863103422416;5489.451054894508;5679.630823996437;4555.382839092124;5448.863778405542;5468.562514998801;5546.921458590491;5638.73128546078;4042.393640953851;4267.07981408366;4279.315309550472;4281.978590107045;4282.892927676802;4123.608257215575;4212.515560710512;4301.333293339521;4308.224519586473;4321.271491403432;3283.343331333731;3972.960270397288;4177.665786737069;4180.372944058385;4187.1625674865145;4201.915961680749;4212.694064839879;4225.893967933566;4246.978765106154;4248.893777655552;3655.853765849363;3665.8900232992787;3668.559772827278;3673.6142705016123;3690.7785532868115;4797.960866631703;5221.269022336875;5267.604929630293;5438.592105592096;5442.857000029999;4070.9389359159636;4868.488808675073;4885.899063402735;4898.926516102261;4911.7544122793715;5142.280080788692;5682.9147562786175;5762.827115186551;5958.6424814511265;5969.224000879858;3998.7400818946676;4015.3976903464422;4021.3713446054394;4031.9395209071886;4049.250888585621;5687.60186786921;6215.595986260879;6303.235094173593;6305.842353941142;6331.683415829236;4063.4108054331955;4097.631213190827;4108.979455102705;4111.2188684150515;4118.794060296973;3760.2291530236234;4006.899827504336;4066.9389959150635;4072.3891416287793;4073.857414990461;4332.805023773938;4532.864014079583;4817.6634332348785;4845.9030819383415;4850.733209673432;5005.424376196711;5250.921236181461;5785.768569257182;5791.971040144772;6012.759489620366;1160.3907948327196;1233.444949772606;1277.7827769279245;1286.9704236610673;1358.5448874627111;732.8786955957394;744.2743325257939;818.5825229133185;847.2501835875295;854.5897968974926;3789.3936970084656;4099.241640296555;4165.729227600196;4517.944335327418;4678.532146785295;4558.407407037064;5083.779892825695;5086.364204474441;5116.513931176532;5164.767585458589;4006.599340066028;4113.239050775556;4604.953950460519;4615.769211539477;4635.65232607549;2483.6235153052035;2544.625902012929;2665.840359443648;2668.813183077166;2744.4511097780123;5190.702324418895;5296.390915044819;5344.412114667383;5349.1976203569;5389.973050134724;4442.379924878155;4870.592114136712;5110.7955681773055;5162.6902385856965;5307.734613269398;4367.825286988548;4723.976380118077;4745.596624286905;4790.640701947368;4958.520739630185;4244.299690551148;4709.163426263828;5028.8433660257615;5035.4712755161645;5129.897402052013;3907.648311651851;3927.8821635350987;4181.623653871166;4378.584034516716;4752;4720.339152518719;4728.952710472851;4901.284550407329;5183.92224116631;5337.519623233928;3370.5618269624792;3494.972915708832;3511.122219445139;3522.2212782545676;3522.489239060373;4000.3399439092223;4646.3727396801;5107.693538387709;5156.88008754698;5752.712364381848;5695.572832037531;5833.562482813721;5852.356240813592;5900.082473196143;5919.378465261258;5302.390225124006;5592.853465039576;5666.28018551106;5891.381404952418;5981.726943761343;5030.320906677698;5071.2139618359215;5349.144136938071;5467.425920278472;5736.741846616829;5132.384113906355;5241.31862857826;5380.704061276668;5942.732577033958;6003.759849605967;4453.732776033448;4688.835890743808;5445.237666726741;5818.854528636818;5828.242328497268 10:07:52 INFO - PROCESS | 1657 | |5;yui.html;614.7049416280186;694.17046629278;738.2986163145014;764.4114032195209;809.7611204694615;14694.338754756034;15121.370870990966;15510.767338489917;15565.455209067684;15587.766183507243;14058.929705351484;14211.934104942118;14266.429342826275;14326.068805527659;14329.283535823219;856.1566856646214;868.9522076285801;875.9474431534096;878.3763527895202;878.7144178142097;687.6871023684237;756.3722110648149;774.9767506974799;847.0851480401183;857.8884744983144;751.5903832411349;866.2030931542964;918.6968300460832;920.8342498350295;922.7139586728102;908.0919080919015;913.808100298938;917.0462718772502;922.9815403691889;923.2798417234534;683.2211279141783;683.73676134688;685.2496516314627;685.6331862453613;686.5125760709902;14652.194129322883;14751.77872331916;14790.482333118289;14805.185714785683;14860.554183374516;14337.56987290383;14338.211398373085;14435.206063666494;14556;14666.413343466249;11838.526458941718;11842.703932401673;11843.940780296129;11870.050395968301;11911.225770324902;3772.6793222576116;3777.54669439669;3796.90507737306;3822.808859557039;3949.7630142191274;89860;93630.35990141312;93682.53158734231;106662;114352.85647143505;10987.835182472269;11098.056665183389;11098.944505277583;11137.665870023913;11289.435528223721;21736.782632173476;21884.562308753735;21956.560868782533;22012.449688757588;22059.33821985343;7625.580593067434;7627.847443051108;7753.10839253493;7821.374290056782;7920.009998750156;4303.1608836277255;4319.33050377192;4387.188370151533;4428.33574963758;4461.643068554507;3541;3645.2527231917393;3647.1793846384353;3651.141981634367;3707.796071216109;662.1987395251703;664.1299897134769;671.3689132215702;684.1858188755465;684.5789839248926;547.8411260734422;577.2293987526699;598.3956204233668;617.6325086573481;621.9582199815292;7791.80520486981;9223.893132824103;9420.53981632848;9537.666181683608;9741.756456088513;651.6057785039983;654.9672516374162;681.2540268406087;681.6898311268361;683.2040672616446;4204;4249.256380133526;4441.289393697058;4713.976430117896;4827.927581086286;166257;167864;168138.1593092051;169938.30061699473;173651.39522907167;90114;115872;143137;148892;149006.50993490143;112149.43925280322;119201;128647.35676321745;130482;130616;7461.850762984602;8973.28213742886;8989.550522473719;9023.007469178516;9126;67465;68663.65668171627;68917.96623050558;69233;70281;80974;99068;99237.51143732994;99831.50084249533;105568;11226.382548959886;11669.824952625548;11677;11757.76484470323;12246.75506489883;12967.546135885575;13042.4130914111;13231;13303.20180789117;13705.588832335046;11934.462949167135;11965.700857478632;12070.818937716116;12178.634640960785;12547.874521254671;9190.31072669539;10522.736931576466;12023.27860328383;12096.879031209575;12207.267563946192;12044.939775301067;12076.818847717466;12169.417975663107;12172.208806427554;12465.875341246472;8115.9188408117525;10026.548805303633;10182.727159105112;10532.420716860339;10539.894601054199;7697.6151192441275;9600.855987160337;10323.225758068325;10360.637377691744;10740.301880378063 10:07:52 INFO - PROCESS | 1657 | __end_tp_report 10:07:52 INFO - PROCESS | 1657 | __start_cc_report 10:07:52 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,386 10:07:52 INFO - PROCESS | 1657 | __end_cc_report 10:07:52 INFO - PROCESS | 1657 | __startTimestamp1473181672159__endTimestamp 10:07:52 INFO - PROCESS | 1657 | 10:07:52 INFO - PROCESS | 1657 | ------- Summary: start ------- 10:07:52 INFO - PROCESS | 1657 | Number of tests: 6 10:07:52 INFO - PROCESS | 1657 | 10:07:52 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5780.70 Median:6028.73 stddev:683.97 (11.3%) stddev-sans-first:648.24 10:07:52 INFO - PROCESS | 1657 | Values: 2921.4 3409.5 3420.5 3454.1 3465.8 5715.2 5809.7 5815.0 5887.9 5906.4 5797.3 5827.8 5851.1 5892.1 5899.7 5206.9 5304.2 5318.1 5375.6 5412.8 5359.1 5393.5 5419.1 5453.6 5465.6 4228.5 5222.7 5250.1 5272.8 5275.6 5210.7 5211.3 5258.1 5275.7 5328.9 5212.6 5246.5 5265.8 5289.5 5319.3 5713.5 5827.1 5839.4 5867.1 5917.7 6216.4 6245.6 6267.1 6269.7 6276.0 6268.0 6291.9 6298.7 6306.8 6327.8 5806.9 5862.8 5934.8 5949.6 6028.7 6374.9 6429.1 6430.3 6435.8 6436.7 6182.7 6239.5 6265.3 6275.0 6289.6 6162.7 6211.1 6214.1 6263.7 6314.2 6112.8 6175.7 6189.5 6258.4 6270.8 6210.8 6307.7 6310.8 6317.7 6328.6 4921.3 4976.1 5031.8 5042.3 5057.5 3849.7 4455.5 4520.4 4524.8 4552.5 5301.7 5860.8 5943.5 5953.1 5982.0 5337.0 6178.1 6257.9 6278.7 6282.1 4957.3 4966.0 5167.4 5969.3 5996.3 4665.6 5527.5 5538.7 5540.5 5542.9 5319.0 6281.7 6505.6 6570.6 6574.0 6197.4 6211.3 6246.0 6279.7 6290.0 5348.3 6482.4 6496.5 6542.8 6543.0 6120.4 6293.5 6302.6 6313.5 6319.4 6154.4 6175.1 6198.8 6264.3 6288.3 6154.5 6226.5 6231.9 6234.9 6251.8 5838.4 6088.5 6117.9 6203.3 6212.8 6016.7 6038.2 6090.5 6116.7 6131.7 6189.9 6190.8 6213.3 6217.3 6241.7 5029.6 5917.1 6027.8 6060.3 6102.8 10:07:52 INFO - PROCESS | 1657 | 10:07:52 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:69638.20 Median:40516.59 stddev:154287.02 (380.8%) stddev-sans-first:154632.39 10:07:52 INFO - PROCESS | 1657 | Values: 455.4 474.9 537.8 553.5 569.5 38721.8 41277.8 41314.8 41350.0 41351.8 42119.6 42261.8 42765.8 42815.6 42846.0 9622.3 9653.5 9786.8 9789.9 9807.5 9598.6 9640.9 9761.4 9770.2 9776.0 9601.0 9711.8 9755.0 9759.4 9785.4 9683.4 9715.9 9734.8 9757.1 9761.8 9588.7 9675.3 9762.5 9773.3 9823.5 40292.8 40599.4 40645.0 40758.4 40788.2 3251.5 3285.5 3309.5 3314.7 3769.5 25818.6 31477.2 31535.0 32244.4 32850.7 2879.2 3233.4 3249.2 3292.7 3344.8 454037.0 458188.0 458984.0 460969.0 461160.0 57125.0 57402.1 57424.9 57492.4 57535.7 27938.9 33079.8 33275.0 33549.5 59502.8 12304.0 12921.3 16070.3 16500.8 16571.9 12773.5 12838.2 12842.6 12844.9 12891.5 16816.2 17502.7 18747.4 18990.6 19001.2 7980.5 8065.6 8068.9 8098.5 8105.8 734.1 844.5 973.4 1028.3 1044.4 28956.0 30532.9 30635.0 30754.0 31421.7 1052.6 1133.0 1133.1 1138.3 1208.5 15129.3 15609.8 15708.6 15782.4 15927.3 646808.0 661330.0 667206.0 670452.0 675696.0 57585.7 57956.4 58327.0 58539.7 58764.0 1351.7 1353.5 1389.4 1395.4 1399.3 28983.4 30269.2 30276.1 30325.2 30539.0 48907.5 49323.0 49644.8 49723.0 49839.0 574894.0 588336.0 589279.0 593338.0 593395.0 24567.8 26086.8 26180.7 26225.9 26343.0 46867.1 46997.0 48386.0 48768.3 48854.8 39067.4 39362.2 40433.8 40785.8 41389.8 38429.0 38432.2 39113.0 39245.8 40295.4 29380.0 31691.5 38470.6 39126.2 39141.0 33302.7 34504.0 35187.8 35383.6 35449.0 25602.4 25790.6 25922.0 26115.8 26550.6 10:07:52 INFO - PROCESS | 1657 | 10:07:52 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:33267.18 Median:18861.68 stddev:98083.32 (520.0%) stddev-sans-first:98327.28 10:07:52 INFO - PROCESS | 1657 | Values: 321.2 357.6 376.8 379.2 383.9 19602.8 20195.9 20471.8 20865.0 21045.9 20215.3 20503.9 20766.6 20766.6 21183.0 5587.5 5756.6 5831.8 6012.0 6093.1 5996.4 6055.6 6082.1 6098.2 6106.4 5758.3 5937.2 5966.7 5992.4 6071.7 5584.9 5842.6 5963.7 6006.4 6031.4 5061.9 5070.7 5114.9 5167.7 5169.4 20068.5 20495.9 24614.0 24884.6 25240.7 2141.9 2143.2 2203.6 2555.8 2655.9 12155.2 14669.3 15237.3 15237.8 15648.8 1865.2 1866.1 1889.8 1915.3 1958.4 92850.0 96150.5 96597.7 96691.5 96874.0 10477.9 10882.4 11075.9 11195.0 11336.0 13272.0 13565.6 13579.5 13596.4 13670.0 8280.1 8535.2 8551.4 8608.1 8681.6 8660.3 8663.3 8683.3 8752.6 8823.6 5517.4 6440.5 6479.7 6501.7 6596.3 1878.2 1883.8 1888.7 1909.4 1938.1 729.6 787.3 897.5 962.9 1008.7 16811.2 18834.3 19258.9 21582.9 21753.6 830.5 945.7 1006.5 1028.7 1052.5 9193.0 11071.6 11477.0 11675.0 11821.5 582058.0 588097.0 590476.0 600534.0 606206.0 58875.0 90984.5 111497.0 113128.4 133678.0 979.0 979.9 997.0 1039.6 1042.2 13810.8 17884.9 18989.2 19111.1 19189.0 37365.8 48383.5 49916.0 51384.0 52788.0 69922.0 75015.9 76138.0 76784.0 77475.6 5320.9 5350.8 7776.6 7928.2 8252.3 11576.8 12476.3 12554.3 12756.2 12900.0 8874.8 9353.5 10990.7 11145.0 11174.7 9758.8 10569.3 10787.6 11002.8 11213.4 8707.2 10754.9 11371.0 11436.9 11562.9 7898.5 8033.4 8114.1 8155.1 8214.9 10280.6 10334.8 11164.7 11221.9 11290.5 10:07:52 INFO - PROCESS | 1657 | 10:07:52 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5722.49 Median:4925.19 stddev:3182.46 (64.6%) stddev-sans-first:3175.70 10:07:52 INFO - PROCESS | 1657 | Values: 1518.0 1546.3 1756.7 1871.1 1903.4 8026.5 8512.2 8769.3 9087.6 9108.8 8712.4 8751.2 8920.2 8932.2 8954.4 2388.9 2414.2 2416.3 2427.7 2433.1 2398.5 2411.7 2431.4 2432.4 2436.2 2407.4 2424.9 2425.7 2430.6 2434.6 2112.7 2400.2 2404.7 2408.5 2417.2 2390.3 2405.2 2408.1 2410.3 2426.2 8932.2 9011.3 9017.4 9019.4 9037.8 5287.3 5386.4 5430.1 5451.0 5472.6 2895.0 3630.6 3638.0 3686.4 3703.6 942.2 1029.7 1057.9 1075.9 1085.8 11583.2 11670.9 11686.8 11695.8 11732.7 4700.9 4776.1 4789.4 4884.4 4899.2 10016.5 10101.8 10125.4 10130.0 10142.4 5379.6 5391.5 5412.4 5426.0 5435.1 4405.9 4507.3 4872.1 4884.3 4951.4 3956.8 4052.6 4097.4 4128.7 4133.6 1307.9 1315.6 1318.4 1325.5 1326.6 759.0 860.5 898.7 903.7 926.4 5365.1 5653.9 5815.6 6062.8 6171.9 903.4 925.1 949.7 967.0 1014.1 3999.6 4018.5 4089.9 4173.9 4186.6 8087.4 10682.1 10695.1 10707.0 10747.5 10777.4 10799.7 10841.5 10842.3 10845.1 6050.1 7768.0 8070.8 8263.4 8270.0 5723.0 5774.4 5937.1 5970.8 5973.2 9935.5 10020.3 10065.4 10148.9 10221.9 9260.5 10310.2 10322.9 10325.6 10343.2 6543.1 6808.9 6997.3 7034.1 7173.6 7217.3 8472.6 8553.4 8555.2 8562.5 7444.5 7476.4 7626.2 7698.5 7709.4 6467.0 7518.6 7538.6 7559.1 7695.5 6864.6 6903.7 6914.9 6925.3 7046.4 4992.5 5013.0 5028.4 5078.5 5117.7 4951.1 4957.9 5075.8 5122.1 5155.9 10:07:52 INFO - PROCESS | 1657 | 10:07:52 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4414.31 Median:4737.27 stddev:1349.04 (28.5%) stddev-sans-first:1316.91 10:07:52 INFO - PROCESS | 1657 | Values: 294.5 299.2 305.3 311.4 313.7 4553.7 5303.8 5475.9 5489.5 5679.6 4555.4 5448.9 5468.6 5546.9 5638.7 4042.4 4267.1 4279.3 4282.0 4282.9 4123.6 4212.5 4301.3 4308.2 4321.3 3283.3 3973.0 4177.7 4180.4 4187.2 4201.9 4212.7 4225.9 4247.0 4248.9 3655.9 3665.9 3668.6 3673.6 3690.8 4798.0 5221.3 5267.6 5438.6 5442.9 4070.9 4868.5 4885.9 4898.9 4911.8 5142.3 5682.9 5762.8 5958.6 5969.2 3998.7 4015.4 4021.4 4031.9 4049.3 5687.6 6215.6 6303.2 6305.8 6331.7 4063.4 4097.6 4109.0 4111.2 4118.8 3760.2 4006.9 4066.9 4072.4 4073.9 4332.8 4532.9 4817.7 4845.9 4850.7 5005.4 5250.9 5785.8 5792.0 6012.8 1160.4 1233.4 1277.8 1287.0 1358.5 732.9 744.3 818.6 847.3 854.6 3789.4 4099.2 4165.7 4517.9 4678.5 4558.4 5083.8 5086.4 5116.5 5164.8 4006.6 4113.2 4605.0 4615.8 4635.7 2483.6 2544.6 2665.8 2668.8 2744.5 5190.7 5296.4 5344.4 5349.2 5390.0 4442.4 4870.6 5110.8 5162.7 5307.7 4367.8 4724.0 4745.6 4790.6 4958.5 4244.3 4709.2 5028.8 5035.5 5129.9 3907.6 3927.9 4181.6 4378.6 4752.0 4720.3 4729.0 4901.3 5183.9 5337.5 3370.6 3495.0 3511.1 3522.2 3522.5 4000.3 4646.4 5107.7 5156.9 5752.7 5695.6 5833.6 5852.4 5900.1 5919.4 5302.4 5592.9 5666.3 5891.4 5981.7 5030.3 5071.2 5349.1 5467.4 5736.7 5132.4 5241.3 5380.7 5942.7 6003.8 4453.7 4688.8 5445.2 5818.9 5828.2 10:07:52 INFO - PROCESS | 1657 | 10:07:52 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:25578.35 Median:3809.86 stddev:42577.83 (1117.6%) stddev-sans-first:42656.02 10:07:52 INFO - PROCESS | 1657 | Values: 614.7 694.2 738.3 764.4 809.8 14694.3 15121.4 15510.8 15565.5 15587.8 14058.9 14211.9 14266.4 14326.1 14329.3 856.2 869.0 875.9 878.4 878.7 687.7 756.4 775.0 847.1 857.9 751.6 866.2 918.7 920.8 922.7 908.1 913.8 917.0 923.0 923.3 683.2 683.7 685.2 685.6 686.5 14652.2 14751.8 14790.5 14805.2 14860.6 14337.6 14338.2 14435.2 14556.0 14666.4 11838.5 11842.7 11843.9 11870.1 11911.2 3772.7 3777.5 3796.9 3822.8 3949.8 89860.0 93630.4 93682.5 106662.0 114352.9 10987.8 11098.1 11098.9 11137.7 11289.4 21736.8 21884.6 21956.6 22012.4 22059.3 7625.6 7627.8 7753.1 7821.4 7920.0 4303.2 4319.3 4387.2 4428.3 4461.6 3541.0 3645.3 3647.2 3651.1 3707.8 662.2 664.1 671.4 684.2 684.6 547.8 577.2 598.4 617.6 622.0 7791.8 9223.9 9420.5 9537.7 9741.8 651.6 655.0 681.3 681.7 683.2 4204.0 4249.3 4441.3 4714.0 4827.9 166257.0 167864.0 168138.2 169938.3 173651.4 90114.0 115872.0 143137.0 148892.0 149006.5 112149.4 119201.0 128647.4 130482.0 130616.0 7461.9 8973.3 8989.6 9023.0 9126.0 67465.0 68663.7 68918.0 69233.0 70281.0 80974.0 99068.0 99237.5 99831.5 105568.0 11226.4 11669.8 11677.0 11757.8 12246.8 12967.5 13042.4 13231.0 13303.2 13705.6 11934.5 11965.7 12070.8 12178.6 12547.9 9190.3 10522.7 12023.3 12096.9 12207.3 12044.9 12076.8 12169.4 12172.2 12465.9 8115.9 10026.5 10182.7 10532.4 10539.9 7697.6 9600.9 10323.2 10360.6 10740.3 10:07:52 INFO - PROCESS | 1657 | -------- Summary: end -------- 10:07:52 INFO - PROCESS | 1657 | 10:07:52 INFO - TEST-INFO | 1657: exit 0 10:07:52 INFO - TEST-OK | dromaeo_css | took 1113724ms 10:07:52 INFO - TEST-START | kraken 10:07:52 INFO - operating with platform_type : mac_ 10:07:52 INFO - Initialising browser for kraken test... 10:07:52 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmpNipbsK/profile http://localhost:49268/getInfo.html) 10:07:55 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 10:07:55 INFO - PROCESS | 1665 | colorDepth:24 10:07:55 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 10:07:55 INFO - PROCESS | 1665 | __metrics 10:07:55 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 10:07:56 INFO - TEST-INFO | 1665: exit 0 10:07:56 INFO - Browser initialized. 10:07:56 INFO - Running cycle 1/1 for kraken test... 10:07:56 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmpNipbsK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:08:08 INFO - PROCESS | 1667 | RSS: Main: 244187136 10:08:08 INFO - PROCESS | 1667 | 10:08:11 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 10:08:12 INFO - PROCESS | 1667 | RSS: Main: 256974848 10:08:12 INFO - PROCESS | 1667 | 10:08:15 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 10:08:15 INFO - PROCESS | 1667 | RSS: Main: 265617408 10:08:15 INFO - PROCESS | 1667 | 10:08:19 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 10:08:19 INFO - PROCESS | 1667 | RSS: Main: 264368128 10:08:19 INFO - PROCESS | 1667 | 10:08:22 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 10:08:22 INFO - PROCESS | 1667 | RSS: Main: 265658368 10:08:22 INFO - PROCESS | 1667 | 10:08:24 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:08:25 INFO - PROCESS | 1667 | RSS: Main: 267223040 10:08:25 INFO - PROCESS | 1667 | 10:08:28 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 10:08:28 INFO - PROCESS | 1667 | RSS: Main: 268083200 10:08:28 INFO - PROCESS | 1667 | 10:08:31 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 10:08:32 INFO - PROCESS | 1667 | RSS: Main: 268431360 10:08:32 INFO - PROCESS | 1667 | 10:08:35 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 10:08:36 INFO - PROCESS | 1667 | RSS: Main: 268382208 10:08:36 INFO - PROCESS | 1667 | 10:08:37 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:08:38 INFO - PROCESS | 1667 | RSS: Main: 267751424 10:08:38 INFO - PROCESS | 1667 | 10:08:40 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:08:41 INFO - PROCESS | 1667 | RSS: Main: 267636736 10:08:41 INFO - PROCESS | 1667 | 10:08:43 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:08:44 INFO - PROCESS | 1667 | RSS: Main: 267726848 10:08:44 INFO - PROCESS | 1667 | 10:08:47 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:08:47 INFO - PROCESS | 1667 | RSS: Main: 268070912 10:08:47 INFO - PROCESS | 1667 | 10:08:50 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:08:51 INFO - PROCESS | 1667 | RSS: Main: 267829248 10:08:51 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 10:08:53 INFO - PROCESS | 1667 | RSS: Main: 269856768 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | __start_tp_report 10:08:53 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 10:08:53 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 10:08:53 INFO - PROCESS | 1667 | |i|pagename|runs| 10:08:53 INFO - PROCESS | 1667 | |0;ai-astar;110;118;117;113;116;121;109;165;122;197 10:08:53 INFO - PROCESS | 1667 | |1;audio-beat-detection;172;139;136;163;111;130;130;114;160;112 10:08:53 INFO - PROCESS | 1667 | |2;audio-dft;199;220;242;270;274;195;196;201;198;198 10:08:53 INFO - PROCESS | 1667 | |3;audio-fft;75;82;85;193;72;82;87;88;135;60 10:08:53 INFO - PROCESS | 1667 | |4;audio-oscillator;70;73;109;102;104;111;146;92;73;113 10:08:53 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;90;88;91;89;91;88;94;92;129;89 10:08:53 INFO - PROCESS | 1667 | |6;imaging-darkroom;104;102;100;102;102;103;101;101;101;101 10:08:53 INFO - PROCESS | 1667 | |7;imaging-desaturate;92;100;146;92;86;89;85;144;148;93 10:08:53 INFO - PROCESS | 1667 | |8;json-parse-financial;46;49;88;87;87;45;51;79;45;50 10:08:53 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;65;70;75;75;95;97;77;71;69;72 10:08:53 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;99;112;114;76;112;112;109;91;83;86 10:08:53 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;151;157;165;162;138;146;149;151;200;152 10:08:53 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;177;199;252;177;172;175;176;177;202;243 10:08:53 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;70;68;69;68;71;67;66;71;89;84 10:08:53 INFO - PROCESS | 1667 | __end_tp_report 10:08:53 INFO - PROCESS | 1667 | __start_cc_report 10:08:53 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,970 10:08:53 INFO - PROCESS | 1667 | __end_cc_report 10:08:53 INFO - PROCESS | 1667 | __startTimestamp1473181733509__endTimestamp 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | ------- Summary: start ------- 10:08:53 INFO - PROCESS | 1667 | Number of tests: 14 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:128.80 Median:119.50 stddev:28.84 (24.1%) stddev-sans-first:29.77 10:08:53 INFO - PROCESS | 1667 | Values: 110.0 118.0 117.0 113.0 116.0 121.0 109.0 165.0 122.0 197.0 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:137.50 stddev:22.02 (16.0%) stddev-sans-first:19.29 10:08:53 INFO - PROCESS | 1667 | Values: 172.0 139.0 136.0 163.0 111.0 130.0 130.0 114.0 160.0 112.0 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:219.30 Median:210.50 stddev:31.39 (14.9%) stddev-sans-first:32.42 10:08:53 INFO - PROCESS | 1667 | Values: 199.0 220.0 242.0 270.0 274.0 195.0 196.0 201.0 198.0 198.0 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:95.90 Median:82.00 stddev:39.30 (47.9%) stddev-sans-first:40.95 10:08:53 INFO - PROCESS | 1667 | Values: 75.0 82.0 85.0 193.0 72.0 82.0 87.0 88.0 135.0 60.0 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:99.30 Median:108.00 stddev:23.39 (21.7%) stddev-sans-first:22.28 10:08:53 INFO - PROCESS | 1667 | Values: 70.0 73.0 109.0 102.0 104.0 111.0 146.0 92.0 73.0 113.0 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:94.10 Median:90.50 stddev:12.40 (13.7%) stddev-sans-first:13.07 10:08:53 INFO - PROCESS | 1667 | Values: 90.0 88.0 91.0 89.0 91.0 88.0 94.0 92.0 129.0 89.0 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:101.70 Median:102.00 stddev:1.16 (1.1%) stddev-sans-first:0.88 10:08:53 INFO - PROCESS | 1667 | Values: 104.0 102.0 100.0 102.0 102.0 103.0 101.0 101.0 101.0 101.0 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:107.50 Median:87.50 stddev:26.90 (30.7%) stddev-sans-first:27.94 10:08:53 INFO - PROCESS | 1667 | Values: 92.0 100.0 146.0 92.0 86.0 89.0 85.0 144.0 148.0 93.0 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:65.00 stddev:19.66 (30.2%) stddev-sans-first:19.90 10:08:53 INFO - PROCESS | 1667 | Values: 46.0 49.0 88.0 87.0 87.0 45.0 51.0 79.0 45.0 50.0 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:76.60 Median:75.00 stddev:10.79 (14.4%) stddev-sans-first:10.60 10:08:53 INFO - PROCESS | 1667 | Values: 65.0 70.0 75.0 75.0 95.0 97.0 77.0 71.0 69.0 72.0 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:99.40 Median:79.50 stddev:14.33 (18.0%) stddev-sans-first:15.20 10:08:53 INFO - PROCESS | 1667 | Values: 99.0 112.0 114.0 76.0 112.0 112.0 109.0 91.0 83.0 86.0 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:157.10 Median:154.50 stddev:16.93 (11.0%) stddev-sans-first:17.82 10:08:53 INFO - PROCESS | 1667 | Values: 151.0 157.0 165.0 162.0 138.0 146.0 149.0 151.0 200.0 152.0 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.00 Median:188.00 stddev:29.59 (15.7%) stddev-sans-first:30.66 10:08:53 INFO - PROCESS | 1667 | Values: 177.0 199.0 252.0 177.0 172.0 175.0 176.0 177.0 202.0 243.0 10:08:53 INFO - PROCESS | 1667 | 10:08:53 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:70.50 stddev:7.75 (11.0%) stddev-sans-first:8.17 10:08:53 INFO - PROCESS | 1667 | Values: 70.0 68.0 69.0 68.0 71.0 67.0 66.0 71.0 89.0 84.0 10:08:53 INFO - PROCESS | 1667 | -------- Summary: end -------- 10:08:53 INFO - PROCESS | 1667 | 10:08:54 INFO - TEST-INFO | 1667: exit 0 10:08:54 INFO - TEST-OK | kraken | took 61701ms 10:08:54 INFO - SUITE-END | took 1175s 10:08:54 INFO - Completed test suite (00:19:35) 10:08:54 INFO - javascript benchmark 10:08:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7295.77492575516, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2921.4157168566294, 3409.4715319125535, 3420.4527275635887, 3454.0846675630964, 3465.8093804840723, 5715.171300161474, 5809.7385617647205, 5815.0114480538305, 5887.941120588793, 5906.350301466835, 5797.275340582427, 5827.766889324433, 5851.063829787246, 5892.14563888237, 5899.734511946962, 5206.869828254305, 5304.177852432879, 5318.1225097858805, 5375.623706340558, 5412.783488660449, 5359.062164121283, 5393.514583687467, 5419.078756611366, 5453.590980676465, 5465.562754979612, 4228.450301460821, 5222.712750798704, 5250.107481728096, 5272.762725677354, 5275.630705850592, 5210.6613070150315, 5211.296474975906, 5258.106121959276, 5275.657082289639, 5328.92006619901, 5212.582993360522, 5246.501582349671, 5265.789368425297, 5289.523942845162, 5319.281896943901, 5713.457221563945, 5827.067669172912, 5839.416058394169, 5867.090600956859, 5917.733701983421, 6216.437426386917, 6245.6252624842655, 6267.05994100884, 6269.655168965705, 6275.968620156916, 6268.0, 6291.905621415683, 6298.748050077991, 6306.842328941767, 6327.8418039548915, 5806.883862322772, 5862.765489380419, 5934.821955341346, 5949.583529152917, 6028.728707208186, 6374.936250637527, 6429.067785171124, 6430.324815894357, 6435.839104022343, 6436.678166091677, 6182.6599537025895, 6239.500839932794, 6265.310815810257, 6275.02737075754, 6289.559730818799, 6162.7226774795245, 6211.09939058834, 6214.130021796952, 6263.655498947602, 6314.210723659543, 6112.755489780359, 6175.6912154393, 6189.473894718909, 6258.374162583706, 6270.7805226816845, 6210.813675589739, 6307.653079080695, 6310.7791227306825, 6317.747290108344, 6328.5886417383645, 4921.3356196912955, 4976.054549635558, 5031.84904452867, 5042.294078828967, 5057.494250574913, 3849.7305188637088, 4455.465344158657, 4520.434945631796, 4524.773761311922, 4552.544745525421, 5301.708406037705, 5860.794872179454, 5943.494802941712, 5953.077273022688, 5982.012967860254, 5336.959292937918, 6178.104174894615, 6257.937420625735, 6278.748850046039, 6282.120503129566, 4957.256411538298, 4965.975170124127, 5167.431582525919, 5969.283975791005, 5996.310424301174, 4665.6034237090225, 5527.53015993637, 5538.695371754592, 5540.473655002768, 5542.91685624716, 5318.97340513295, 6281.685915704288, 6505.60966342021, 6570.638614876228, 6573.9999999999045, 6197.442230199304, 6211.2857021443115, 6246.0, 6279.717412716347, 6290.025046117859, 5348.251244825649, 6482.448991835747, 6496.512761542997, 6542.803715888531, 6543.0, 6120.44915957566, 6293.527985401021, 6302.558820882494, 6313.463355614824, 6319.431251187415, 6154.446099851035, 6175.073738939195, 6198.81403557894, 6264.310925798068, 6288.339724329063, 6154.476869466145, 6226.50187984951, 6231.906521402273, 6234.937650623436, 6251.78118765841, 5838.357780644211, 6088.512918966383, 6117.877642447215, 6203.286622038343, 6212.844678883064, 6016.699165041818, 6038.154658347923, 6090.451859332681, 6116.731440059472, 6131.724072416663, 6189.926374664357, 6190.845228869314, 6213.254409470893, 6217.347178546368, 6241.719122639583, 5029.64792464524, 5917.082852157922, 6027.819165425044, 6060.272767267956, 6102.816915492543], "value": 5737.042202249937, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.357945297131, 474.91688954432965, 537.8010136249588, 553.4990833295866, 569.4504802865234, 38721.80639096804, 41277.79361103194, 41314.793426032826, 41350.0, 41351.79324103379, 42119.578804211946, 42261.7886910565, 42765.7861710691, 42815.57184428155, 42846.0, 9622.278329125309, 9653.469059201741, 9786.804263914717, 9789.902100979007, 9807.4605896676, 9598.568064437117, 9640.855387169164, 9761.365511241747, 9770.218382529381, 9775.951120244388, 9601.039896010412, 9711.757206069835, 9755.024497550223, 9759.365641233297, 9785.412875227508, 9683.418994860296, 9715.854262186072, 9734.805303893952, 9757.12185903265, 9761.755956101084, 9588.712338629852, 9675.274354423376, 9762.463064531448, 9773.267004974656, 9823.508824558743, 40292.79121626356, 40599.39100913489, 40645.0, 40758.388624170664, 40788.18423631541, 3251.479763237894, 3285.5400243965864, 3309.5035744638258, 3314.7182489488423, 3769.5476542815036, 25818.612720809204, 31477.213069673213, 31535.0, 32244.355112897847, 32850.671493285, 2879.2226098953165, 3233.4341490239112, 3249.2039450334582, 3292.703656670887, 3344.832758362072, 454037.0, 458187.9999999967, 458984.0, 460969.0, 461160.0, 57125.0, 57402.13896791552, 57424.85150296971, 57492.42507574871, 57535.712321438965, 27938.86030569834, 33079.84220552269, 33275.0, 33549.49675754866, 59502.80994380088, 12304.0, 12921.28932908699, 16070.276837542338, 16500.83499165017, 16571.91714041422, 12773.489060437665, 12838.16551924122, 12842.614721558368, 12844.90818280438, 12891.48434062627, 16816.159192040348, 17502.687298452667, 18747.437576872715, 18990.62018759617, 19001.23995040183, 7980.521168729895, 8065.556394398247, 8068.878966815502, 8098.51408915467, 8105.797355066171, 734.1227233456076, 844.5439462690201, 973.3527204408989, 1028.3007554862616, 1044.3942513342242, 28956.0, 30532.931347402733, 30635.0, 30754.0, 31421.685783141875, 1052.6368402900987, 1132.9660110196708, 1133.0878642692712, 1138.2942575603179, 1208.546794951893, 15129.319180636898, 15609.765853512206, 15708.60728481797, 15782.36870525189, 15927.283272252776, 646808.0, 661330.0, 667206.0, 670452.0, 675696.0, 57585.71207144021, 57956.420435795946, 58327.0, 58539.70730146322, 58764.0, 1351.6688411339285, 1353.5397964692052, 1389.4095009620669, 1395.4069520453766, 1399.3423091147145, 28983.420331593672, 30269.243268917573, 30276.091717247637, 30325.241868953453, 30539.0, 48907.510924890295, 49323.0, 49644.75177624089, 49723.005539889724, 49839.0, 574894.0, 588336.0, 589279.0, 593338.0, 593395.0, 24567.754322456545, 26086.82609282549, 26180.73819261783, 26225.868870655526, 26343.0, 46867.062658747316, 46997.0, 48386.0, 48768.26847597218, 48854.75572622114, 39067.41398879076, 39362.21275574415, 40433.797831010655, 40785.77642670725, 41389.75830725083, 38429.0, 38432.231355372176, 39113.0, 39245.803770980965, 40295.39556906708, 29379.97170099036, 31691.52462713107, 38470.615293847826, 39126.217475649755, 39141.0, 33302.66697332996, 34504.0, 35187.82406087953, 35383.64616353803, 35449.0, 25602.359941001625, 25790.61314080253, 25922.0, 26115.824787884972, 26550.601740973514], "value": 19741.47287779171, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.1793866670657, 357.5566297790739, 376.8172436368361, 379.2377321583616, 383.87524054682234, 19602.803971960275, 20195.899020504894, 20471.795282047213, 20864.956752162387, 21045.894770526163, 20215.292464763737, 20503.897480512613, 20766.584668306627, 20766.584668306627, 21183.0, 5587.469190426903, 5756.647187910838, 5831.7958871439505, 6012.038073908176, 6093.116498107772, 5996.370381109986, 6055.636661800284, 6082.087686846986, 6098.176746139281, 6106.358832322609, 5758.3090029196555, 5937.16879636849, 5966.701664916758, 5992.400759923995, 6071.726772295236, 5584.888302233932, 5842.591018628697, 5963.701814909259, 6006.369331220225, 6031.366706495821, 5061.949380506221, 5070.69575825448, 5114.923276150861, 5167.715775632339, 5169.379674439054, 20068.49828754278, 20495.89752051245, 24614.015439382405, 24884.626730599055, 25240.747592524207, 2141.935741927745, 2143.1748776721074, 2203.6253836847773, 2555.8083143764293, 2655.884528498016, 12155.237490563906, 14669.266536673123, 15237.314320855477, 15237.847621523864, 15648.843511564852, 1865.216609024213, 1866.0762922353385, 1889.8488120950428, 1915.2913422033798, 1958.392898201562, 92850.0, 96150.51924740331, 96597.65310560972, 96691.51654241754, 96874.0, 10477.947610262052, 10882.401467919182, 11075.944620276847, 11195.0, 11336.0, 13272.0, 13565.59303220905, 13579.456821727217, 13596.38816253271, 13670.0, 8280.130586288473, 8535.231829135408, 8551.358648101417, 8608.053114157437, 8681.609327580274, 8660.26387757035, 8663.306935445149, 8683.348748843735, 8752.649894004297, 8823.602937867812, 5517.36550296721, 6440.516961227927, 6479.6760161991715, 6501.707423165969, 6596.307387724316, 1878.2017642501883, 1883.849292056632, 1888.707250376194, 1909.4367161687335, 1938.1084701037398, 729.6133049483782, 787.2678409079497, 897.5243121145803, 962.8892677342055, 1008.6923488336128, 16811.15944202785, 18834.340798072, 19258.90370548166, 21582.89208553947, 21753.564928701337, 830.4975489828687, 945.6595625574788, 1006.4866917871792, 1028.681108856257, 1052.4527245832123, 9192.954035229915, 11071.5571377144, 11477.024452921596, 11675.007624352025, 11821.468033938494, 582058.0, 588097.0, 590476.0, 600534.0, 606206.0, 58875.0, 90984.54507727419, 111497.0, 113128.43435782769, 133678.0, 978.9559469823876, 979.9412035277763, 996.9930370325878, 1039.6101461951769, 1042.2443728296923, 13810.792838107638, 17884.91057544704, 18989.24043038263, 19111.139998699815, 19189.0, 37365.81317093397, 48383.5161648379, 49916.0, 51384.0, 52788.0, 69922.0, 75015.87349201817, 76138.0, 76784.0, 77475.61262193653, 5320.8935821282585, 5350.785968561214, 7776.572288524186, 7928.151130380997, 8252.257296843312, 11576.768464630828, 12476.251424914533, 12554.309512976874, 12756.234625922474, 12900.032497562908, 8874.778130546789, 9353.485558294087, 10990.725231869268, 11144.996950274513, 11174.66476005721, 9758.804823903623, 10569.312994655324, 10787.6224332148, 11002.834957475805, 11213.439328033402, 8707.216350528483, 10754.946225268823, 11371.0, 11436.885631143914, 11562.884371156517, 7898.486598371088, 8033.437659363788, 8114.148014458633, 8155.062167850773, 8214.932058832313, 10280.584062745398, 10334.793304133726, 11164.720881978015, 11221.943890280496, 11290.548378064785], "value": 9429.971119877893, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1517.9620509487265, 1546.3350759173557, 1756.7013607686692, 1871.055117165831, 1903.4004288649066, 8026.478278911866, 8512.233898949093, 8769.298456123512, 9087.636854471833, 9108.817823643523, 8712.433691810027, 8751.212390884819, 8920.152585504367, 8932.151445612673, 8954.417962832427, 2388.9283321500384, 2414.2274472168915, 2416.335507735378, 2427.66012758214, 2433.148398060682, 2398.4723360860585, 2411.7347091819884, 2431.416460049584, 2432.4405386761055, 2436.2082323245018, 2407.4222186675247, 2424.9151279705216, 2425.721042080157, 2430.5989511730545, 2434.586120359534, 2112.725345705064, 2400.195934361991, 2404.7114346278386, 2408.4821763320883, 2417.178159425795, 2390.270967354956, 2405.230326295586, 2408.0969636386353, 2410.3251089694913, 2426.2478631624253, 8932.240759535447, 9011.324150688659, 9017.368784185044, 9019.368644194908, 9037.825082739268, 5287.339082614673, 5386.407495175527, 5430.076886929192, 5450.972745136249, 5472.644278121909, 2894.956575651367, 3630.6006339302658, 3637.9818100909592, 3686.4286035664513, 3703.6481534254203, 942.185009966381, 1029.6665817765936, 1057.9047885690325, 1075.9462026898702, 1085.8045551800674, 11583.247088939192, 11670.94164529172, 11686.76626467466, 11695.766084678344, 11732.70668233301, 4700.905981880412, 4776.090199819, 4789.44921334051, 4884.3650325458175, 4899.240617704261, 10016.49917504122, 10101.797964040677, 10125.392476451436, 10130.0, 10142.391456512632, 5379.6234263601955, 5391.487808658171, 5412.350517937873, 5426.023315803114, 5435.130379139318, 4405.933910991338, 4507.301368287921, 4872.074305881871, 4884.3161957326, 4951.405831300267, 3956.793178080713, 4052.6423648078226, 4097.385392191136, 4128.669706423479, 4133.565975572582, 1307.895368370528, 1315.5790147153011, 1318.4133060787858, 1325.5161865919063, 1326.635175326793, 758.9962050189714, 860.5181098584812, 898.725888603969, 903.6566104880103, 926.4070994563485, 5365.141577347684, 5653.869226154786, 5815.621984570989, 6062.818115456544, 6171.9199140150295, 903.3586153830854, 925.1488630459993, 949.6818565780541, 966.9951650241704, 1014.1329163565193, 3999.5800440953863, 4018.477597912253, 4089.8957281533817, 4173.937390939138, 4186.623203911602, 8087.433879628369, 10682.092022178202, 10695.144388449062, 10707.036366727032, 10747.462626868626, 10777.407242601575, 10799.650043744532, 10841.457927103613, 10842.349459032326, 10845.13238940883, 6050.092486127028, 7768.0, 8070.82972968928, 8263.380246481538, 8270.007599088269, 5722.971385143048, 5774.39368866263, 5937.139114828239, 5970.820875373745, 5973.1637570741, 9935.516120969758, 10020.34867733595, 10065.396076235449, 10148.949255253676, 10221.9488902555, 9260.53697315118, 10310.226732995205, 10322.896771032194, 10325.586976520855, 10343.172546196436, 6543.116679248431, 6808.897866532105, 6997.335253150943, 7034.050403195708, 7173.569585824867, 7217.314355136254, 8472.61873215719, 8553.44402613828, 8555.230029297392, 8562.52906090171, 7444.516106453063, 7476.439267054884, 7626.237376262329, 7698.461107722405, 7709.383249340151, 6467.0, 7518.624068796647, 7538.623068846426, 7559.130699969354, 7695.538267703956, 6864.5881247125335, 6903.723851045902, 6914.9308506914285, 6925.342092501204, 7046.365827075588, 4992.475790042138, 5013.0, 5028.396592408934, 5078.492150784892, 5117.718525481134, 4951.133551628379, 4957.876053098701, 5075.847724568269, 5122.078025955364, 5155.948440515697], "value": 4617.619729108992, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.45378822284664, 299.1563790111884, 305.31152251672484, 311.36481577581725, 313.7364613724471, 4553.726776393419, 5303.761330740116, 5475.863103422416, 5489.451054894508, 5679.630823996437, 4555.382839092124, 5448.863778405542, 5468.562514998801, 5546.921458590491, 5638.73128546078, 4042.393640953851, 4267.07981408366, 4279.315309550472, 4281.978590107045, 4282.892927676802, 4123.608257215575, 4212.515560710512, 4301.333293339521, 4308.224519586473, 4321.271491403432, 3283.343331333731, 3972.960270397288, 4177.665786737069, 4180.372944058385, 4187.1625674865145, 4201.915961680749, 4212.694064839879, 4225.893967933566, 4246.978765106154, 4248.893777655552, 3655.853765849363, 3665.8900232992787, 3668.559772827278, 3673.6142705016123, 3690.7785532868115, 4797.960866631703, 5221.269022336875, 5267.604929630293, 5438.592105592096, 5442.857000029999, 4070.9389359159636, 4868.488808675073, 4885.899063402735, 4898.926516102261, 4911.7544122793715, 5142.280080788692, 5682.9147562786175, 5762.827115186551, 5958.6424814511265, 5969.224000879858, 3998.7400818946676, 4015.3976903464422, 4021.3713446054394, 4031.9395209071886, 4049.250888585621, 5687.60186786921, 6215.595986260879, 6303.235094173593, 6305.842353941142, 6331.683415829236, 4063.4108054331955, 4097.631213190827, 4108.979455102705, 4111.2188684150515, 4118.794060296973, 3760.2291530236234, 4006.899827504336, 4066.9389959150635, 4072.3891416287793, 4073.857414990461, 4332.805023773938, 4532.864014079583, 4817.6634332348785, 4845.9030819383415, 4850.733209673432, 5005.424376196711, 5250.921236181461, 5785.768569257182, 5791.971040144772, 6012.759489620366, 1160.3907948327196, 1233.444949772606, 1277.7827769279245, 1286.9704236610673, 1358.5448874627111, 732.8786955957394, 744.2743325257939, 818.5825229133185, 847.2501835875295, 854.5897968974926, 3789.3936970084656, 4099.241640296555, 4165.729227600196, 4517.944335327418, 4678.532146785295, 4558.407407037064, 5083.779892825695, 5086.364204474441, 5116.513931176532, 5164.767585458589, 4006.599340066028, 4113.239050775556, 4604.953950460519, 4615.769211539477, 4635.65232607549, 2483.6235153052035, 2544.625902012929, 2665.840359443648, 2668.813183077166, 2744.4511097780123, 5190.702324418895, 5296.390915044819, 5344.412114667383, 5349.1976203569, 5389.973050134724, 4442.379924878155, 4870.592114136712, 5110.7955681773055, 5162.6902385856965, 5307.734613269398, 4367.825286988548, 4723.976380118077, 4745.596624286905, 4790.640701947368, 4958.520739630185, 4244.299690551148, 4709.163426263828, 5028.8433660257615, 5035.4712755161645, 5129.897402052013, 3907.648311651851, 3927.8821635350987, 4181.623653871166, 4378.584034516716, 4752.0, 4720.339152518719, 4728.952710472851, 4901.284550407329, 5183.92224116631, 5337.519623233928, 3370.5618269624792, 3494.972915708832, 3511.122219445139, 3522.2212782545676, 3522.489239060373, 4000.3399439092223, 4646.3727396801, 5107.693538387709, 5156.88008754698, 5752.712364381848, 5695.572832037531, 5833.562482813721, 5852.356240813592, 5900.082473196143, 5919.378465261258, 5302.390225124006, 5592.853465039576, 5666.28018551106, 5891.381404952418, 5981.726943761343, 5030.320906677698, 5071.2139618359215, 5349.144136938071, 5467.425920278472, 5736.741846616829, 5132.384113906355, 5241.31862857826, 5380.704061276668, 5942.732577033958, 6003.759849605967, 4453.732776033448, 4688.835890743808, 5445.237666726741, 5818.854528636818, 5828.242328497268], "value": 3980.6188442323023, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.7049416280186, 694.17046629278, 738.2986163145014, 764.4114032195209, 809.7611204694615, 14694.338754756034, 15121.370870990966, 15510.767338489917, 15565.455209067684, 15587.766183507243, 14058.929705351484, 14211.934104942118, 14266.429342826275, 14326.068805527659, 14329.283535823219, 856.1566856646214, 868.9522076285801, 875.9474431534096, 878.3763527895202, 878.7144178142097, 687.6871023684237, 756.3722110648149, 774.9767506974799, 847.0851480401183, 857.8884744983144, 751.5903832411349, 866.2030931542964, 918.6968300460832, 920.8342498350295, 922.7139586728102, 908.0919080919015, 913.808100298938, 917.0462718772502, 922.9815403691889, 923.2798417234534, 683.2211279141783, 683.73676134688, 685.2496516314627, 685.6331862453613, 686.5125760709902, 14652.194129322883, 14751.77872331916, 14790.482333118289, 14805.185714785683, 14860.554183374516, 14337.56987290383, 14338.211398373085, 14435.206063666494, 14556.0, 14666.413343466249, 11838.526458941718, 11842.703932401673, 11843.940780296129, 11870.050395968301, 11911.225770324902, 3772.6793222576116, 3777.54669439669, 3796.90507737306, 3822.808859557039, 3949.7630142191274, 89860.0, 93630.35990141312, 93682.53158734231, 106662.0, 114352.85647143505, 10987.835182472269, 11098.056665183389, 11098.944505277583, 11137.665870023913, 11289.435528223721, 21736.782632173476, 21884.562308753735, 21956.560868782533, 22012.449688757588, 22059.33821985343, 7625.580593067434, 7627.847443051108, 7753.10839253493, 7821.374290056782, 7920.009998750156, 4303.1608836277255, 4319.33050377192, 4387.188370151533, 4428.33574963758, 4461.643068554507, 3541.0, 3645.2527231917393, 3647.1793846384353, 3651.141981634367, 3707.796071216109, 662.1987395251703, 664.1299897134769, 671.3689132215702, 684.1858188755465, 684.5789839248926, 547.8411260734422, 577.2293987526699, 598.3956204233668, 617.6325086573481, 621.9582199815292, 7791.80520486981, 9223.893132824103, 9420.53981632848, 9537.666181683608, 9741.756456088513, 651.6057785039983, 654.9672516374162, 681.2540268406087, 681.6898311268361, 683.2040672616446, 4204.0, 4249.256380133526, 4441.289393697058, 4713.976430117896, 4827.927581086286, 166257.0, 167864.0, 168138.1593092051, 169938.30061699473, 173651.39522907167, 90114.0, 115872.0, 143137.0, 148892.0, 149006.50993490143, 112149.43925280322, 119201.0, 128647.35676321745, 130482.0, 130616.0, 7461.850762984602, 8973.28213742886, 8989.550522473719, 9023.007469178516, 9126.0, 67465.0, 68663.65668171627, 68917.96623050558, 69233.0, 70281.0, 80974.0, 99068.0, 99237.51143732994, 99831.50084249533, 105568.0, 11226.382548959886, 11669.824952625548, 11677.0, 11757.76484470323, 12246.75506489883, 12967.546135885575, 13042.4130914111, 13231.0, 13303.20180789117, 13705.588832335046, 11934.462949167135, 11965.700857478632, 12070.818937716116, 12178.634640960785, 12547.874521254671, 9190.31072669539, 10522.736931576466, 12023.27860328383, 12096.879031209575, 12207.267563946192, 12044.939775301067, 12076.818847717466, 12169.417975663107, 12172.208806427554, 12465.875341246472, 8115.9188408117525, 10026.548805303633, 10182.727159105112, 10532.420716860339, 10539.894601054199, 7697.6151192441275, 9600.855987160337, 10323.225758068325, 10360.637377691744, 10740.301880378063], "value": 7681.31497719963, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1646.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 118.0, 117.0, 113.0, 116.0, 121.0, 109.0, 165.0, 122.0, 197.0], "value": 128.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 139.0, 136.0, 163.0, 111.0, 130.0, 130.0, 114.0, 160.0, 112.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 220.0, 242.0, 270.0, 274.0, 195.0, 196.0, 201.0, 198.0, 198.0], "value": 219.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 82.0, 85.0, 193.0, 72.0, 82.0, 87.0, 88.0, 135.0, 60.0], "value": 95.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 109.0, 102.0, 104.0, 111.0, 146.0, 92.0, 73.0, 113.0], "value": 99.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 91.0, 89.0, 91.0, 88.0, 94.0, 92.0, 129.0, 89.0], "value": 94.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 102.0, 100.0, 102.0, 102.0, 103.0, 101.0, 101.0, 101.0, 101.0], "value": 101.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 100.0, 146.0, 92.0, 86.0, 89.0, 85.0, 144.0, 148.0, 93.0], "value": 107.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 49.0, 88.0, 87.0, 87.0, 45.0, 51.0, 79.0, 45.0, 50.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 75.0, 75.0, 95.0, 97.0, 77.0, 71.0, 69.0, 72.0], "value": 76.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 112.0, 114.0, 76.0, 112.0, 112.0, 109.0, 91.0, 83.0, 86.0], "value": 99.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 157.0, 165.0, 162.0, 138.0, 146.0, 149.0, 151.0, 200.0, 152.0], "value": 157.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 199.0, 252.0, 177.0, 172.0, 175.0, 176.0, 177.0, 202.0, 243.0], "value": 195.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 69.0, 68.0, 71.0, 67.0, 66.0, 71.0, 89.0, 84.0], "value": 72.3, "unit": "score"}]}]} 10:08:54 INFO - Return code: 0 10:08:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:08:54 ERROR - # TBPL SUCCESS # 10:08:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:08:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:08:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:08:54 INFO - 2016-09-06 10:08:54.792 screenresolution[1672:10467] starting screenresolution argv=screenresolution get 10:08:54 INFO - 2016-09-06 10:08:54.794 screenresolution[1672:10467] Display 0: 1600x1200x32@60 10:08:54 INFO - 2016-09-06 10:08:54.800 screenresolution[1673:10470] starting screenresolution argv=screenresolution list 10:08:54 INFO - Available Modes on Display 0 10:08:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:08:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:08:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:08:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:08:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:08:55 INFO - Intel Iris: 10:08:55 INFO - Chipset Model: Intel Iris 10:08:55 INFO - Type: GPU 10:08:55 INFO - Bus: Built-In 10:08:55 INFO - VRAM (Dynamic, Max): 1536 MB 10:08:55 INFO - Vendor: Intel (0x8086) 10:08:55 INFO - Device ID: 0x0a2e 10:08:55 INFO - Revision ID: 0x0009 10:08:55 INFO - Displays: 10:08:55 INFO - Display: 10:08:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:08:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:08:55 INFO - Display Serial Number: 1600x1200 60 10:08:55 INFO - Main Display: Yes 10:08:55 INFO - Mirror: Off 10:08:55 INFO - Online: Yes 10:08:55 INFO - Rotation: Supported 10:08:55 INFO - Return code: 0 10:08:55 INFO - Running post-action listener: _resource_record_post_action 10:08:55 INFO - [mozharness: 2016-09-06 17:08:55.039771Z] Finished run-tests step (success) 10:08:55 INFO - Running post-run listener: _resource_record_post_run 10:08:55 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 38175744; Write bytes: 707369984; Read time: 377; Write time: 2163 10:08:55 INFO - TinderboxPrint: CPU usage
54.6% 10:08:55 INFO - TinderboxPrint: I/O read bytes / time
38,175,744 / 377 10:08:55 INFO - TinderboxPrint: I/O write bytes / time
707,369,984 / 2,163 10:08:55 INFO - TinderboxPrint: CPU idle
2,200.6 (45.4%) 10:08:55 INFO - TinderboxPrint: CPU system
985.2 (20.3%) 10:08:55 INFO - TinderboxPrint: CPU user
1,659.9 (34.3%) 10:08:55 INFO - TinderboxPrint: Swap in / out
444,174,336 / 0 10:08:55 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 391096832; Write bytes: 381593600; Read time: 29130; Write time: 804 10:08:55 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 31894528; Write bytes: 297407488; Read time: 293; Write time: 1308 10:08:55 INFO - Running post-run listener: _upload_blobber_files 10:08:55 INFO - Blob upload gear active. 10:08:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:08:55 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:08:55 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'] 10:08:55 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 10:08:56 INFO - (blobuploader) - INFO - Open directory for files ... 10:08:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:08:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:08:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:08:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:08:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:08:57 INFO - (blobuploader) - INFO - Done attempting. 10:08:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:08:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:08:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:08:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:08:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:08:58 INFO - (blobuploader) - INFO - Done attempting. 10:08:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:08:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:08:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:09:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:01 INFO - (blobuploader) - INFO - Done attempting. 10:09:01 INFO - (blobuploader) - INFO - Iteration through files over. 10:09:01 INFO - Return code: 0 10:09:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:09:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:09:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b108313d004e565b0264406ecbd0e7eb31b7f2b7419ce21e630f969d267fc29c909d155c9ba793fb884c746a36629d3de9c20ef2e36916088725f4034c699014", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5ccd891c973a3bd9dd81eb4f82007b74a55fac83368f10affdc539296261286731fc33941a2e39d9d217f4c55f091032a85c51dfba9d60980f78976f724c8f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6a4d8fc531ec82d44de7f2d31e966a89a73226a0491b623815890c1b7d3d03e7dbcd1eae06f1c33de37aefc4bba44974634cb048f887d2b87d2e84ace72e9f8"} 10:09:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:09:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:09:01 INFO - Contents: 10:09:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b108313d004e565b0264406ecbd0e7eb31b7f2b7419ce21e630f969d267fc29c909d155c9ba793fb884c746a36629d3de9c20ef2e36916088725f4034c699014", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5ccd891c973a3bd9dd81eb4f82007b74a55fac83368f10affdc539296261286731fc33941a2e39d9d217f4c55f091032a85c51dfba9d60980f78976f724c8f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6a4d8fc531ec82d44de7f2d31e966a89a73226a0491b623815890c1b7d3d03e7dbcd1eae06f1c33de37aefc4bba44974634cb048f887d2b87d2e84ace72e9f8"} 10:09:01 INFO - Running post-run listener: copy_logs_to_upload_dir 10:09:01 INFO - Copying logs to upload dir... 10:09:01 INFO - mkdir: /builds/slave/test/build/upload/logs 10:09:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.134116 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-06 10:09:01.861145) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 10:09:01.865259) ========= 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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b108313d004e565b0264406ecbd0e7eb31b7f2b7419ce21e630f969d267fc29c909d155c9ba793fb884c746a36629d3de9c20ef2e36916088725f4034c699014", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5ccd891c973a3bd9dd81eb4f82007b74a55fac83368f10affdc539296261286731fc33941a2e39d9d217f4c55f091032a85c51dfba9d60980f78976f724c8f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6a4d8fc531ec82d44de7f2d31e966a89a73226a0491b623815890c1b7d3d03e7dbcd1eae06f1c33de37aefc4bba44974634cb048f887d2b87d2e84ace72e9f8"} build_url:https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010953 build_url: 'https://queue.taskcluster.net/v1/task/bS4T_DZXQuizAPB3BTiHaA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b108313d004e565b0264406ecbd0e7eb31b7f2b7419ce21e630f969d267fc29c909d155c9ba793fb884c746a36629d3de9c20ef2e36916088725f4034c699014", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5ccd891c973a3bd9dd81eb4f82007b74a55fac83368f10affdc539296261286731fc33941a2e39d9d217f4c55f091032a85c51dfba9d60980f78976f724c8f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6a4d8fc531ec82d44de7f2d31e966a89a73226a0491b623815890c1b7d3d03e7dbcd1eae06f1c33de37aefc4bba44974634cb048f887d2b87d2e84ace72e9f8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 10:09:01.900002) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 10:09:01.900400) ========= 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.epEB9S5Sxl/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.QpwsQAuHbS/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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.004960 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 10:09:01.949798) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 10:09:01.950175) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 10:09:01.950618) ========= ========= Total master_lag: 0.19 =========