builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0176 starttime: 1473333177.87 results: success (0) buildid: 20160908030116 builduid: c9088ca10dcc43d7950426a31416600a revision: 331524df5cab6383f44d55d777b7cededdbc12c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 04:12:57.868317) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 04:12:57.868764) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 04:12:57.869042) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 04:12:58.021016) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 04:12:58.021321) ========= 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.7LoVr62NCY/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.evYvzT4g1f/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-08 04:12:58-- 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% 159M=0s 2016-09-08 04:12:58 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.495546 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 04:12:58.545631) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 04:12:58.545907) ========= 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.7LoVr62NCY/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.evYvzT4g1f/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.020321 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 04:12:58.609437) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 04:12:58.609745) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 331524df5cab6383f44d55d777b7cededdbc12c9 --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 331524df5cab6383f44d55d777b7cededdbc12c9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7LoVr62NCY/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.evYvzT4g1f/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-08 04:12:58,734 truncating revision to first 12 chars 2016-09-08 04:12:58,734 Setting DEBUG logging. 2016-09-08 04:12:58,734 attempt 1/10 2016-09-08 04:12:58,734 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/331524df5cab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 04:12:59,355 unpacking tar archive at: mozilla-central-331524df5cab/testing/mozharness/ program finished with exit code 0 elapsedTime=0.885181 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 04:12:59.566714) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 04:12:59.567122) ========= script_repo_revision: 331524df5cab6383f44d55d777b7cededdbc12c9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 04:12:59.567499) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 04:12:59.567760) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 04:12:59.589505) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-08 04:12:59.590196) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7LoVr62NCY/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.evYvzT4g1f/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 04:12:59 INFO - MultiFileLogger online at 20160908 04:12:59 in /builds/slave/test 04:12:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:12:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:12:59 INFO - {'append_to_log': False, 04:12:59 INFO - 'base_work_dir': '/builds/slave/test', 04:12:59 INFO - 'blob_upload_branch': 'Firefox', 04:12:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:12:59 INFO - 'branch': 'Firefox', 04:12:59 INFO - 'buildbot_json_path': 'buildprops.json', 04:12:59 INFO - 'config_files': ('talos/mac_config.py',), 04:12:59 INFO - 'default_actions': ('clobber', 04:12:59 INFO - 'read-buildbot-config', 04:12:59 INFO - 'download-and-extract', 04:12:59 INFO - 'populate-webroot', 04:12:59 INFO - 'create-virtualenv', 04:12:59 INFO - 'install', 04:12:59 INFO - 'run-tests'), 04:12:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:12:59 INFO - 'download_minidump_stackwalk': True, 04:12:59 INFO - 'download_symbols': 'ondemand', 04:12:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:12:59 INFO - 'tooltool.py': '/tools/tooltool.py', 04:12:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:12:59 INFO - '/tools/misc-python/virtualenv.py')}, 04:12:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:12:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:12:59 INFO - 'installer_path': 'installer.exe', 04:12:59 INFO - 'log_level': 'info', 04:12:59 INFO - 'log_name': 'talos', 04:12:59 INFO - 'log_to_console': True, 04:12:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:12:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:12:59 INFO - 'opt_config_files': (), 04:12:59 INFO - 'pip_index': False, 04:12:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:12:59 INFO - 'cmd': ('bash', 04:12:59 INFO - '-c', 04:12:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:12:59 INFO - 'enabled': True, 04:12:59 INFO - 'halt_on_failure': False, 04:12:59 INFO - 'name': 'check_screen_resolution'},), 04:12:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:12:59 INFO - 'cmd': ('bash', 04:12:59 INFO - '-c', 04:12:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:12:59 INFO - 'enabled': True, 04:12:59 INFO - 'halt_on_failure': False, 04:12:59 INFO - 'name': 'check_screen_resolution'},), 04:12:59 INFO - 'run_cmd_checks_enabled': True, 04:12:59 INFO - 'sps_profile': False, 04:12:59 INFO - 'sps_profile_interval': 0, 04:12:59 INFO - 'suite': 'dromaeojs', 04:12:59 INFO - 'system_bits': '32', 04:12:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:12:59 INFO - 'title': 't-yosemite-r7-0176', 04:12:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:12:59 INFO - 'use_talos_json': True, 04:12:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:12:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:12:59 INFO - 'work_dir': 'build'} 04:12:59 INFO - [mozharness: 2016-09-08 11:12:59.788669Z] Running clobber step. 04:12:59 INFO - Running pre-action listener: _resource_record_pre_action 04:12:59 INFO - Running main action method: clobber 04:12:59 INFO - rmtree: /builds/slave/test/build 04:12:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:13:01 INFO - Running post-action listener: _resource_record_post_action 04:13:01 INFO - [mozharness: 2016-09-08 11:13:01.326599Z] Finished clobber step (success) 04:13:01 INFO - [mozharness: 2016-09-08 11:13:01.326729Z] Running read-buildbot-config step. 04:13:01 INFO - Running pre-action listener: _resource_record_pre_action 04:13:01 INFO - Running main action method: read_buildbot_config 04:13:01 INFO - Using buildbot properties: 04:13:01 INFO - { 04:13:01 INFO - "project": "", 04:13:01 INFO - "product": "firefox", 04:13:01 INFO - "script_repo_revision": "production", 04:13:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:13:01 INFO - "repository": "", 04:13:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:13:01 INFO - "buildid": "20160908030116", 04:13:01 INFO - "pgo_build": "False", 04:13:01 INFO - "basedir": "/builds/slave/test", 04:13:01 INFO - "buildnumber": 28, 04:13:01 INFO - "slavename": "t-yosemite-r7-0176", 04:13:01 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:13:01 INFO - "platform": "yosemite_r7", 04:13:01 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:13:01 INFO - "branch": "mozilla-central", 04:13:01 INFO - "repo_path": "mozilla-central", 04:13:01 INFO - "revision": "331524df5cab6383f44d55d777b7cededdbc12c9", 04:13:01 INFO - "stage_platform": "macosx64", 04:13:01 INFO - "builduid": "c9088ca10dcc43d7950426a31416600a", 04:13:01 INFO - "slavebuilddir": "test" 04:13:01 INFO - } 04:13:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 04:13:01 INFO - Running post-action listener: _resource_record_post_action 04:13:01 INFO - [mozharness: 2016-09-08 11:13:01.330191Z] Finished read-buildbot-config step (success) 04:13:01 INFO - [mozharness: 2016-09-08 11:13:01.330306Z] Running download-and-extract step. 04:13:01 INFO - Running pre-action listener: _resource_record_pre_action 04:13:01 INFO - Running main action method: download_and_extract 04:13:01 INFO - mkdir: /builds/slave/test/build/tests 04:13:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:13:01 INFO - trying https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 04:13:01 INFO - Downloading https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/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 04:13:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/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 04:13:02 INFO - Downloaded 1199 bytes. 04:13:02 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 04:13:02 INFO - Using the following test package requirements: 04:13:02 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:13:02 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:13:02 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 04:13:02 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:13:02 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 04:13:02 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:13:02 INFO - u'jsshell-mac.zip'], 04:13:02 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:13:02 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 04:13:02 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:13:02 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:13:02 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 04:13:02 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:13:02 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 04:13:02 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:13:02 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 04:13:02 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:13:02 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 04:13:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:13:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:13:03 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 04:13:10 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 04:13:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:13:10 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 04:13:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 04:13:18 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 04:13: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')]} 04:13:18 INFO - trying https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:13:18 INFO - Downloading https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 04:13:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/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 04:13:21 INFO - Downloaded 111492076 bytes. 04:13:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:13:21 INFO - mkdir: /builds/slave/test/properties 04:13:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:13:21 INFO - Writing to file /builds/slave/test/properties/build_url 04:13:21 INFO - Contents: 04:13:21 INFO - build_url:https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:13:22 INFO - Running post-action listener: _resource_record_post_action 04:13:22 INFO - Running post-action listener: set_extra_try_arguments 04:13:22 INFO - [mozharness: 2016-09-08 11:13:22.260294Z] Finished download-and-extract step (success) 04:13:22 INFO - [mozharness: 2016-09-08 11:13:22.260416Z] Running populate-webroot step. 04:13:22 INFO - Running pre-action listener: _resource_record_pre_action 04:13:22 INFO - Running main action method: populate_webroot 04:13:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:13:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:13:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:13:22 INFO - '--tppagecycles', 04:13:22 INFO - '7'], 04:13:22 INFO - 'tests': ['tsvgm']}}, 04:13:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:13:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:13:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:13:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:13:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:13:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:13:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:22 INFO - 'talos_options': ['--disable-e10s'], 04:13:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:22 INFO - 'talos_options': ['--disable-e10s'], 04:13:22 INFO - 'tests': ['damp', 'tps']}, 04:13:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:22 INFO - 'tests': ['damp', 'tps']}, 04:13:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:13:22 INFO - 'tests': ['dromaeo_dom']}, 04:13:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:13:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:13:22 INFO - 'tests': ['basic_compositor_video']}, 04:13:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:13:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:13:22 INFO - 'tests': ['a11yr', 04:13:22 INFO - 'ts_paint', 04:13:22 INFO - 'tpaint', 04:13:22 INFO - 'sessionrestore', 04:13:22 INFO - 'sessionrestore_no_auto_restore', 04:13:22 INFO - 'tabpaint']}, 04:13:22 INFO - 'other-e10s': {'tests': ['a11yr', 04:13:22 INFO - 'ts_paint', 04:13:22 INFO - 'tpaint', 04:13:22 INFO - 'sessionrestore', 04:13:22 INFO - 'sessionrestore_no_auto_restore', 04:13:22 INFO - 'tabpaint']}, 04:13:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:13:22 INFO - 'ts_paint', 04:13:22 INFO - 'tpaint', 04:13:22 INFO - 'sessionrestore', 04:13:22 INFO - 'sessionrestore_no_auto_restore', 04:13:22 INFO - 'tabpaint']}, 04:13:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:13:22 INFO - 'ts_paint', 04:13:22 INFO - 'tpaint', 04:13:22 INFO - 'sessionrestore', 04:13:22 INFO - 'sessionrestore_no_auto_restore', 04:13:22 INFO - 'tabpaint']}, 04:13:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:13:22 INFO - 'tests': ['a11yr', 04:13:22 INFO - 'ts_paint', 04:13:22 INFO - 'tpaint', 04:13:22 INFO - 'sessionrestore', 04:13:22 INFO - 'sessionrestore_no_auto_restore', 04:13:22 INFO - 'tabpaint']}, 04:13:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:13:22 INFO - 'tests': ['a11yr', 04:13:22 INFO - 'ts_paint', 04:13:22 INFO - 'tpaint', 04:13:22 INFO - 'sessionrestore', 04:13:22 INFO - 'sessionrestore_no_auto_restore', 04:13:22 INFO - 'tabpaint']}, 04:13:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:13:22 INFO - 'tests': ['tsvgx', 04:13:22 INFO - 'tsvgr_opacity', 04:13:22 INFO - 'tart', 04:13:22 INFO - 'tscrollx', 04:13:22 INFO - 'cart']}, 04:13:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:13:22 INFO - 'tsvgr_opacity', 04:13:22 INFO - 'tart', 04:13:22 INFO - 'tscrollx', 04:13:22 INFO - 'cart']}, 04:13:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:22 INFO - 'talos_options': ['--disable-e10s'], 04:13:22 INFO - 'tests': ['tp5o']}, 04:13:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:22 INFO - 'tests': ['tp5o']}, 04:13:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:22 INFO - 'talos_options': ['--disable-e10s', 04:13:22 INFO - '--xperf_path', 04:13:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:22 INFO - 'tests': ['tp5n']}, 04:13:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:22 INFO - 'talos_options': ['--xperf_path', 04:13:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:22 INFO - 'tests': ['tp5n']}}, 04:13:22 INFO - 'talos.zip': {'path': '', 04:13:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:13:22 INFO - Running post-action listener: _resource_record_post_action 04:13:22 INFO - [mozharness: 2016-09-08 11:13:22.279117Z] Finished populate-webroot step (success) 04:13:22 INFO - [mozharness: 2016-09-08 11:13:22.279201Z] Running create-virtualenv step. 04:13:22 INFO - Running pre-action listener: _resource_record_pre_action 04:13:22 INFO - Running main action method: create_virtualenv 04:13:22 INFO - Creating virtualenv /builds/slave/test/build/venv 04:13:22 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 04:13:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:13:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:13:22 INFO - Using real prefix '/tools/python27' 04:13:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:13:23 INFO - Installing distribute.............................................................................................................................................................................................done. 04:13:25 INFO - Installing pip.................done. 04:13:25 INFO - Return code: 0 04:13:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:13:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:13:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd540d67ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcfd30>, '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 0x109d34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc7b20>, '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.7LoVr62NCY/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.evYvzT4g1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 04:13:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:13:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:13:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LoVr62NCY/Render', 04:13:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:13:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:13:25 INFO - 'HOME': '/Users/cltbld', 04:13:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:13:25 INFO - 'LOGNAME': 'cltbld', 04:13:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:25 INFO - 'MOZ_NO_REMOTE': '1', 04:13:25 INFO - 'NO_EM_RESTART': '1', 04:13:25 INFO - 'PAGER': '/bin/cat', 04:13:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:13:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:25 INFO - 'PWD': '/builds/slave/test', 04:13:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:13:25 INFO - 'SHELL': '/bin/bash', 04:13:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.evYvzT4g1f/Listeners', 04:13:25 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 04:13:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:13:25 INFO - 'USER': 'cltbld', 04:13:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:13:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:13:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:25 INFO - 'XPC_FLAGS': '0x0', 04:13:25 INFO - 'XPC_SERVICE_NAME': '0', 04:13:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:13:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:26 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 04:13:26 INFO - Cleaning up... 04:13:26 INFO - Return code: 0 04:13:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:13:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd540d67ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcfd30>, '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 0x109d34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc7b20>, '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.7LoVr62NCY/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.evYvzT4g1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 04:13:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:13:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:13:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LoVr62NCY/Render', 04:13:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:13:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:13:26 INFO - 'HOME': '/Users/cltbld', 04:13:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:13:26 INFO - 'LOGNAME': 'cltbld', 04:13:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:26 INFO - 'MOZ_NO_REMOTE': '1', 04:13:26 INFO - 'NO_EM_RESTART': '1', 04:13:26 INFO - 'PAGER': '/bin/cat', 04:13:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:13:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:26 INFO - 'PWD': '/builds/slave/test', 04:13:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:13:26 INFO - 'SHELL': '/bin/bash', 04:13:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.evYvzT4g1f/Listeners', 04:13:26 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 04:13:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:13:26 INFO - 'USER': 'cltbld', 04:13:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:13:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:13:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:26 INFO - 'XPC_FLAGS': '0x0', 04:13:26 INFO - 'XPC_SERVICE_NAME': '0', 04:13:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:13:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:26 INFO - Downloading/unpacking psutil>=3.1.1 04:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:13:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:29 INFO - Installing collected packages: psutil 04:13:29 INFO - Running setup.py install for psutil 04:13:30 INFO - building 'psutil._psutil_osx' extension 04:13:30 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 04:13:30 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 04:13:30 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 04:13:30 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 04:13:30 INFO - building 'psutil._psutil_posix' extension 04:13:30 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 04:13:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:13:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:13:30 INFO - ^ 04:13:30 INFO - 1 warning generated. 04:13:30 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 04:13:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:30 INFO - Successfully installed psutil 04:13:30 INFO - Cleaning up... 04:13:30 INFO - Return code: 0 04:13:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:13:30 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')]} 04:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:30 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')]} 04:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:30 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 0x10a06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd540d67ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcfd30>, '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 0x109d34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc7b20>, '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.7LoVr62NCY/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.evYvzT4g1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 04:13:30 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 04:13:30 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 04:13:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LoVr62NCY/Render', 04:13:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:13:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:13:30 INFO - 'HOME': '/Users/cltbld', 04:13:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:13:30 INFO - 'LOGNAME': 'cltbld', 04:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:30 INFO - 'MOZ_NO_REMOTE': '1', 04:13:30 INFO - 'NO_EM_RESTART': '1', 04:13:30 INFO - 'PAGER': '/bin/cat', 04:13:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:30 INFO - 'PWD': '/builds/slave/test', 04:13:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:13:30 INFO - 'SHELL': '/bin/bash', 04:13:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.evYvzT4g1f/Listeners', 04:13:30 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 04:13:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:13:30 INFO - 'USER': 'cltbld', 04:13:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:13:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:30 INFO - 'XPC_FLAGS': '0x0', 04:13:30 INFO - 'XPC_SERVICE_NAME': '0', 04:13:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:13:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:13:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:13:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:13:33 INFO - Installing collected packages: mozsystemmonitor 04:13:33 INFO - Running setup.py install for mozsystemmonitor 04:13:33 INFO - Successfully installed mozsystemmonitor 04:13:33 INFO - Cleaning up... 04:13:33 INFO - Return code: 0 04:13:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd540d67ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcfd30>, '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 0x109d34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc7b20>, '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.7LoVr62NCY/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.evYvzT4g1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 04:13:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:13:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:13:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LoVr62NCY/Render', 04:13:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:13:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:13:33 INFO - 'HOME': '/Users/cltbld', 04:13:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:13:33 INFO - 'LOGNAME': 'cltbld', 04:13:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:33 INFO - 'MOZ_NO_REMOTE': '1', 04:13:33 INFO - 'NO_EM_RESTART': '1', 04:13:33 INFO - 'PAGER': '/bin/cat', 04:13:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:13:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:33 INFO - 'PWD': '/builds/slave/test', 04:13:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:13:33 INFO - 'SHELL': '/bin/bash', 04:13:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.evYvzT4g1f/Listeners', 04:13:33 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 04:13:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:13:33 INFO - 'USER': 'cltbld', 04:13:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:13:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:13:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:33 INFO - 'XPC_FLAGS': '0x0', 04:13:33 INFO - 'XPC_SERVICE_NAME': '0', 04:13:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:13:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:34 INFO - Downloading/unpacking jsonschema==2.5.1 04:13:34 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 04:13:34 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 04:13:34 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 04:13:34 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 04:13:34 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 04:13:34 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 04:13:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:13:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:36 INFO - Downloading functools32-3.2.3-2.tar.gz 04:13:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:13:37 INFO - warning: no files found matching '*.txt' 04:13:37 INFO - no previously-included directories found matching 'build' 04:13:37 INFO - no previously-included directories found matching 'dist' 04:13:37 INFO - no previously-included directories found matching '.git*' 04:13:37 INFO - Installing collected packages: jsonschema, functools32 04:13:37 INFO - Running setup.py install for functools32 04:13:37 INFO - warning: no files found matching '*.txt' 04:13:37 INFO - no previously-included directories found matching 'build' 04:13:37 INFO - no previously-included directories found matching 'dist' 04:13:37 INFO - no previously-included directories found matching '.git*' 04:13:37 INFO - Successfully installed jsonschema functools32 04:13:37 INFO - Cleaning up... 04:13:37 INFO - Return code: 0 04:13:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:37 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 0x10a06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd540d67ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcfd30>, '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 0x109d34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc7b20>, '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.7LoVr62NCY/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.evYvzT4g1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 04:13:37 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 04:13:37 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 04:13:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LoVr62NCY/Render', 04:13:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:13:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:13:37 INFO - 'HOME': '/Users/cltbld', 04:13:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:13:37 INFO - 'LOGNAME': 'cltbld', 04:13:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:37 INFO - 'MOZ_NO_REMOTE': '1', 04:13:37 INFO - 'NO_EM_RESTART': '1', 04:13:37 INFO - 'PAGER': '/bin/cat', 04:13:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:13:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:37 INFO - 'PWD': '/builds/slave/test', 04:13:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:13:37 INFO - 'SHELL': '/bin/bash', 04:13:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.evYvzT4g1f/Listeners', 04:13:37 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 04:13:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:13:37 INFO - 'USER': 'cltbld', 04:13:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:13:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:13:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:37 INFO - 'XPC_FLAGS': '0x0', 04:13:37 INFO - 'XPC_SERVICE_NAME': '0', 04:13:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:13:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:13:37 INFO - Cleaning up... 04:13:37 INFO - Return code: 0 04:13:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:37 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 0x10a06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd540d67ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcfd30>, '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 0x109d34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc7b20>, '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.7LoVr62NCY/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.evYvzT4g1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 04:13:37 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 04:13:37 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 04:13:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LoVr62NCY/Render', 04:13:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:13:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:13:37 INFO - 'HOME': '/Users/cltbld', 04:13:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:13:37 INFO - 'LOGNAME': 'cltbld', 04:13:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:37 INFO - 'MOZ_NO_REMOTE': '1', 04:13:37 INFO - 'NO_EM_RESTART': '1', 04:13:37 INFO - 'PAGER': '/bin/cat', 04:13:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:13:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:37 INFO - 'PWD': '/builds/slave/test', 04:13:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:13:37 INFO - 'SHELL': '/bin/bash', 04:13:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.evYvzT4g1f/Listeners', 04:13:37 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 04:13:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:13:37 INFO - 'USER': 'cltbld', 04:13:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:13:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:13:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:37 INFO - 'XPC_FLAGS': '0x0', 04:13:37 INFO - 'XPC_SERVICE_NAME': '0', 04:13:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:13:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:37 INFO - Downloading/unpacking blobuploader==1.2.4 04:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:40 INFO - Downloading blobuploader-1.2.4.tar.gz 04:13:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:13:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:13:40 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 04:13:40 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 04:13:40 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 04:13:40 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 04:13:40 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 04:13:40 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 04:13:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:13:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:13:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:41 INFO - Downloading docopt-0.6.1.tar.gz 04:13:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:13:41 INFO - Installing collected packages: blobuploader, requests, docopt 04:13:41 INFO - Running setup.py install for blobuploader 04:13:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:13:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:13:41 INFO - Running setup.py install for requests 04:13:42 INFO - Running setup.py install for docopt 04:13:42 INFO - Successfully installed blobuploader requests docopt 04:13:42 INFO - Cleaning up... 04:13:42 INFO - Return code: 0 04:13:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:42 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 0x10a06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd540d67ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcfd30>, '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 0x109d34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc7b20>, '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.7LoVr62NCY/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.evYvzT4g1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 04:13:42 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 04:13:42 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 04:13:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LoVr62NCY/Render', 04:13:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:13:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:13:42 INFO - 'HOME': '/Users/cltbld', 04:13:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:13:42 INFO - 'LOGNAME': 'cltbld', 04:13:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:42 INFO - 'MOZ_NO_REMOTE': '1', 04:13:42 INFO - 'NO_EM_RESTART': '1', 04:13:42 INFO - 'PAGER': '/bin/cat', 04:13:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:13:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:42 INFO - 'PWD': '/builds/slave/test', 04:13:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:13:42 INFO - 'SHELL': '/bin/bash', 04:13:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.evYvzT4g1f/Listeners', 04:13:42 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 04:13:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:13:42 INFO - 'USER': 'cltbld', 04:13:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:13:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:13:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:42 INFO - 'XPC_FLAGS': '0x0', 04:13:42 INFO - 'XPC_SERVICE_NAME': '0', 04:13:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:13:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:42 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-h21LN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:42 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-t_Ps8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:42 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-cOyodo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-y0fiJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-sO8Cx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-eTmhV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-5ADQLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-f2ehhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-1zqILR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-MZl_ud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-DdaQot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-mJPmdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:43 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-w470se-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:44 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-tiJd5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:44 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Y0x2jV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:44 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-etiSA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:44 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-zKpuhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:13:44 INFO - Running setup.py install for manifestparser 04:13:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:13:44 INFO - Running setup.py install for mozcrash 04:13:44 INFO - Running setup.py install for mozdebug 04:13:44 INFO - Running setup.py install for mozdevice 04:13:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:13:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:13:45 INFO - Running setup.py install for mozfile 04:13:45 INFO - Running setup.py install for mozhttpd 04:13:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:13:45 INFO - Running setup.py install for mozinfo 04:13:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:13:45 INFO - Running setup.py install for mozInstall 04:13:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:13:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:13:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:13:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:13:45 INFO - Running setup.py install for mozleak 04:13:45 INFO - Running setup.py install for mozlog 04:13:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:13:45 INFO - Running setup.py install for moznetwork 04:13:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:13:46 INFO - Running setup.py install for mozprocess 04:13:46 INFO - Running setup.py install for mozprofile 04:13:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:13:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:13:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:13:46 INFO - Running setup.py install for mozrunner 04:13:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:13:46 INFO - Running setup.py install for mozscreenshot 04:13:46 INFO - Running setup.py install for moztest 04:13:46 INFO - Running setup.py install for mozversion 04:13:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:13:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:13:47 INFO - Cleaning up... 04:13:47 INFO - Return code: 0 04:13:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:13:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:47 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 0x10a06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd540d67ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcfd30>, '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 0x109d34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc7b20>, '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.7LoVr62NCY/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.evYvzT4g1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 04:13:47 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 04:13:47 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 04:13:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LoVr62NCY/Render', 04:13:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:13:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:13:47 INFO - 'HOME': '/Users/cltbld', 04:13:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:13:47 INFO - 'LOGNAME': 'cltbld', 04:13:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:47 INFO - 'MOZ_NO_REMOTE': '1', 04:13:47 INFO - 'NO_EM_RESTART': '1', 04:13:47 INFO - 'PAGER': '/bin/cat', 04:13:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:13:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:47 INFO - 'PWD': '/builds/slave/test', 04:13:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:13:47 INFO - 'SHELL': '/bin/bash', 04:13:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.evYvzT4g1f/Listeners', 04:13:47 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 04:13:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:13:47 INFO - 'USER': 'cltbld', 04:13:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:13:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:13:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:47 INFO - 'XPC_FLAGS': '0x0', 04:13:47 INFO - 'XPC_SERVICE_NAME': '0', 04:13:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:13:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-K6Wag0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:47 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)) 04:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-RBejNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:47 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)) 04:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-PUWgdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:47 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)) 04:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-zyFTJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:47 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)) 04:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-m1uGck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:47 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)) 04:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-HMA3jP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:47 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)) 04:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:47 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-nkNGsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:48 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)) 04:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:48 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-NH1Y4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:48 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)) 04:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:48 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-JvPVKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:48 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)) 04:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:48 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-idMCXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:48 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)) 04:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:48 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Lo8MVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:48 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)) 04:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:48 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-HLp6Kw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:48 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)) 04:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:48 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-2CKhKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:48 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)) 04:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:48 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-nhSVWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:48 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)) 04:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:48 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-8ZKnE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:48 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)) 04:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:48 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-oiNwyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:49 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)) 04:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:49 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-ZF4pe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:49 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)) 04:13:49 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)) 04:13:49 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)) 04:13:49 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)) 04:13:49 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)) 04:13:49 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)) 04:13:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:13:49 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 04:13:49 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 04:13:49 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 04:13:49 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 04:13:49 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 04:13:49 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 04:13:51 INFO - Downloading blessings-1.6.tar.gz 04:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:13:51 INFO - Installing collected packages: blessings 04:13:51 INFO - Running setup.py install for blessings 04:13:51 INFO - Successfully installed blessings 04:13:51 INFO - Cleaning up... 04:13:51 INFO - Return code: 0 04:13:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:13:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:52 INFO - Reading from file tmpfile_stdout 04:13:52 INFO - Current package versions: 04:13:52 INFO - blessings == 1.6 04:13:52 INFO - blobuploader == 1.2.4 04:13:52 INFO - docopt == 0.6.1 04:13:52 INFO - functools32 == 3.2.3-2 04:13:52 INFO - jsonschema == 2.5.1 04:13:52 INFO - manifestparser == 1.1 04:13:52 INFO - mozInstall == 1.12 04:13:52 INFO - mozcrash == 0.17 04:13:52 INFO - mozdebug == 0.1 04:13:52 INFO - mozdevice == 0.48 04:13:52 INFO - mozfile == 1.2 04:13:52 INFO - mozhttpd == 0.7 04:13:52 INFO - mozinfo == 0.9 04:13:52 INFO - mozleak == 0.1 04:13:52 INFO - mozlog == 3.3 04:13:52 INFO - moznetwork == 0.27 04:13:52 INFO - mozprocess == 0.23 04:13:52 INFO - mozprofile == 0.28 04:13:52 INFO - mozrunner == 6.12 04:13:52 INFO - mozscreenshot == 0.1 04:13:52 INFO - mozsystemmonitor == 0.3 04:13:52 INFO - moztest == 0.7 04:13:52 INFO - mozversion == 1.4 04:13:52 INFO - psutil == 3.1.1 04:13:52 INFO - requests == 1.2.3 04:13:52 INFO - wsgiref == 0.1.2 04:13:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:52 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')]} 04:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:52 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')]} 04:13:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:52 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 0x10a06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd540d67ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcfd30>, '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 0x109d34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc7b20>, '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.7LoVr62NCY/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.evYvzT4g1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 04:13:52 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 04:13:52 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 04:13:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LoVr62NCY/Render', 04:13:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:13:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:13:52 INFO - 'HOME': '/Users/cltbld', 04:13:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:13:52 INFO - 'LOGNAME': 'cltbld', 04:13:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:52 INFO - 'MOZ_NO_REMOTE': '1', 04:13:52 INFO - 'NO_EM_RESTART': '1', 04:13:52 INFO - 'PAGER': '/bin/cat', 04:13:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:13:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:52 INFO - 'PWD': '/builds/slave/test', 04:13:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:13:52 INFO - 'SHELL': '/bin/bash', 04:13:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.evYvzT4g1f/Listeners', 04:13:52 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 04:13:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:13:52 INFO - 'USER': 'cltbld', 04:13:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:13:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:13:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:52 INFO - 'XPC_FLAGS': '0x0', 04:13:52 INFO - 'XPC_SERVICE_NAME': '0', 04:13:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:52 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)) 04:13:52 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)) 04:13:52 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)) 04:13:52 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)) 04:13:52 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)) 04:13:52 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)) 04:13:52 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)) 04:13:52 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)) 04:13:52 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)) 04:13:52 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)) 04:13:52 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)) 04:13:52 INFO - Cleaning up... 04:13:52 INFO - Return code: 0 04:13:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:13:52 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')]} 04:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:52 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')]} 04:13:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:52 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 0x10a06ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a100618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd540d67ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcfd30>, '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 0x109d34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc7b20>, '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.7LoVr62NCY/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.evYvzT4g1f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 04:13:52 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 04:13:52 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 04:13:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LoVr62NCY/Render', 04:13:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:13:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:13:52 INFO - 'HOME': '/Users/cltbld', 04:13:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:13:52 INFO - 'LOGNAME': 'cltbld', 04:13:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:52 INFO - 'MOZ_NO_REMOTE': '1', 04:13:52 INFO - 'NO_EM_RESTART': '1', 04:13:52 INFO - 'PAGER': '/bin/cat', 04:13:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:13:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:52 INFO - 'PWD': '/builds/slave/test', 04:13:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:13:52 INFO - 'SHELL': '/bin/bash', 04:13:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.evYvzT4g1f/Listeners', 04:13:52 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 04:13:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:13:52 INFO - 'USER': 'cltbld', 04:13:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:13:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:13:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:52 INFO - 'XPC_FLAGS': '0x0', 04:13:52 INFO - 'XPC_SERVICE_NAME': '0', 04:13:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:13:52 INFO - Cleaning up... 04:13:52 INFO - Return code: 0 04:13:52 INFO - Running post-action listener: _resource_record_post_action 04:13:52 INFO - Running post-action listener: _start_resource_monitoring 04:13:52 INFO - Starting resource monitoring. 04:13:52 INFO - [mozharness: 2016-09-08 11:13:52.768470Z] Finished create-virtualenv step (success) 04:13:52 INFO - [mozharness: 2016-09-08 11:13:52.769491Z] Running install step. 04:13:52 INFO - Running pre-action listener: _resource_record_pre_action 04:13:52 INFO - Running main action method: install 04:13:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:53 INFO - Reading from file tmpfile_stdout 04:13:53 INFO - Detecting whether we're running mozinstall >=1.0... 04:13:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:13:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:13:53 INFO - Reading from file tmpfile_stdout 04:13:53 INFO - Output received: 04:13:53 INFO - Usage: mozinstall [options] installer 04:13:53 INFO - Options: 04:13:53 INFO - -h, --help show this help message and exit 04:13:53 INFO - -d DEST, --destination=DEST 04:13:53 INFO - Directory to install application into. [default: 04:13:53 INFO - "/builds/slave/test"] 04:13:53 INFO - --app=APP Application being installed. [default: firefox] 04:13:53 INFO - mkdir: /builds/slave/test/build/application 04:13:53 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'] 04:13:53 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 04:14:26 INFO - Reading from file tmpfile_stdout 04:14:26 INFO - Output received: 04:14:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:14:26 INFO - Running post-action listener: _resource_record_post_action 04:14:26 INFO - [mozharness: 2016-09-08 11:14:26.926605Z] Finished install step (success) 04:14:26 INFO - [mozharness: 2016-09-08 11:14:26.926748Z] Running run-tests step. 04:14:26 INFO - Running pre-action listener: _resource_record_pre_action 04:14:26 INFO - Running main action method: run_tests 04:14:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:14:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:14:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:14:26 INFO - 2016-09-08 04:14:26.941 screenresolution[1958:17785] starting screenresolution argv=screenresolution get 04:14:26 INFO - 2016-09-08 04:14:26.944 screenresolution[1958:17785] Display 0: 1600x1200x32@60 04:14:26 INFO - 2016-09-08 04:14:26.949 screenresolution[1959:17787] starting screenresolution argv=screenresolution list 04:14:26 INFO - Available Modes on Display 0 04:14:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:14:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:14:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:14:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:14:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:14:27 INFO - Intel Iris: 04:14:27 INFO - Chipset Model: Intel Iris 04:14:27 INFO - Type: GPU 04:14:27 INFO - Bus: Built-In 04:14:27 INFO - VRAM (Dynamic, Max): 1536 MB 04:14:27 INFO - Vendor: Intel (0x8086) 04:14:27 INFO - Device ID: 0x0a2e 04:14:27 INFO - Revision ID: 0x0009 04:14:27 INFO - Displays: 04:14:27 INFO - Display: 04:14:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:14:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:14:27 INFO - Display Serial Number: 1600x1200 60 04:14:27 INFO - Main Display: Yes 04:14:27 INFO - Mirror: Off 04:14:27 INFO - Online: Yes 04:14:27 INFO - Rotation: Supported 04:14:27 INFO - Return code: 0 04:14:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:14:27 INFO - Python 2.7.3 04:14:27 INFO - Return code: 0 04:14:27 INFO - grabbing minidump binary from tooltool 04:14: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')]} 04:14:27 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 0x109fcfd30>, '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 0x109d34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109fc7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:14:27 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 04:14:27 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 04:14:27 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 04:14:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:14:27 INFO - Return code: 0 04:14:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:14:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:14:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:14:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:14:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:14:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0176 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:14:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7LoVr62NCY/Render', 04:14:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:14:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:14:27 INFO - 'HOME': '/Users/cltbld', 04:14:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:14:27 INFO - 'LOGNAME': 'cltbld', 04:14:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:14:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:14:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:14:27 INFO - 'MOZ_NO_REMOTE': '1', 04:14:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:14:27 INFO - 'NO_EM_RESTART': '1', 04:14:27 INFO - 'PAGER': '/bin/cat', 04:14:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:14:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:14:27 INFO - 'PWD': '/builds/slave/test', 04:14:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:14:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:14:27 INFO - 'SHELL': '/bin/bash', 04:14:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.evYvzT4g1f/Listeners', 04:14:27 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 04:14:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:14:27 INFO - 'USER': 'cltbld', 04:14:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:14:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:14:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:14:27 INFO - 'XPC_FLAGS': '0x0', 04:14:27 INFO - 'XPC_SERVICE_NAME': '0', 04:14:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:14:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:14:27 INFO - mozversion application_buildid: 20160908030116 04:14:27 INFO - mozversion application_changeset: 331524df5cab6383f44d55d777b7cededdbc12c9 04:14:27 INFO - mozversion application_display_name: Nightly 04:14:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:14:27 INFO - mozversion application_name: Firefox 04:14:27 INFO - mozversion application_remotingname: firefox 04:14:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:14:27 INFO - mozversion application_vendor: Mozilla 04:14:27 INFO - mozversion application_version: 51.0a1 04:14:27 INFO - mozversion platform_buildid: 20160908030116 04:14:27 INFO - mozversion platform_changeset: 331524df5cab6383f44d55d777b7cededdbc12c9 04:14:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:14:27 INFO - mozversion platform_version: 51.0a1 04:14:27 INFO - using testdate: 1473333267 04:14:27 INFO - actual date: 1473333267 04:14:27 INFO - starting webserver on 'localhost:52483' 04:14:27 INFO - SUITE-START | Running 2 tests 04:14:27 INFO - TEST-START | dromaeo_css 04:14:27 INFO - operating with platform_type : mac_ 04:14:27 INFO - Initialising browser for dromaeo_css test... 04:14:27 INFO - TEST-INFO | started process 1969 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmp8vMuP3/profile http://localhost:52483/getInfo.html) 04:14:29 INFO - PROCESS | 1969 | __metrics Screen width/height:1600/1200 04:14:29 INFO - PROCESS | 1969 | colorDepth:24 04:14:29 INFO - PROCESS | 1969 | Browser inner width/height: 1024/689 04:14:29 INFO - PROCESS | 1969 | __metrics 04:14:29 INFO - PROCESS | 1969 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:14:29 INFO - PROCESS | 1969 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:14:30 INFO - TEST-INFO | 1969: exit 0 04:14:30 INFO - Browser initialized. 04:14:30 INFO - Running cycle 1/1 for dromaeo_css test... 04:14:30 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmp8vMuP3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:42 INFO - PROCESS | 1971 | RSS: Main: 232439808 04:14:42 INFO - PROCESS | 1971 | 04:15:33 INFO - PROCESS | 1971 | 1473333333806 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] 04:17:45 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52483/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52483/tests/dromaeo/cssquery-ext.html) 04:17:47 INFO - PROCESS | 1971 | RSS: Main: 365568000 04:17:47 INFO - PROCESS | 1971 | 04:20:49 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52483/tests/dromaeo/cssquery-ext.html (next: http://localhost:52483/tests/dromaeo/cssquery-jquery.html) 04:20:50 INFO - PROCESS | 1971 | RSS: Main: 369270784 04:20:50 INFO - PROCESS | 1971 | 04:23:52 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52483/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52483/tests/dromaeo/cssquery-mootools.html) 04:23:53 INFO - PROCESS | 1971 | RSS: Main: 360476672 04:23:53 INFO - PROCESS | 1971 | 04:26:55 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52483/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52483/tests/dromaeo/cssquery-prototype.html) 04:26:56 INFO - PROCESS | 1971 | RSS: Main: 331030528 04:26:56 INFO - PROCESS | 1971 | 04:29:59 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52483/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52483/tests/dromaeo/cssquery-yui.html) 04:30:00 INFO - PROCESS | 1971 | RSS: Main: 324825088 04:30:00 INFO - PROCESS | 1971 | 04:33:03 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:52483/tests/dromaeo/cssquery-yui.html (next: http://localhost:52483/tests/dromaeo/cssquery-dojo.html) 04:33:03 INFO - PROCESS | 1971 | RSS: Main: 412991488 04:33:03 INFO - PROCESS | 1971 | 04:33:03 INFO - PROCESS | 1971 | __start_tp_report 04:33:03 INFO - PROCESS | 1971 | _x_x_mozilla_page_load 04:33:03 INFO - PROCESS | 1971 | _x_x_mozilla_page_load_details 04:33:03 INFO - PROCESS | 1971 | |i|pagename|runs| 04:33:03 INFO - PROCESS | 1971 | |0;dojo.html;3297.3734990351822;3305.124142102343;3336.8164750938704;3338.265581572053;3391.576052993376;5650.51970582501;5707.686077265748;5707.914381284284;5797.855053623656;5805.651660900338;5699.629524080932;5776;5777.768889244436;5780.53755699544;5780.7282397872505;5190;5214.843554693346;5249.947500524984;5284.5772338212855;5296.894062118756;5315.654482458628;5317.654352467097;5321.494458026481;5328.973355133219;5331.067063263933;5222.529972302492;5227.79088836446;5264.736763161845;5271.604629652772;5322.733863306819;5329.067413202693;5341.706206158659;5343.839684809462;5351.625386222966;5356.812511562116;5109.693418394871;5127.9230811537855;5155.61332900034;5257.106291930343;5265;5663.405342439026;5685.033544297479;5726.226959360475;5795.275590551181;5827.970860145714;6276.309605943343;6283.371662833726;6286.591371560834;6286.87426251477;6297.464715499188;6125.723854043087;6241.87516249677;6256.280527739323;6289.496840252768;6308.148399965991;5931.228940237742;5932.911006334908;5940.227770389822;5947.821565353046;5970.731317090698;6301.684915754194;6303.936960630427;6315.684215789192;6331.461825744771;6341.873162536724;5956.880862382728;6100.969495152496;6132.110769107974;6244.687765611701;6256.280527739277;6250.062490626442;6271.184745982993;6286.9056964145575;6291.874162516816;6292.2134733158355;6250.249970003538;6252.718627661766;6268.059791031382;6272.780452684134;6277.96861015692;6114.692773002168;6131.590761709946;6162.291336496271;6301.905471417841;6315.273743519554;4948.628852836052;4994.775235114356;5015.322931404214;5019.924701129486;5035.647504674711;4489.438820147481;4493.707908985905;4515.909681806411;4552.271636538204;4584.266517357207;5777;5839.795607153729;5839.9124013139835;5856.59003869725;5916.674582897896;6162.383761623802;6196.907046394308;6233.906491402542;6234.4438780099945;6289.339619339903;5672.943270567324;5811.389804070596;5870.706464676749;5896.882062358729;5917.763289468459;4361.912761744811;5497.202905578669;5501.587380946445;5510.421405752419;5517.36550296713;6201.348858369806;6233.376662333731;6315.5894866833505;6352.936470635234;6353.682315884187;6211.0372892201785;6217.285012223652;6238.407351301619;6262.655553944394;6276.278228003838;6337.366263373626;6352.142460767922;6356.650384228912;6372.171617689856;6374.553781235269;6107.522607572365;6131.162586540493;6245.375462453719;6250.218722659668;6287.937120628735;6210.689465526796;6240.344763799917;6250.749970001331;6257.655828929271;6268.153799237227;6177.382261773787;6212.720427580679;6213.099100630293;6218.595791273553;6247.406496383018;6043.728032238472;6136.877262454636;6166.907496387468;6216.72024758896;6242.500599951902;5034.974825125851;6023.367546407564;6107.908381374372;6126.356732543019;6128.747575574743;5456.236126942311;6160.691965401802;6178.042403427476;6192.225971753531;6192.566520343713;5901.350851406428;5960.284765828128;6068.150458935841;6096.817095487142;6105.9694701526205 04:33:03 INFO - PROCESS | 1971 | |1;ext.html;442.5662850406601;452.1386758225581;507.5229284472596;533.1336578060651;536.0244355273404;25305.873470632643;39681.20637587246;41075;41146;41149.794251028776;39900.600993990054;41991.79004104975;42402.15195696092;42448.57551424485;42490.150196996045;8457.53483558404;9516.714498565054;9706.902930970706;9712.368696034771;9733.51332433379;8389.496630202177;8686.391952563323;8690.261327787146;8701.347398945105;11577.363245021521;11616.593419230328;11888.989435897958;11894.940525297361;11930.284182949008;11972.70068248292;11705.122115841303;11792.882071179265;11793.764124717458;11802.40987950599;11819.881801182008;11485.827712584318;11594.826077608843;11598.88401115995;11617.244879082833;11625.941870290595;39436.802815986026;39725.40411893824;39773.00567485807;39778.60221397778;39805.402918956235;3331.3004269103517;3333.583302087239;3348.0792781984906;3564.8930532084078;3565.0552603560077;30304.84847575748;30316.393672126433;30539;31091.37817243665;31133;3073.416050950312;3163.984180079085;3190.3778763141195;3212.5823642926503;3335.0828522156357;526263;531746;532017;532746.3362683201;533005;58782;59517;59528.404715953155;59541;59556.70221648864;30512.84743576268;31120.665950053837;63391.683041584496;63501.68249158725;63647.36352636414;15899.364025439085;16478.2584783685;16496.17519124039;16523.752143717855;16547.172641367884;12526.81209781854;12610.873891261153;12619.369031548385;12627.179233349987;12657.873421265853;13867.583972480841;16413.58966025835;16418.097004664858;16758.413455528997;18701.625967480573;6537.477001839841;7815.25755053269;7822.726204582926;7881.123389962622;7978.242067003625;862.5385418801037;943.5706753427174;953.4565297780338;985.3053597213947;1047.9991608014327;28356.858215708788;28751.56872646912;29092;29178.708212917598;29892.850535747617;841.3941961787503;922.2667978956746;924.3991405586288;1122.2873475343215;1127.2672762704144;15314.846851531565;15334.463293784664;15397.846021539865;15637.921810390875;15725.606859828596;581605;685828;698399;698876;700887;47543.76228118906;54500.45499545033;54880.176797348075;55585.722071389384;56501;1330.3015916643647;1337.806018127366;1373.1074801379184;1375.3742047368623;1377.0360747476607;28650.283742906595;29756.484650101844;30620.234494136934;30977.690223098383;31317;47724;47901.28148077712;48249.7937551564;48278.55164345075;48282;671935;676179;686473;897907.5313870166;901059;39621.80189099036;39980.600193997685;40146.99632509211;40442.59557404388;40537.59462405456;35946.8202658985;44690.55309446994;44861;45157.77421112874;45177.54822451733;37056.88829335124;37079;37595.87212383633;38230.47078116844;38464;31077.589217123594;36788.81605592062;37877.81061094677;38048.239035219696;38335.80832095822;37126.81436592908;37384;37794.62205377911;38374.42438363371;38433.4234986481;29088.654804696886;29323.266918327212;33539.83230083931;34008.65991340154;34153;19964;25109.87445062763;25112.62331064999;25199.74800251974;25575.872120639277 04:33:03 INFO - PROCESS | 1971 | |2;jquery.html;345.0528299817;358.2887967384741;363.9217568222832;375.7482486733889;396.3361369705744;14946.850531494681;16388.34446622135;19893.90053049733;19974.40076797695;20110.296139635117;17583.472495825114;19553.804461955377;19705.70441443376;19788.010599470006;20048.799512004913;5042.873928151789;5663.461971112738;5901.675407852566;5902.409759024106;5915.319738230094;4107.199096176247;4316.244657184996;5808.477237048664;6003.339632640406;6003.6697981611;4227.027783609771;4242.384854196155;4256.531781504032;4264;4363.170997510479;4712.91602931326;6076.4227398397525;6453.741850325981;6589.670516474157;6596.868062638769;5010.574101201402;5290.973545132289;5699.059655156894;5708.286464191976;5716.514096301819;12504.74990500194;12565.748685026248;12733.172343797623;12769.680757981032;12788.360581970865;2131.11558703138;2138.9465263368384;2174.3694328644674;2332.9618319847673;2639.3665520274994;9239.214666753334;9256.490893000882;10060.496975151213;10173.677421935101;15330.15684137372;1596.7944202127435;1822.1891258390021;1826.3151318255655;1907.647085289212;1916.2239293086323;100831;111368.32947505794;115259;118822.62354752942;123879.3806030964;10629.309094908805;11151.609693660683;11186.664400068012;11190.440477976068;11416.31502109876;14004.649883752783;14033.087849289763;14044.157350558999;14055.508057218152;14063.859361406256;6635.369639884203;8439.156084391634;8485.957570212233;8534.615942282611;8546.017208021158;8342;8347;8348.749537513762;8506.787330316667;8541.444806087584;4314.257498827958;4403.229434848889;4412.713173643703;4489.483709373399;4514.255147900625;1848.4085092770185;1853.7590113285187;1917.4918646558674;1935.2355819451238;1943.3586916317581;830.6096134816627;839.4585492357518;889.1997202517786;912.202960168467;947.5262368815593;17767.78966628218;20373.898130509253;20498.692519612217;20711.378658640264;20723.689144662843;915.7435917942987;926.5645146781002;929.6235024814961;946.2855544064188;983.7835676151235;10470.528826202839;10838.566457341776;10882.891171088346;10899.727506812234;10944;496693;575687;585409;605966;617808;109399.45300273607;129717;139197.21605567832;145457;148900;952.2096659772511;972.5040229483014;1020.1229737283286;1020.9897901021043;1023.4013102335068;18807.811921880606;19018.619627607648;19227.61544769069;19237.61524769525;19699.70450443273;54755.90488190134;55405.722971386494;55869.44130558642;56528;57123.42876571181;78015.6099219523;82638;83141.51726344659;87351.12648873685;88635.67046494178;7809.648565814438;7851.018622672166;7984.121746607984;8035.437519373588;8135.511869287861;10729.678109656723;11178.832317515082;12559.686007849878;12912.741745164856;13010.414531345923;10702.83945740799;10861.67414977552;11296.43517824122;11314.03830674402;11417.885821141683;10627.256092073481;10695.197860160675;10865.891341086804;10958.28771129874;11315.830262546233;11138.498767555642;11182.66452006441;11403.771924561297;11502.217156340781;11708.297502149899;7777.607187253899;7961.402894782798;8065;8149.470284431493;8255.752327430188;10694.679159625224;10878.238523303293;10903.563857445613;11241.100711942861;11305.208635395444 04:33:03 INFO - PROCESS | 1971 | |3;mootools.html;1355.4849157320216;1421.950231741889;1528.9388424463023;1707.6072503324235;1882.858785591081;7879.960600196998;8441.198086181817;8932.196102350787;8947.284217262619;9074.274058075338;8829.161229683186;8941.821163576724;8944.239739622124;8945.642174313036;8968.372213945026;2385.844920080194;2419.213755529451;2419.7701218384227;2421.4309637235324;2423.903043878243;2358.5636657218383;2391.56951748686;2408.759124087595;2411.963820542693;2416.9727865657114;2371.620540713486;2391.892364843586;2394.6527753475734;2400.147947478646;2403.038784486202;2349.7767712067325;2377.785999260066;2386.6658667786523;2387.4628208653003;2394.31761947844;2337.4506990857135;2365.231299827563;2370.7392186859433;2374.3233178544033;2376.3227480168243;8574.485530868167;8788.42875213109;8864.911350886474;8905.287576993825;8974.86537701935;5219.060569097561;5390.5418039466695;5440.891182176334;5445.754941027623;5454.209139674725;3495.5106285120105;3515.103648569624;3586.049696830342;3596.478510615946;3608.8195590220384;1054.6414219165445;1072.0780129088978;1072.367303291062;1081.9080378167864;1094.6497120921308;11604.825927611093;11704.47329870158;11810.350430726307;11821.704457388634;11844.289342639384;4539.6368290536675;4593.333966574828;4802.423709154854;4805.111054454937;4806.687565308243;10107.39355638664;10124.341917775468;10136.898631013595;10138.695839124837;10150.390976541432;5384.353877534721;5388.218708287276;5391.865203369947;5394.163904594794;5405.21624364465;3863.74885632433;4358.738475691469;4554.817807287672;4762.76186190696;4931.50684931504;3894.6105389460827;3901.5318161820765;4024.1951609677594;4036.798160091984;4069;1264.3741348032784;1296.358302640199;1296.3971753134838;1306.5627809409573;1311.114997376767;789.3458147813869;874.0429229993148;894.3471265975874;935.0555938502162;958.501579178837;5733.6997252060955;5886.1465087562065;5973.880522389528;6128.571000029955;6151.569390142647;442.77372479681515;447.4094195661695;453.4852941910955;454.6680922926216;459.2055743563587;3913.941290880639;4004.1791432756213;4108.096218831853;4142.627163555234;4153.68847336451;10367.63713270032;10399.428031458192;10522.368657880552;10840.241183117107;10869;10651.09465695409;10655.467226638637;10659.987301206373;10677.932206779415;10700.250982431311;8098.521183053534;8162.632681529345;8191.303739182236;8220.095789463154;8280.130586288353;5781.311297939531;5870.354261031198;5877.911831322448;6025.276966764016;6025.638461692312;9938.552765125442;9979.50102494887;9982.251331150048;10053.04496072872;10085.394876307151;8406.411551191359;9835;10240.743981400525;10308.690739277834;10315.587376504856;7523.510971786816;7585.393168546393;7651.846963060819;7669.1947345527915;7693.8461230773955;8257.091719910684;8318.168183181875;8318.542480163407;8320;8357;7332.376747976482;7468.029156209658;7540.622968851645;7577.621118944141;7599.772006839804;7286.234945330876;7358.668859901424;7359.4480413968095;7394.186639469546;7401.3708834747495;6921.204061532988;7064.682089306097;7075.080239568823;7083.3979111774015;7085.858282834418;4216.903605062767;4306.784660767012;4925.605951523941;4981.526754958273;5186.844394668166;4796.784144713426;5031.647784655039;5165.845024649267;5246.449122842047;5293.047251494768 04:33:03 INFO - PROCESS | 1971 | |4;prototype.html;285.1147187981318;297.9433907557564;302.7532560962815;305.7233203950425;313.04365164422705;4533.6826422150425;4750.714957102577;5212.983468223698;5407.026735187665;5476.479734425228;4391.385206071144;4406.69153159279;4503.61719253863;4667.649926255527;4869.7808598613055;3359.9160020999425;3495.667911548399;3879.8060096995177;4054.2904991626497;4108.52751933527;3391.3217356528794;3866.574676785551;4043.2722110020186;4075.7962101894936;4133.586641335857;3547.2728090741334;3709.5548534175937;3996.900077498057;4042.7170098093147;4074.6129117733917;3019.6225471816024;3855.132595166079;3983.3353314950314;3984.5816189300285;4072.6741860651223;3102.5501302311263;3548.254866478043;3550.804705741183;3556.5198698175673;3565.6790888820133;5242.475752424765;5259.342582177228;5281.498257665516;5285.471452854722;5321.893562128775;3772.792916144059;3810.904727381838;4637.814487420499;4706.29405589161;4714.538493067126;5592.217089607459;5597.608167428281;5610.4670056344585;5611.7474713637985;5615.511889349325;3510.8420121094614;3931.1941052084253;3932.0956180078456;3942.960570394288;3960.485136932209;5472;5622.734884650962;5838.970805145989;5923.851903702356;5958.374370691102;3938.133610605662;3956.723029387915;3957.683385329167;3978.2839088964265;3991.600839915985;4023.7183397162503;4026.275270451288;4028.415879697486;4031.9798401007806;4047.0287131088326;3698.5561732591723;4087.8758341456337;4363.650907927358;4475.507694153641;4654.953450465519;5533.889322213533;5598.636088654224;5649.440852263048;5654.688992105392;5730.08318669011;1351.8986076044337;1367.7264547090422;1378.2764048874421;1381.696026874086;1387.0650442632673;729.6461216310086;745.9072458847814;775.3874439193087;782.1396463889789;839.6607411179159;4177.937330940038;5142.1772516397195;5193.2989046479;5357.544608708304;5369.624126311199;5028.64799464034;5840.328362238397;5855.560832937546;5872.647641141545;5912.17229587858;4291.9785401072795;4476.798544065525;4614.861554153381;4680.67235293533;4819.349387832668;3008.4283986042583;3029.3032602501107;3056.43455960648;3066.632004159515;3090.799098058619;5088.669236499616;5291.354322838581;5712.514436272963;5933.139694744238;5945.16767652529;5721.313442386941;5800.997699913387;5944.851378715567;5967.46292833647;6027.969860150759;5285.101532739367;5611.494965453047;5763.884722305615;5968.37332080134;6062.42406971337;4301.268784306612;5347.5721942243745;5814.738336774771;5819.941800582109;5863.912041319469;4728;5219.817306394258;5613.635113717595;5717.399673034438;5738.885222295447;5470.018839732275;5484.149956756709;5494.8735509221315;5976.820695379146;6084;3824.6175382462643;3948.8548320986592;3965.6034396561267;3974.5627980922654;3982.4623675803396;4605.608523275425;5132.538071573576;5740.378994908971;5761.56788240892;5792.8551786204;4822.662413631152;4963.528464795839;5659.6321239119325;5663.884863325073;5679.488846003879;5186.766595503287;5230.947690523046;5618.550515958632;5626.35296940857;5643.463870932255;4930.457649658606;5239.214117882196;5483.326171465048;5490.062816393211;5521.779128834802;4479.6257983268915;4908.92636610444;4947.554720075211;5528.115501519737;5590.316648008898;4849.3210950466255;5104.642675012714;5346.994876921154;5388.703621300866;5571.693556854412 04:33:03 INFO - PROCESS | 1971 | |5;yui.html;699.1819571101811;701.3932947999979;725.6843273176172;764.9923500764994;769.9384049276058;15771.68456630868;16603.920745151558;16633.9187952783;16658.167091645413;16683.24925378358;15007.849921500781;15185.696286074271;15188.544343669708;15188.924055379708;15208.467703630375;580.3529065092413;672.3411057163983;692.4114502672738;847.6143354773594;876.2332958661171;549.2914140758417;641.8895311111779;648.8053583924827;791.5053091817614;804.806846356873;879.2745984562729;883.6200433813458;886.6364790435922;887.7824932891418;891.0198781340538;886.0961818944705;887.573964497045;887.8978917424514;888.3470649072893;889.2574700125404;662.7912207654573;663.8506336074392;664.445188267797;664.6344510519243;669.7823207457595;11960.282383057045;14708.338124784303;14932;14970.326335314829;15563.37746490139;14320.355583998746;14950.70098598022;14950.925245373703;14967.101973881494;14990.700185996218;11927.224730392496;12009.159358844885;12024.158308918379;12107.334096624681;12123.939380303042;3300.405926933151;3818.6372294631965;3825.196708691178;3880.3791393377205;3965.5637879833193;113924;114826;120781;127595;127618.99999999907;11299;11380.943095284636;11499.425028748528;11563.30620162793;11681.88318116825;23563.52872942566;23801.880990595284;23820.16629417962;23834.04663813428;23890.924908379166;8137.145599711945;8214.712485062993;8244.051934027544;8299.419040667217;8351.331893448509;3929.626685464876;4493.910121797546;4503.527129651339;4516.774161291923;4687.7656117194;3515.9824200878834;3634.890953271406;3637.781733096023;3638.872639457606;3645.9817700911326;553.9446055394428;660.2736989311815;669.718718138383;671.1979184874126;671.7078071039016;511.0315951272318;552.3399537552666;578.2309528327314;613.8741557980603;677.9084823548857;9179;9383.5308234588;9435.198008169382;9569.138777510057;9676.661316853877;636.3858876184504;647.8186107889799;669.3641041010961;671.7493720926726;673.7978606418055;3556.590992035949;4694.178518759203;4694.906101878011;4710;4753.263244197155;98722.01277987128;149630.25184874007;149839;153550;174936.26083565824;88751;125650;146918;147213.05573888464;148024;113499.30013997233;117370;121366;125584;127848.18353155276;8648.697295594875;8951.73144805657;9012.684556040507;9106.453612783254;9288.907110928803;67119;67598.66200669162;68415.3158468409;69087.65456172687;69681.65159174171;98257.50871245598;99885.50057249959;102830;103175;105155;11067.50196241155;11365;11999.227198644943;12074.45664945097;12103.213291136048;12857.614271571867;13021.674458138623;13052;13404.865951340362;13510.93244533771;12280.631581052583;12497.687557811127;12616.306103164414;12721.8091728626;12780.360981951051;11633.883661163618;11865.231949288092;11938.22401543897;12105.636830895088;12542.623721288375;11790.528378864748;12012.279263244232;12081.335526546125;12516;12563.937180314406;10257.487125643836;10410;10472.319299245524;10642.467876606293;10808.189385796253;10338.741531461774;10608.363498189825;10633.308834925705;10672.039516443558;10678.8932110681 04:33:03 INFO - PROCESS | 1971 | __end_tp_report 04:33:03 INFO - PROCESS | 1971 | __start_cc_report 04:33:03 INFO - PROCESS | 1971 | _x_x_mozilla_cycle_collect,1349 04:33:03 INFO - PROCESS | 1971 | __end_cc_report 04:33:03 INFO - PROCESS | 1971 | __startTimestamp1473334383828__endTimestamp 04:33:03 INFO - PROCESS | 1971 | 04:33:03 INFO - PROCESS | 1971 | ------- Summary: start ------- 04:33:03 INFO - PROCESS | 1971 | Number of tests: 6 04:33:03 INFO - PROCESS | 1971 | 04:33:03 INFO - PROCESS | 1971 | [#0] dojo.html Cycles:165 Average:5787.40 Median:6068.15 stddev:647.08 (10.7%) stddev-sans-first:618.87 04:33:03 INFO - PROCESS | 1971 | Values: 3297.4 3305.1 3336.8 3338.3 3391.6 5650.5 5707.7 5707.9 5797.9 5805.7 5699.6 5776.0 5777.8 5780.5 5780.7 5190.0 5214.8 5249.9 5284.6 5296.9 5315.7 5317.7 5321.5 5329.0 5331.1 5222.5 5227.8 5264.7 5271.6 5322.7 5329.1 5341.7 5343.8 5351.6 5356.8 5109.7 5127.9 5155.6 5257.1 5265.0 5663.4 5685.0 5726.2 5795.3 5828.0 6276.3 6283.4 6286.6 6286.9 6297.5 6125.7 6241.9 6256.3 6289.5 6308.1 5931.2 5932.9 5940.2 5947.8 5970.7 6301.7 6303.9 6315.7 6331.5 6341.9 5956.9 6101.0 6132.1 6244.7 6256.3 6250.1 6271.2 6286.9 6291.9 6292.2 6250.2 6252.7 6268.1 6272.8 6278.0 6114.7 6131.6 6162.3 6301.9 6315.3 4948.6 4994.8 5015.3 5019.9 5035.6 4489.4 4493.7 4515.9 4552.3 4584.3 5777.0 5839.8 5839.9 5856.6 5916.7 6162.4 6196.9 6233.9 6234.4 6289.3 5672.9 5811.4 5870.7 5896.9 5917.8 4361.9 5497.2 5501.6 5510.4 5517.4 6201.3 6233.4 6315.6 6352.9 6353.7 6211.0 6217.3 6238.4 6262.7 6276.3 6337.4 6352.1 6356.7 6372.2 6374.6 6107.5 6131.2 6245.4 6250.2 6287.9 6210.7 6240.3 6250.7 6257.7 6268.2 6177.4 6212.7 6213.1 6218.6 6247.4 6043.7 6136.9 6166.9 6216.7 6242.5 5035.0 6023.4 6107.9 6126.4 6128.7 5456.2 6160.7 6178.0 6192.2 6192.6 5901.4 5960.3 6068.2 6096.8 6106.0 04:33:03 INFO - PROCESS | 1971 | 04:33:03 INFO - PROCESS | 1971 | [#1] ext.html Cycles:180 Average:77116.03 Median:37836.22 stddev:179448.03 (474.3%) stddev-sans-first:179859.09 04:33:03 INFO - PROCESS | 1971 | Values: 442.6 452.1 507.5 533.1 536.0 25305.9 39681.2 41075.0 41146.0 41149.8 39900.6 41991.8 42402.2 42448.6 42490.2 8457.5 9516.7 9706.9 9712.4 9733.5 8389.5 8686.4 8690.3 8701.3 11577.4 11616.6 11889.0 11894.9 11930.3 11972.7 11705.1 11792.9 11793.8 11802.4 11819.9 11485.8 11594.8 11598.9 11617.2 11625.9 39436.8 39725.4 39773.0 39778.6 39805.4 3331.3 3333.6 3348.1 3564.9 3565.1 30304.8 30316.4 30539.0 31091.4 31133.0 3073.4 3164.0 3190.4 3212.6 3335.1 526263.0 531746.0 532017.0 532746.3 533005.0 58782.0 59517.0 59528.4 59541.0 59556.7 30512.8 31120.7 63391.7 63501.7 63647.4 15899.4 16478.3 16496.2 16523.8 16547.2 12526.8 12610.9 12619.4 12627.2 12657.9 13867.6 16413.6 16418.1 16758.4 18701.6 6537.5 7815.3 7822.7 7881.1 7978.2 862.5 943.6 953.5 985.3 1048.0 28356.9 28751.6 29092.0 29178.7 29892.9 841.4 922.3 924.4 1122.3 1127.3 15314.8 15334.5 15397.8 15637.9 15725.6 581605.0 685828.0 698399.0 698876.0 700887.0 47543.8 54500.5 54880.2 55585.7 56501.0 1330.3 1337.8 1373.1 1375.4 1377.0 28650.3 29756.5 30620.2 30977.7 31317.0 47724.0 47901.3 48249.8 48278.6 48282.0 671935.0 676179.0 686473.0 897907.5 901059.0 39621.8 39980.6 40147.0 40442.6 40537.6 35946.8 44690.6 44861.0 45157.8 45177.5 37056.9 37079.0 37595.9 38230.5 38464.0 31077.6 36788.8 37877.8 38048.2 38335.8 37126.8 37384.0 37794.6 38374.4 38433.4 29088.7 29323.3 33539.8 34008.7 34153.0 19964.0 25109.9 25112.6 25199.7 25575.9 04:33:03 INFO - PROCESS | 1971 | 04:33:03 INFO - PROCESS | 1971 | [#2] jquery.html Cycles:180 Average:34179.16 Median:11427.40 stddev:96984.14 (848.7%) stddev-sans-first:97222.94 04:33:03 INFO - PROCESS | 1971 | Values: 345.1 358.3 363.9 375.7 396.3 14946.9 16388.3 19893.9 19974.4 20110.3 17583.5 19553.8 19705.7 19788.0 20048.8 5042.9 5663.5 5901.7 5902.4 5915.3 4107.2 4316.2 5808.5 6003.3 6003.7 4227.0 4242.4 4256.5 4264.0 4363.2 4712.9 6076.4 6453.7 6589.7 6596.9 5010.6 5291.0 5699.1 5708.3 5716.5 12504.7 12565.7 12733.2 12769.7 12788.4 2131.1 2138.9 2174.4 2333.0 2639.4 9239.2 9256.5 10060.5 10173.7 15330.2 1596.8 1822.2 1826.3 1907.6 1916.2 100831.0 111368.3 115259.0 118822.6 123879.4 10629.3 11151.6 11186.7 11190.4 11416.3 14004.6 14033.1 14044.2 14055.5 14063.9 6635.4 8439.2 8486.0 8534.6 8546.0 8342.0 8347.0 8348.7 8506.8 8541.4 4314.3 4403.2 4412.7 4489.5 4514.3 1848.4 1853.8 1917.5 1935.2 1943.4 830.6 839.5 889.2 912.2 947.5 17767.8 20373.9 20498.7 20711.4 20723.7 915.7 926.6 929.6 946.3 983.8 10470.5 10838.6 10882.9 10899.7 10944.0 496693.0 575687.0 585409.0 605966.0 617808.0 109399.5 129717.0 139197.2 145457.0 148900.0 952.2 972.5 1020.1 1021.0 1023.4 18807.8 19018.6 19227.6 19237.6 19699.7 54755.9 55405.7 55869.4 56528.0 57123.4 78015.6 82638.0 83141.5 87351.1 88635.7 7809.6 7851.0 7984.1 8035.4 8135.5 10729.7 11178.8 12559.7 12912.7 13010.4 10702.8 10861.7 11296.4 11314.0 11417.9 10627.3 10695.2 10865.9 10958.3 11315.8 11138.5 11182.7 11403.8 11502.2 11708.3 7777.6 7961.4 8065.0 8149.5 8255.8 10694.7 10878.2 10903.6 11241.1 11305.2 04:33:03 INFO - PROCESS | 1971 | 04:33:03 INFO - PROCESS | 1971 | [#3] mootools.html Cycles:180 Average:5721.81 Median:2526.27 stddev:3226.78 (127.7%) stddev-sans-first:3219.15 04:33:03 INFO - PROCESS | 1971 | Values: 1355.5 1422.0 1528.9 1707.6 1882.9 7880.0 8441.2 8932.2 8947.3 9074.3 8829.2 8941.8 8944.2 8945.6 8968.4 2385.8 2419.2 2419.8 2421.4 2423.9 2358.6 2391.6 2408.8 2412.0 2417.0 2371.6 2391.9 2394.7 2400.1 2403.0 2349.8 2377.8 2386.7 2387.5 2394.3 2337.5 2365.2 2370.7 2374.3 2376.3 8574.5 8788.4 8864.9 8905.3 8974.9 5219.1 5390.5 5440.9 5445.8 5454.2 3495.5 3515.1 3586.0 3596.5 3608.8 1054.6 1072.1 1072.4 1081.9 1094.6 11604.8 11704.5 11810.4 11821.7 11844.3 4539.6 4593.3 4802.4 4805.1 4806.7 10107.4 10124.3 10136.9 10138.7 10150.4 5384.4 5388.2 5391.9 5394.2 5405.2 3863.7 4358.7 4554.8 4762.8 4931.5 3894.6 3901.5 4024.2 4036.8 4069.0 1264.4 1296.4 1296.4 1306.6 1311.1 789.3 874.0 894.3 935.1 958.5 5733.7 5886.1 5973.9 6128.6 6151.6 442.8 447.4 453.5 454.7 459.2 3913.9 4004.2 4108.1 4142.6 4153.7 10367.6 10399.4 10522.4 10840.2 10869.0 10651.1 10655.5 10660.0 10677.9 10700.3 8098.5 8162.6 8191.3 8220.1 8280.1 5781.3 5870.4 5877.9 6025.3 6025.6 9938.6 9979.5 9982.3 10053.0 10085.4 8406.4 9835.0 10240.7 10308.7 10315.6 7523.5 7585.4 7651.8 7669.2 7693.8 8257.1 8318.2 8318.5 8320.0 8357.0 7332.4 7468.0 7540.6 7577.6 7599.8 7286.2 7358.7 7359.4 7394.2 7401.4 6921.2 7064.7 7075.1 7083.4 7085.9 4216.9 4306.8 4925.6 4981.5 5186.8 4796.8 5031.6 5165.8 5246.4 5293.0 04:33:03 INFO - PROCESS | 1971 | 04:33:03 INFO - PROCESS | 1971 | [#4] prototype.html Cycles:180 Average:4488.63 Median:4939.01 stddev:1388.39 (28.1%) stddev-sans-first:1355.96 04:33:03 INFO - PROCESS | 1971 | Values: 285.1 297.9 302.8 305.7 313.0 4533.7 4750.7 5213.0 5407.0 5476.5 4391.4 4406.7 4503.6 4667.6 4869.8 3359.9 3495.7 3879.8 4054.3 4108.5 3391.3 3866.6 4043.3 4075.8 4133.6 3547.3 3709.6 3996.9 4042.7 4074.6 3019.6 3855.1 3983.3 3984.6 4072.7 3102.6 3548.3 3550.8 3556.5 3565.7 5242.5 5259.3 5281.5 5285.5 5321.9 3772.8 3810.9 4637.8 4706.3 4714.5 5592.2 5597.6 5610.5 5611.7 5615.5 3510.8 3931.2 3932.1 3943.0 3960.5 5472.0 5622.7 5839.0 5923.9 5958.4 3938.1 3956.7 3957.7 3978.3 3991.6 4023.7 4026.3 4028.4 4032.0 4047.0 3698.6 4087.9 4363.7 4475.5 4655.0 5533.9 5598.6 5649.4 5654.7 5730.1 1351.9 1367.7 1378.3 1381.7 1387.1 729.6 745.9 775.4 782.1 839.7 4177.9 5142.2 5193.3 5357.5 5369.6 5028.6 5840.3 5855.6 5872.6 5912.2 4292.0 4476.8 4614.9 4680.7 4819.3 3008.4 3029.3 3056.4 3066.6 3090.8 5088.7 5291.4 5712.5 5933.1 5945.2 5721.3 5801.0 5944.9 5967.5 6028.0 5285.1 5611.5 5763.9 5968.4 6062.4 4301.3 5347.6 5814.7 5819.9 5863.9 4728.0 5219.8 5613.6 5717.4 5738.9 5470.0 5484.1 5494.9 5976.8 6084.0 3824.6 3948.9 3965.6 3974.6 3982.5 4605.6 5132.5 5740.4 5761.6 5792.9 4822.7 4963.5 5659.6 5663.9 5679.5 5186.8 5230.9 5618.6 5626.4 5643.5 4930.5 5239.2 5483.3 5490.1 5521.8 4479.6 4908.9 4947.6 5528.1 5590.3 4849.3 5104.6 5347.0 5388.7 5571.7 04:33:03 INFO - PROCESS | 1971 | 04:33:03 INFO - PROCESS | 1971 | [#5] yui.html Cycles:180 Average:25817.05 Median:13595.67 stddev:41955.50 (308.6%) stddev-sans-first:42030.81 04:33:03 INFO - PROCESS | 1971 | Values: 699.2 701.4 725.7 765.0 769.9 15771.7 16603.9 16633.9 16658.2 16683.2 15007.8 15185.7 15188.5 15188.9 15208.5 580.4 672.3 692.4 847.6 876.2 549.3 641.9 648.8 791.5 804.8 879.3 883.6 886.6 887.8 891.0 886.1 887.6 887.9 888.3 889.3 662.8 663.9 664.4 664.6 669.8 11960.3 14708.3 14932.0 14970.3 15563.4 14320.4 14950.7 14950.9 14967.1 14990.7 11927.2 12009.2 12024.2 12107.3 12123.9 3300.4 3818.6 3825.2 3880.4 3965.6 113924.0 114826.0 120781.0 127595.0 127619.0 11299.0 11380.9 11499.4 11563.3 11681.9 23563.5 23801.9 23820.2 23834.0 23890.9 8137.1 8214.7 8244.1 8299.4 8351.3 3929.6 4493.9 4503.5 4516.8 4687.8 3516.0 3634.9 3637.8 3638.9 3646.0 553.9 660.3 669.7 671.2 671.7 511.0 552.3 578.2 613.9 677.9 9179.0 9383.5 9435.2 9569.1 9676.7 636.4 647.8 669.4 671.7 673.8 3556.6 4694.2 4694.9 4710.0 4753.3 98722.0 149630.3 149839.0 153550.0 174936.3 88751.0 125650.0 146918.0 147213.1 148024.0 113499.3 117370.0 121366.0 125584.0 127848.2 8648.7 8951.7 9012.7 9106.5 9288.9 67119.0 67598.7 68415.3 69087.7 69681.7 98257.5 99885.5 102830.0 103175.0 105155.0 11067.5 11365.0 11999.2 12074.5 12103.2 12857.6 13021.7 13052.0 13404.9 13510.9 12280.6 12497.7 12616.3 12721.8 12780.4 11633.9 11865.2 11938.2 12105.6 12542.6 11790.5 12012.3 12081.3 12516.0 12563.9 10257.5 10410.0 10472.3 10642.5 10808.2 10338.7 10608.4 10633.3 10672.0 10678.9 04:33:03 INFO - PROCESS | 1971 | -------- Summary: end -------- 04:33:03 INFO - PROCESS | 1971 | 04:33:04 INFO - TEST-INFO | 1971: exit 0 04:33:04 INFO - TEST-OK | dromaeo_css | took 1116942ms 04:33:04 INFO - TEST-START | kraken 04:33:04 INFO - operating with platform_type : mac_ 04:33:04 INFO - Initialising browser for kraken test... 04:33:04 INFO - TEST-INFO | started process 1976 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmplynTlR/profile http://localhost:52483/getInfo.html) 04:33:06 INFO - PROCESS | 1976 | __metrics Screen width/height:1600/1200 04:33:06 INFO - PROCESS | 1976 | colorDepth:24 04:33:06 INFO - PROCESS | 1976 | Browser inner width/height: 1024/689 04:33:06 INFO - PROCESS | 1976 | __metrics 04:33:06 INFO - PROCESS | 1976 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:33:06 INFO - PROCESS | 1976 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:33:07 INFO - TEST-INFO | 1976: exit 0 04:33:07 INFO - Browser initialized. 04:33:07 INFO - Running cycle 1/1 for kraken test... 04:33:07 INFO - TEST-INFO | started process 1977 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmplynTlR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:33:19 INFO - PROCESS | 1977 | RSS: Main: 232628224 04:33:19 INFO - PROCESS | 1977 | 04:33:21 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52483/tests/kraken/driver.html?testName=audio-beat-detection) 04:33:22 INFO - PROCESS | 1977 | RSS: Main: 291487744 04:33:22 INFO - PROCESS | 1977 | 04:33:25 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52483/tests/kraken/driver.html?testName=audio-dft) 04:33:26 INFO - PROCESS | 1977 | RSS: Main: 287498240 04:33:26 INFO - PROCESS | 1977 | 04:33:29 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52483/tests/kraken/driver.html?testName=audio-fft) 04:33:30 INFO - PROCESS | 1977 | RSS: Main: 375111680 04:33:30 INFO - PROCESS | 1977 | 04:33:32 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52483/tests/kraken/driver.html?testName=audio-oscillator) 04:33:32 INFO - PROCESS | 1977 | RSS: Main: 279871488 04:33:32 INFO - PROCESS | 1977 | 04:33:35 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52483/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:33:35 INFO - PROCESS | 1977 | RSS: Main: 283734016 04:33:35 INFO - PROCESS | 1977 | 04:33:38 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52483/tests/kraken/driver.html?testName=imaging-darkroom) 04:33:39 INFO - PROCESS | 1977 | RSS: Main: 322252800 04:33:39 INFO - PROCESS | 1977 | 04:33:41 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52483/tests/kraken/driver.html?testName=imaging-desaturate) 04:33:42 INFO - PROCESS | 1977 | RSS: Main: 321331200 04:33:42 INFO - PROCESS | 1977 | 04:33:45 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52483/tests/kraken/driver.html?testName=json-parse-financial) 04:33:45 INFO - PROCESS | 1977 | RSS: Main: 323031040 04:33:45 INFO - PROCESS | 1977 | 04:33:47 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52483/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:33:48 INFO - PROCESS | 1977 | RSS: Main: 299003904 04:33:48 INFO - PROCESS | 1977 | 04:33:49 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:33:50 INFO - PROCESS | 1977 | RSS: Main: 306671616 04:33:50 INFO - PROCESS | 1977 | 04:33:52 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:33:53 INFO - PROCESS | 1977 | RSS: Main: 308994048 04:33:53 INFO - PROCESS | 1977 | 04:33:56 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:33:57 INFO - PROCESS | 1977 | RSS: Main: 319688704 04:33:57 INFO - PROCESS | 1977 | 04:33:59 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:34:00 INFO - PROCESS | 1977 | RSS: Main: 316006400 04:34:00 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:52483/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52483/tests/kraken/driver.html?testName=ai-astar) 04:34:02 INFO - PROCESS | 1977 | RSS: Main: 360570880 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | __start_tp_report 04:34:02 INFO - PROCESS | 1977 | _x_x_mozilla_page_load 04:34:02 INFO - PROCESS | 1977 | _x_x_mozilla_page_load_details 04:34:02 INFO - PROCESS | 1977 | |i|pagename|runs| 04:34:02 INFO - PROCESS | 1977 | |0;ai-astar;114;112;116;113;114;114;113;112;113;123 04:34:02 INFO - PROCESS | 1977 | |1;audio-beat-detection;144;131;167;162;108;131;131;108;158;108 04:34:02 INFO - PROCESS | 1977 | |2;audio-dft;185;185;184;185;183;186;184;190;249;208 04:34:02 INFO - PROCESS | 1977 | |3;audio-fft;76;76;76;153;57;57;58;70;135;57 04:34:02 INFO - PROCESS | 1977 | |4;audio-oscillator;82;87;138;70;73;83;119;69;70;120 04:34:02 INFO - PROCESS | 1977 | |5;imaging-gaussian-blur;86;84;85;101;86;83;86;83;86;84 04:34:02 INFO - PROCESS | 1977 | |6;imaging-darkroom;92;98;86;131;149;93;92;94;93;92 04:34:02 INFO - PROCESS | 1977 | |7;imaging-desaturate;84;85;88;83;86;83;83;82;86;84 04:34:02 INFO - PROCESS | 1977 | |8;json-parse-financial;54;55;56;53;54;54;54;54;54;54 04:34:02 INFO - PROCESS | 1977 | |9;json-stringify-tinderbox;67;64;71;73;73;76;72;69;69;68 04:34:02 INFO - PROCESS | 1977 | |10;stanford-crypto-aes;84;86;83;84;87;86;86;84;88;83 04:34:02 INFO - PROCESS | 1977 | |11;stanford-crypto-ccm;139;145;153;146;150;151;139;134;178;139 04:34:02 INFO - PROCESS | 1977 | |12;stanford-crypto-pbkdf2;166;227;168;182;168;169;166;171;168;168 04:34:02 INFO - PROCESS | 1977 | |13;stanford-crypto-sha256-iterative;68;67;68;66;68;67;67;67;66;66 04:34:02 INFO - PROCESS | 1977 | __end_tp_report 04:34:02 INFO - PROCESS | 1977 | __start_cc_report 04:34:02 INFO - PROCESS | 1977 | _x_x_mozilla_cycle_collect,2080 04:34:02 INFO - PROCESS | 1977 | __end_cc_report 04:34:02 INFO - PROCESS | 1977 | __startTimestamp1473334442565__endTimestamp 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | ------- Summary: start ------- 04:34:02 INFO - PROCESS | 1977 | Number of tests: 14 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | [#0] ai-astar Cycles:10 Average:114.40 Median:114.00 stddev:3.24 (2.8%) stddev-sans-first:3.43 04:34:02 INFO - PROCESS | 1977 | Values: 114.0 112.0 116.0 113.0 114.0 114.0 113.0 112.0 113.0 123.0 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:137.50 stddev:22.60 (16.4%) stddev-sans-first:23.73 04:34:02 INFO - PROCESS | 1977 | Values: 144.0 131.0 167.0 162.0 108.0 131.0 131.0 108.0 158.0 108.0 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | [#2] audio-dft Cycles:10 Average:193.90 Median:185.50 stddev:20.72 (11.2%) stddev-sans-first:21.73 04:34:02 INFO - PROCESS | 1977 | Values: 185.0 185.0 184.0 185.0 183.0 186.0 184.0 190.0 249.0 208.0 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | [#3] audio-fft Cycles:10 Average:81.50 Median:64.00 stddev:34.24 (53.5%) stddev-sans-first:36.26 04:34:02 INFO - PROCESS | 1977 | Values: 76.0 76.0 76.0 153.0 57.0 57.0 58.0 70.0 135.0 57.0 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:71.50 stddev:25.13 (35.2%) stddev-sans-first:26.44 04:34:02 INFO - PROCESS | 1977 | Values: 82.0 87.0 138.0 70.0 73.0 83.0 119.0 69.0 70.0 120.0 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:85.50 stddev:5.27 (6.2%) stddev-sans-first:5.59 04:34:02 INFO - PROCESS | 1977 | Values: 86.0 84.0 85.0 101.0 86.0 83.0 86.0 83.0 86.0 84.0 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | [#6] imaging-darkroom Cycles:10 Average:102.00 Median:92.50 stddev:20.68 (22.4%) stddev-sans-first:21.61 04:34:02 INFO - PROCESS | 1977 | Values: 92.0 98.0 86.0 131.0 149.0 93.0 92.0 94.0 93.0 92.0 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:1.84 (2.2%) stddev-sans-first:1.94 04:34:02 INFO - PROCESS | 1977 | Values: 84.0 85.0 88.0 83.0 86.0 83.0 83.0 82.0 86.0 84.0 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:0.79 (1.5%) stddev-sans-first:0.83 04:34:02 INFO - PROCESS | 1977 | Values: 54.0 55.0 56.0 53.0 54.0 54.0 54.0 54.0 54.0 54.0 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:71.50 stddev:3.49 (4.9%) stddev-sans-first:3.50 04:34:02 INFO - PROCESS | 1977 | Values: 67.0 64.0 71.0 73.0 73.0 76.0 72.0 69.0 69.0 68.0 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:86.00 stddev:1.73 (2.0%) stddev-sans-first:1.79 04:34:02 INFO - PROCESS | 1977 | Values: 84.0 86.0 83.0 84.0 87.0 86.0 86.0 84.0 88.0 83.0 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | [#11] stanford-crypto-ccm Cycles:10 Average:147.40 Median:148.00 stddev:12.41 (8.4%) stddev-sans-first:12.79 04:34:02 INFO - PROCESS | 1977 | Values: 139.0 145.0 153.0 146.0 150.0 151.0 139.0 134.0 178.0 139.0 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.30 Median:168.50 stddev:18.74 (11.1%) stddev-sans-first:19.58 04:34:02 INFO - PROCESS | 1977 | Values: 166.0 227.0 168.0 182.0 168.0 169.0 166.0 171.0 168.0 168.0 04:34:02 INFO - PROCESS | 1977 | 04:34:02 INFO - PROCESS | 1977 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 04:34:02 INFO - PROCESS | 1977 | Values: 68.0 67.0 68.0 66.0 68.0 67.0 67.0 67.0 66.0 66.0 04:34:02 INFO - PROCESS | 1977 | -------- Summary: end -------- 04:34:02 INFO - PROCESS | 1977 | 04:34:03 INFO - TEST-INFO | 1977: exit 0 04:34:03 INFO - TEST-OK | kraken | took 58911ms 04:34:03 INFO - SUITE-END | took 1175s 04:34:03 INFO - Completed test suite (00:19:36) 04:34:03 INFO - javascript benchmark 04:34:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3297.3734990351822, 3305.124142102343, 3336.8164750938704, 3338.265581572053, 3391.576052993376, 5650.51970582501, 5707.686077265748, 5707.914381284284, 5797.855053623656, 5805.651660900338, 5699.629524080932, 5776.0, 5777.768889244436, 5780.53755699544, 5780.7282397872505, 5190.0, 5214.843554693346, 5249.947500524984, 5284.5772338212855, 5296.894062118756, 5315.654482458628, 5317.654352467097, 5321.494458026481, 5328.973355133219, 5331.067063263933, 5222.529972302492, 5227.79088836446, 5264.736763161845, 5271.604629652772, 5322.733863306819, 5329.067413202693, 5341.706206158659, 5343.839684809462, 5351.625386222966, 5356.812511562116, 5109.693418394871, 5127.9230811537855, 5155.61332900034, 5257.106291930343, 5265.0, 5663.405342439026, 5685.033544297479, 5726.226959360475, 5795.275590551181, 5827.970860145714, 6276.309605943343, 6283.371662833726, 6286.591371560834, 6286.87426251477, 6297.464715499188, 6125.723854043087, 6241.87516249677, 6256.280527739323, 6289.496840252768, 6308.148399965991, 5931.228940237742, 5932.911006334908, 5940.227770389822, 5947.821565353046, 5970.731317090698, 6301.684915754194, 6303.936960630427, 6315.684215789192, 6331.461825744771, 6341.873162536724, 5956.880862382728, 6100.969495152496, 6132.110769107974, 6244.687765611701, 6256.280527739277, 6250.062490626442, 6271.184745982993, 6286.9056964145575, 6291.874162516816, 6292.2134733158355, 6250.249970003538, 6252.718627661766, 6268.059791031382, 6272.780452684134, 6277.96861015692, 6114.692773002168, 6131.590761709946, 6162.291336496271, 6301.905471417841, 6315.273743519554, 4948.628852836052, 4994.775235114356, 5015.322931404214, 5019.924701129486, 5035.647504674711, 4489.438820147481, 4493.707908985905, 4515.909681806411, 4552.271636538204, 4584.266517357207, 5777.0, 5839.795607153729, 5839.9124013139835, 5856.59003869725, 5916.674582897896, 6162.383761623802, 6196.907046394308, 6233.906491402542, 6234.4438780099945, 6289.339619339903, 5672.943270567324, 5811.389804070596, 5870.706464676749, 5896.882062358729, 5917.763289468459, 4361.912761744811, 5497.202905578669, 5501.587380946445, 5510.421405752419, 5517.36550296713, 6201.348858369806, 6233.376662333731, 6315.5894866833505, 6352.936470635234, 6353.682315884187, 6211.0372892201785, 6217.285012223652, 6238.407351301619, 6262.655553944394, 6276.278228003838, 6337.366263373626, 6352.142460767922, 6356.650384228912, 6372.171617689856, 6374.553781235269, 6107.522607572365, 6131.162586540493, 6245.375462453719, 6250.218722659668, 6287.937120628735, 6210.689465526796, 6240.344763799917, 6250.749970001331, 6257.655828929271, 6268.153799237227, 6177.382261773787, 6212.720427580679, 6213.099100630293, 6218.595791273553, 6247.406496383018, 6043.728032238472, 6136.877262454636, 6166.907496387468, 6216.72024758896, 6242.500599951902, 5034.974825125851, 6023.367546407564, 6107.908381374372, 6126.356732543019, 6128.747575574743, 5456.236126942311, 6160.691965401802, 6178.042403427476, 6192.225971753531, 6192.566520343713, 5901.350851406428, 5960.284765828128, 6068.150458935841, 6096.817095487142, 6105.9694701526205], "value": 5745.708498282389, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [442.5662850406601, 452.1386758225581, 507.5229284472596, 533.1336578060651, 536.0244355273404, 25305.873470632643, 39681.20637587246, 41075.0, 41146.0, 41149.794251028776, 39900.600993990054, 41991.79004104975, 42402.15195696092, 42448.57551424485, 42490.150196996045, 8457.53483558404, 9516.714498565054, 9706.902930970706, 9712.368696034771, 9733.51332433379, 8389.496630202177, 8686.391952563323, 8690.261327787146, 8701.347398945105, 11577.363245021521, 11616.593419230328, 11888.989435897958, 11894.940525297361, 11930.284182949008, 11972.70068248292, 11705.122115841303, 11792.882071179265, 11793.764124717458, 11802.40987950599, 11819.881801182008, 11485.827712584318, 11594.826077608843, 11598.88401115995, 11617.244879082833, 11625.941870290595, 39436.802815986026, 39725.40411893824, 39773.00567485807, 39778.60221397778, 39805.402918956235, 3331.3004269103517, 3333.583302087239, 3348.0792781984906, 3564.8930532084078, 3565.0552603560077, 30304.84847575748, 30316.393672126433, 30539.0, 31091.37817243665, 31133.0, 3073.416050950312, 3163.984180079085, 3190.3778763141195, 3212.5823642926503, 3335.0828522156357, 526263.0, 531746.0, 532017.0, 532746.3362683201, 533005.0, 58782.0, 59517.0, 59528.404715953155, 59541.0, 59556.70221648864, 30512.84743576268, 31120.665950053837, 63391.683041584496, 63501.68249158725, 63647.36352636414, 15899.364025439085, 16478.2584783685, 16496.17519124039, 16523.752143717855, 16547.172641367884, 12526.81209781854, 12610.873891261153, 12619.369031548385, 12627.179233349987, 12657.873421265853, 13867.583972480841, 16413.58966025835, 16418.097004664858, 16758.413455528997, 18701.625967480573, 6537.477001839841, 7815.25755053269, 7822.726204582926, 7881.123389962622, 7978.242067003625, 862.5385418801037, 943.5706753427174, 953.4565297780338, 985.3053597213947, 1047.9991608014327, 28356.858215708788, 28751.56872646912, 29092.0, 29178.708212917598, 29892.850535747617, 841.3941961787503, 922.2667978956746, 924.3991405586288, 1122.2873475343215, 1127.2672762704144, 15314.846851531565, 15334.463293784664, 15397.846021539865, 15637.921810390875, 15725.606859828596, 581605.0, 685828.0, 698399.0, 698876.0, 700887.0, 47543.76228118906, 54500.45499545033, 54880.176797348075, 55585.722071389384, 56501.0, 1330.3015916643647, 1337.806018127366, 1373.1074801379184, 1375.3742047368623, 1377.0360747476607, 28650.283742906595, 29756.484650101844, 30620.234494136934, 30977.690223098383, 31317.0, 47724.0, 47901.28148077712, 48249.7937551564, 48278.55164345075, 48282.0, 671935.0, 676179.0, 686473.0, 897907.5313870166, 901059.0, 39621.80189099036, 39980.600193997685, 40146.99632509211, 40442.59557404388, 40537.59462405456, 35946.8202658985, 44690.55309446994, 44861.0, 45157.77421112874, 45177.54822451733, 37056.88829335124, 37079.0, 37595.87212383633, 38230.47078116844, 38464.0, 31077.589217123594, 36788.81605592062, 37877.81061094677, 38048.239035219696, 38335.80832095822, 37126.81436592908, 37384.0, 37794.62205377911, 38374.42438363371, 38433.4234986481, 29088.654804696886, 29323.266918327212, 33539.83230083931, 34008.65991340154, 34153.0, 19964.0, 25109.87445062763, 25112.62331064999, 25199.74800251974, 25575.872120639277], "value": 20199.960369515564, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.0528299817, 358.2887967384741, 363.9217568222832, 375.7482486733889, 396.3361369705744, 14946.850531494681, 16388.34446622135, 19893.90053049733, 19974.40076797695, 20110.296139635117, 17583.472495825114, 19553.804461955377, 19705.70441443376, 19788.010599470006, 20048.799512004913, 5042.873928151789, 5663.461971112738, 5901.675407852566, 5902.409759024106, 5915.319738230094, 4107.199096176247, 4316.244657184996, 5808.477237048664, 6003.339632640406, 6003.6697981611, 4227.027783609771, 4242.384854196155, 4256.531781504032, 4264.0, 4363.170997510479, 4712.91602931326, 6076.4227398397525, 6453.741850325981, 6589.670516474157, 6596.868062638769, 5010.574101201402, 5290.973545132289, 5699.059655156894, 5708.286464191976, 5716.514096301819, 12504.74990500194, 12565.748685026248, 12733.172343797623, 12769.680757981032, 12788.360581970865, 2131.11558703138, 2138.9465263368384, 2174.3694328644674, 2332.9618319847673, 2639.3665520274994, 9239.214666753334, 9256.490893000882, 10060.496975151213, 10173.677421935101, 15330.15684137372, 1596.7944202127435, 1822.1891258390021, 1826.3151318255655, 1907.647085289212, 1916.2239293086323, 100831.0, 111368.32947505794, 115259.0, 118822.62354752942, 123879.3806030964, 10629.309094908805, 11151.609693660683, 11186.664400068012, 11190.440477976068, 11416.31502109876, 14004.649883752783, 14033.087849289763, 14044.157350558999, 14055.508057218152, 14063.859361406256, 6635.369639884203, 8439.156084391634, 8485.957570212233, 8534.615942282611, 8546.017208021158, 8342.0, 8347.0, 8348.749537513762, 8506.787330316667, 8541.444806087584, 4314.257498827958, 4403.229434848889, 4412.713173643703, 4489.483709373399, 4514.255147900625, 1848.4085092770185, 1853.7590113285187, 1917.4918646558674, 1935.2355819451238, 1943.3586916317581, 830.6096134816627, 839.4585492357518, 889.1997202517786, 912.202960168467, 947.5262368815593, 17767.78966628218, 20373.898130509253, 20498.692519612217, 20711.378658640264, 20723.689144662843, 915.7435917942987, 926.5645146781002, 929.6235024814961, 946.2855544064188, 983.7835676151235, 10470.528826202839, 10838.566457341776, 10882.891171088346, 10899.727506812234, 10944.0, 496693.0, 575687.0, 585409.0, 605966.0, 617808.0, 109399.45300273607, 129717.0, 139197.21605567832, 145457.0, 148900.0, 952.2096659772511, 972.5040229483014, 1020.1229737283286, 1020.9897901021043, 1023.4013102335068, 18807.811921880606, 19018.619627607648, 19227.61544769069, 19237.61524769525, 19699.70450443273, 54755.90488190134, 55405.722971386494, 55869.44130558642, 56528.0, 57123.42876571181, 78015.6099219523, 82638.0, 83141.51726344659, 87351.12648873685, 88635.67046494178, 7809.648565814438, 7851.018622672166, 7984.121746607984, 8035.437519373588, 8135.511869287861, 10729.678109656723, 11178.832317515082, 12559.686007849878, 12912.741745164856, 13010.414531345923, 10702.83945740799, 10861.67414977552, 11296.43517824122, 11314.03830674402, 11417.885821141683, 10627.256092073481, 10695.197860160675, 10865.891341086804, 10958.28771129874, 11315.830262546233, 11138.498767555642, 11182.66452006441, 11403.771924561297, 11502.217156340781, 11708.297502149899, 7777.607187253899, 7961.402894782798, 8065.0, 8149.470284431493, 8255.752327430188, 10694.679159625224, 10878.238523303293, 10903.563857445613, 11241.100711942861, 11305.208635395444], "value": 9188.703039600876, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.4849157320216, 1421.950231741889, 1528.9388424463023, 1707.6072503324235, 1882.858785591081, 7879.960600196998, 8441.198086181817, 8932.196102350787, 8947.284217262619, 9074.274058075338, 8829.161229683186, 8941.821163576724, 8944.239739622124, 8945.642174313036, 8968.372213945026, 2385.844920080194, 2419.213755529451, 2419.7701218384227, 2421.4309637235324, 2423.903043878243, 2358.5636657218383, 2391.56951748686, 2408.759124087595, 2411.963820542693, 2416.9727865657114, 2371.620540713486, 2391.892364843586, 2394.6527753475734, 2400.147947478646, 2403.038784486202, 2349.7767712067325, 2377.785999260066, 2386.6658667786523, 2387.4628208653003, 2394.31761947844, 2337.4506990857135, 2365.231299827563, 2370.7392186859433, 2374.3233178544033, 2376.3227480168243, 8574.485530868167, 8788.42875213109, 8864.911350886474, 8905.287576993825, 8974.86537701935, 5219.060569097561, 5390.5418039466695, 5440.891182176334, 5445.754941027623, 5454.209139674725, 3495.5106285120105, 3515.103648569624, 3586.049696830342, 3596.478510615946, 3608.8195590220384, 1054.6414219165445, 1072.0780129088978, 1072.367303291062, 1081.9080378167864, 1094.6497120921308, 11604.825927611093, 11704.47329870158, 11810.350430726307, 11821.704457388634, 11844.289342639384, 4539.6368290536675, 4593.333966574828, 4802.423709154854, 4805.111054454937, 4806.687565308243, 10107.39355638664, 10124.341917775468, 10136.898631013595, 10138.695839124837, 10150.390976541432, 5384.353877534721, 5388.218708287276, 5391.865203369947, 5394.163904594794, 5405.21624364465, 3863.74885632433, 4358.738475691469, 4554.817807287672, 4762.76186190696, 4931.50684931504, 3894.6105389460827, 3901.5318161820765, 4024.1951609677594, 4036.798160091984, 4069.0, 1264.3741348032784, 1296.358302640199, 1296.3971753134838, 1306.5627809409573, 1311.114997376767, 789.3458147813869, 874.0429229993148, 894.3471265975874, 935.0555938502162, 958.501579178837, 5733.6997252060955, 5886.1465087562065, 5973.880522389528, 6128.571000029955, 6151.569390142647, 442.77372479681515, 447.4094195661695, 453.4852941910955, 454.6680922926216, 459.2055743563587, 3913.941290880639, 4004.1791432756213, 4108.096218831853, 4142.627163555234, 4153.68847336451, 10367.63713270032, 10399.428031458192, 10522.368657880552, 10840.241183117107, 10869.0, 10651.09465695409, 10655.467226638637, 10659.987301206373, 10677.932206779415, 10700.250982431311, 8098.521183053534, 8162.632681529345, 8191.303739182236, 8220.095789463154, 8280.130586288353, 5781.311297939531, 5870.354261031198, 5877.911831322448, 6025.276966764016, 6025.638461692312, 9938.552765125442, 9979.50102494887, 9982.251331150048, 10053.04496072872, 10085.394876307151, 8406.411551191359, 9835.0, 10240.743981400525, 10308.690739277834, 10315.587376504856, 7523.510971786816, 7585.393168546393, 7651.846963060819, 7669.1947345527915, 7693.8461230773955, 8257.091719910684, 8318.168183181875, 8318.542480163407, 8320.0, 8357.0, 7332.376747976482, 7468.029156209658, 7540.622968851645, 7577.621118944141, 7599.772006839804, 7286.234945330876, 7358.668859901424, 7359.4480413968095, 7394.186639469546, 7401.3708834747495, 6921.204061532988, 7064.682089306097, 7075.080239568823, 7083.3979111774015, 7085.858282834418, 4216.903605062767, 4306.784660767012, 4925.605951523941, 4981.526754958273, 5186.844394668166, 4796.784144713426, 5031.647784655039, 5165.845024649267, 5246.449122842047, 5293.047251494768], "value": 4518.899669980281, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.1147187981318, 297.9433907557564, 302.7532560962815, 305.7233203950425, 313.04365164422705, 4533.6826422150425, 4750.714957102577, 5212.983468223698, 5407.026735187665, 5476.479734425228, 4391.385206071144, 4406.69153159279, 4503.61719253863, 4667.649926255527, 4869.7808598613055, 3359.9160020999425, 3495.667911548399, 3879.8060096995177, 4054.2904991626497, 4108.52751933527, 3391.3217356528794, 3866.574676785551, 4043.2722110020186, 4075.7962101894936, 4133.586641335857, 3547.2728090741334, 3709.5548534175937, 3996.900077498057, 4042.7170098093147, 4074.6129117733917, 3019.6225471816024, 3855.132595166079, 3983.3353314950314, 3984.5816189300285, 4072.6741860651223, 3102.5501302311263, 3548.254866478043, 3550.804705741183, 3556.5198698175673, 3565.6790888820133, 5242.475752424765, 5259.342582177228, 5281.498257665516, 5285.471452854722, 5321.893562128775, 3772.792916144059, 3810.904727381838, 4637.814487420499, 4706.29405589161, 4714.538493067126, 5592.217089607459, 5597.608167428281, 5610.4670056344585, 5611.7474713637985, 5615.511889349325, 3510.8420121094614, 3931.1941052084253, 3932.0956180078456, 3942.960570394288, 3960.485136932209, 5472.0, 5622.734884650962, 5838.970805145989, 5923.851903702356, 5958.374370691102, 3938.133610605662, 3956.723029387915, 3957.683385329167, 3978.2839088964265, 3991.600839915985, 4023.7183397162503, 4026.275270451288, 4028.415879697486, 4031.9798401007806, 4047.0287131088326, 3698.5561732591723, 4087.8758341456337, 4363.650907927358, 4475.507694153641, 4654.953450465519, 5533.889322213533, 5598.636088654224, 5649.440852263048, 5654.688992105392, 5730.08318669011, 1351.8986076044337, 1367.7264547090422, 1378.2764048874421, 1381.696026874086, 1387.0650442632673, 729.6461216310086, 745.9072458847814, 775.3874439193087, 782.1396463889789, 839.6607411179159, 4177.937330940038, 5142.1772516397195, 5193.2989046479, 5357.544608708304, 5369.624126311199, 5028.64799464034, 5840.328362238397, 5855.560832937546, 5872.647641141545, 5912.17229587858, 4291.9785401072795, 4476.798544065525, 4614.861554153381, 4680.67235293533, 4819.349387832668, 3008.4283986042583, 3029.3032602501107, 3056.43455960648, 3066.632004159515, 3090.799098058619, 5088.669236499616, 5291.354322838581, 5712.514436272963, 5933.139694744238, 5945.16767652529, 5721.313442386941, 5800.997699913387, 5944.851378715567, 5967.46292833647, 6027.969860150759, 5285.101532739367, 5611.494965453047, 5763.884722305615, 5968.37332080134, 6062.42406971337, 4301.268784306612, 5347.5721942243745, 5814.738336774771, 5819.941800582109, 5863.912041319469, 4728.0, 5219.817306394258, 5613.635113717595, 5717.399673034438, 5738.885222295447, 5470.018839732275, 5484.149956756709, 5494.8735509221315, 5976.820695379146, 6084.0, 3824.6175382462643, 3948.8548320986592, 3965.6034396561267, 3974.5627980922654, 3982.4623675803396, 4605.608523275425, 5132.538071573576, 5740.378994908971, 5761.56788240892, 5792.8551786204, 4822.662413631152, 4963.528464795839, 5659.6321239119325, 5663.884863325073, 5679.488846003879, 5186.766595503287, 5230.947690523046, 5618.550515958632, 5626.35296940857, 5643.463870932255, 4930.457649658606, 5239.214117882196, 5483.326171465048, 5490.062816393211, 5521.779128834802, 4479.6257983268915, 4908.92636610444, 4947.554720075211, 5528.115501519737, 5590.316648008898, 4849.3210950466255, 5104.642675012714, 5346.994876921154, 5388.703621300866, 5571.693556854412], "value": 4041.9154436839463, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.1819571101811, 701.3932947999979, 725.6843273176172, 764.9923500764994, 769.9384049276058, 15771.68456630868, 16603.920745151558, 16633.9187952783, 16658.167091645413, 16683.24925378358, 15007.849921500781, 15185.696286074271, 15188.544343669708, 15188.924055379708, 15208.467703630375, 580.3529065092413, 672.3411057163983, 692.4114502672738, 847.6143354773594, 876.2332958661171, 549.2914140758417, 641.8895311111779, 648.8053583924827, 791.5053091817614, 804.806846356873, 879.2745984562729, 883.6200433813458, 886.6364790435922, 887.7824932891418, 891.0198781340538, 886.0961818944705, 887.573964497045, 887.8978917424514, 888.3470649072893, 889.2574700125404, 662.7912207654573, 663.8506336074392, 664.445188267797, 664.6344510519243, 669.7823207457595, 11960.282383057045, 14708.338124784303, 14932.0, 14970.326335314829, 15563.37746490139, 14320.355583998746, 14950.70098598022, 14950.925245373703, 14967.101973881494, 14990.700185996218, 11927.224730392496, 12009.159358844885, 12024.158308918379, 12107.334096624681, 12123.939380303042, 3300.405926933151, 3818.6372294631965, 3825.196708691178, 3880.3791393377205, 3965.5637879833193, 113924.0, 114826.0, 120781.0, 127595.0, 127618.99999999907, 11299.0, 11380.943095284636, 11499.425028748528, 11563.30620162793, 11681.88318116825, 23563.52872942566, 23801.880990595284, 23820.16629417962, 23834.04663813428, 23890.924908379166, 8137.145599711945, 8214.712485062993, 8244.051934027544, 8299.419040667217, 8351.331893448509, 3929.626685464876, 4493.910121797546, 4503.527129651339, 4516.774161291923, 4687.7656117194, 3515.9824200878834, 3634.890953271406, 3637.781733096023, 3638.872639457606, 3645.9817700911326, 553.9446055394428, 660.2736989311815, 669.718718138383, 671.1979184874126, 671.7078071039016, 511.0315951272318, 552.3399537552666, 578.2309528327314, 613.8741557980603, 677.9084823548857, 9179.0, 9383.5308234588, 9435.198008169382, 9569.138777510057, 9676.661316853877, 636.3858876184504, 647.8186107889799, 669.3641041010961, 671.7493720926726, 673.7978606418055, 3556.590992035949, 4694.178518759203, 4694.906101878011, 4710.0, 4753.263244197155, 98722.01277987128, 149630.25184874007, 149839.0, 153550.0, 174936.26083565824, 88751.0, 125650.0, 146918.0, 147213.05573888464, 148024.0, 113499.30013997233, 117370.0, 121366.0, 125584.0, 127848.18353155276, 8648.697295594875, 8951.73144805657, 9012.684556040507, 9106.453612783254, 9288.907110928803, 67119.0, 67598.66200669162, 68415.3158468409, 69087.65456172687, 69681.65159174171, 98257.50871245598, 99885.50057249959, 102830.0, 103175.0, 105155.0, 11067.50196241155, 11365.0, 11999.227198644943, 12074.45664945097, 12103.213291136048, 12857.614271571867, 13021.674458138623, 13052.0, 13404.865951340362, 13510.93244533771, 12280.631581052583, 12497.687557811127, 12616.306103164414, 12721.8091728626, 12780.360981951051, 11633.883661163618, 11865.231949288092, 11938.22401543897, 12105.636830895088, 12542.623721288375, 11790.528378864748, 12012.279263244232, 12081.335526546125, 12516.0, 12563.937180314406, 10257.487125643836, 10410.0, 10472.319299245524, 10642.467876606293, 10808.189385796253, 10338.741531461774, 10608.363498189825, 10633.308834925705, 10672.039516443558, 10678.8932110681], "value": 7742.475971154631, "unit": "score"}], "name": "dromaeo_css", "value": 7295.9607755856805, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 116.0, 113.0, 114.0, 114.0, 113.0, 112.0, 113.0, 123.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 131.0, 167.0, 162.0, 108.0, 131.0, 131.0, 108.0, 158.0, 108.0], "value": 134.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 184.0, 185.0, 183.0, 186.0, 184.0, 190.0, 249.0, 208.0], "value": 193.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 153.0, 57.0, 57.0, 58.0, 70.0, 135.0, 57.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 87.0, 138.0, 70.0, 73.0, 83.0, 119.0, 69.0, 70.0, 120.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 101.0, 86.0, 83.0, 86.0, 83.0, 86.0, 84.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 98.0, 86.0, 131.0, 149.0, 93.0, 92.0, 94.0, 93.0, 92.0], "value": 102.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 88.0, 83.0, 86.0, 83.0, 83.0, 82.0, 86.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 56.0, 53.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 71.0, 73.0, 73.0, 76.0, 72.0, 69.0, 69.0, 68.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 83.0, 84.0, 87.0, 86.0, 86.0, 84.0, 88.0, 83.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 145.0, 153.0, 146.0, 150.0, 151.0, 139.0, 134.0, 178.0, 139.0], "value": 147.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 227.0, 168.0, 182.0, 168.0, 169.0, 166.0, 171.0, 168.0, 168.0], "value": 175.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 66.0, 68.0, 67.0, 67.0, 67.0, 66.0, 66.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1487.7, "alertThreshold": 2.0}]} 04:34:03 INFO - Return code: 0 04:34:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:34:03 INFO - # TBPL SUCCESS # 04:34:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:34:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:34:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:34:03 INFO - 2016-09-08 04:34:03.895 screenresolution[1981:19812] starting screenresolution argv=screenresolution get 04:34:03 INFO - 2016-09-08 04:34:03.898 screenresolution[1981:19812] Display 0: 1600x1200x32@60 04:34:03 INFO - 2016-09-08 04:34:03.904 screenresolution[1982:19815] starting screenresolution argv=screenresolution list 04:34:03 INFO - Available Modes on Display 0 04:34:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:34:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:34:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:34:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:34:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:34:04 INFO - Intel Iris: 04:34:04 INFO - Chipset Model: Intel Iris 04:34:04 INFO - Type: GPU 04:34:04 INFO - Bus: Built-In 04:34:04 INFO - VRAM (Dynamic, Max): 1536 MB 04:34:04 INFO - Vendor: Intel (0x8086) 04:34:04 INFO - Device ID: 0x0a2e 04:34:04 INFO - Revision ID: 0x0009 04:34:04 INFO - Displays: 04:34:04 INFO - Display: 04:34:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:34:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:34:04 INFO - Display Serial Number: 1600x1200 60 04:34:04 INFO - Main Display: Yes 04:34:04 INFO - Mirror: Off 04:34:04 INFO - Online: Yes 04:34:04 INFO - Rotation: Supported 04:34:04 INFO - Return code: 0 04:34:04 INFO - Running post-action listener: _resource_record_post_action 04:34:04 INFO - [mozharness: 2016-09-08 11:34:04.135424Z] Finished run-tests step (success) 04:34:04 INFO - Running post-run listener: _resource_record_post_run 04:34:04 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 557056; Write bytes: 703651328; Read time: 6; Write time: 1893 04:34:04 INFO - TinderboxPrint: CPU usage
53.4% 04:34:04 INFO - TinderboxPrint: I/O read bytes / time
557,056 / 6 04:34:04 INFO - TinderboxPrint: I/O write bytes / time
703,651,328 / 1,893 04:34:04 INFO - TinderboxPrint: CPU idle
2,254.5 (46.6%) 04:34:04 INFO - TinderboxPrint: CPU system
963.1 (19.9%) 04:34:04 INFO - TinderboxPrint: CPU user
1,624.1 (33.5%) 04:34:04 INFO - TinderboxPrint: Swap in / out
405,585,920 / 0 04:34:04 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 406288896; Write bytes: 404012544; Read time: 30001; Write time: 789 04:34:04 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 499712; Write bytes: 292098048; Read time: 5; Write time: 1076 04:34:04 INFO - Running post-run listener: _upload_blobber_files 04:34:04 INFO - Blob upload gear active. 04:34:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:34:04 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 04:34:04 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'] 04:34:04 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 04:34:05 INFO - (blobuploader) - INFO - Open directory for files ... 04:34:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:34:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:34:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:34:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:34:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:34:06 INFO - (blobuploader) - INFO - Done attempting. 04:34:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:34:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:34:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:34:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:34:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:34:07 INFO - (blobuploader) - INFO - Done attempting. 04:34:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:34:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:34:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:34:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:34:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:34:10 INFO - (blobuploader) - INFO - Done attempting. 04:34:10 INFO - (blobuploader) - INFO - Iteration through files over. 04:34:10 INFO - Return code: 0 04:34:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:34:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:34:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f54eea0ab2234e08476481a864423094a1cbc3a73a6b6018b10c45705491fdfe95265b88556227acf5160b36616a4d00f026cb795b51bff0f87c2cc4125d01c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0eee8c83618a4a7c3821ad4c1a9389f55e31a9c1cf35a4dae90e2f73b7bd033c3df7bc84b7a11f0badc93175f72e01a0b164c25d90852b703f74c826b63cd476", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bd98791fb0e094514d70b0eb5f2c39814b56abc3ecb5b51673b2ec8787338dc99b1db8b3028dcf39e2d9fa7c88611db5ba36e8535f53b1e1729eae978d11121"} 04:34:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:34:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:34:10 INFO - Contents: 04:34:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f54eea0ab2234e08476481a864423094a1cbc3a73a6b6018b10c45705491fdfe95265b88556227acf5160b36616a4d00f026cb795b51bff0f87c2cc4125d01c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0eee8c83618a4a7c3821ad4c1a9389f55e31a9c1cf35a4dae90e2f73b7bd033c3df7bc84b7a11f0badc93175f72e01a0b164c25d90852b703f74c826b63cd476", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bd98791fb0e094514d70b0eb5f2c39814b56abc3ecb5b51673b2ec8787338dc99b1db8b3028dcf39e2d9fa7c88611db5ba36e8535f53b1e1729eae978d11121"} 04:34:10 INFO - Running post-run listener: copy_logs_to_upload_dir 04:34:10 INFO - Copying logs to upload dir... 04:34:10 INFO - mkdir: /builds/slave/test/build/upload/logs 04:34:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.710534 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-08 04:34:10.341618) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 04:34:10.345346) ========= 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.7LoVr62NCY/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.evYvzT4g1f/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f54eea0ab2234e08476481a864423094a1cbc3a73a6b6018b10c45705491fdfe95265b88556227acf5160b36616a4d00f026cb795b51bff0f87c2cc4125d01c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0eee8c83618a4a7c3821ad4c1a9389f55e31a9c1cf35a4dae90e2f73b7bd033c3df7bc84b7a11f0badc93175f72e01a0b164c25d90852b703f74c826b63cd476", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bd98791fb0e094514d70b0eb5f2c39814b56abc3ecb5b51673b2ec8787338dc99b1db8b3028dcf39e2d9fa7c88611db5ba36e8535f53b1e1729eae978d11121"} build_url:https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011179 build_url: 'https://queue.taskcluster.net/v1/task/XTj355s8ThieXooLGQ8jKA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f54eea0ab2234e08476481a864423094a1cbc3a73a6b6018b10c45705491fdfe95265b88556227acf5160b36616a4d00f026cb795b51bff0f87c2cc4125d01c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0eee8c83618a4a7c3821ad4c1a9389f55e31a9c1cf35a4dae90e2f73b7bd033c3df7bc84b7a11f0badc93175f72e01a0b164c25d90852b703f74c826b63cd476", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3bd98791fb0e094514d70b0eb5f2c39814b56abc3ecb5b51673b2ec8787338dc99b1db8b3028dcf39e2d9fa7c88611db5ba36e8535f53b1e1729eae978d11121"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 04:34:10.377971) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 04:34:10.378294) ========= 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.7LoVr62NCY/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.evYvzT4g1f/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.005093 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 04:34:10.426906) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 04:34:10.427236) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 04:34:10.427534) ========= ========= Total master_lag: 0.25 =========