builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0055 starttime: 1473065523.89 results: success (0) buildid: 20160904235820 builduid: 3adfd84831514e76ba8af2930361ffa2 revision: 03056868056e209bb08eece43061972c66ef4742 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-05 01:52:03.886503) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-05 01:52:03.886871) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-05 01:52:03.887135) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-05 01:52:04.064867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 01:52:04.065131) ========= 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.1XIZcbIuJO/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.RVIVLw7MSH/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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-05 01:52:04-- 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% 32.9M=0s 2016-09-05 01:52:04 (32.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.154120 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 01:52:04.245430) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 01:52:04.246162) ========= 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.1XIZcbIuJO/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.RVIVLw7MSH/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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.027613 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 01:52:04.298348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-05 01:52:04.298705) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 03056868056e209bb08eece43061972c66ef4742 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 03056868056e209bb08eece43061972c66ef4742 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1XIZcbIuJO/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.RVIVLw7MSH/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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-05 01:52:04,379 truncating revision to first 12 chars 2016-09-05 01:52:04,379 Setting DEBUG logging. 2016-09-05 01:52:04,379 attempt 1/10 2016-09-05 01:52:04,379 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/03056868056e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-05 01:52:05,464 unpacking tar archive at: autoland-03056868056e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.366130 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-05 01:52:05.686858) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-05 01:52:05.687129) ========= script_repo_revision: 03056868056e209bb08eece43061972c66ef4742 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-05 01:52:05.687502) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-05 01:52:05.687779) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-05 01:52:05.706073) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-05 01:52:05.706351) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1XIZcbIuJO/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.RVIVLw7MSH/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 01:52:05 INFO - MultiFileLogger online at 20160905 01:52:05 in /builds/slave/test 01:52:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 01:52:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:52:05 INFO - {'append_to_log': False, 01:52:05 INFO - 'base_work_dir': '/builds/slave/test', 01:52:05 INFO - 'blob_upload_branch': 'Autoland', 01:52:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:52:05 INFO - 'branch': 'Autoland', 01:52:05 INFO - 'buildbot_json_path': 'buildprops.json', 01:52:05 INFO - 'config_files': ('talos/mac_config.py',), 01:52:05 INFO - 'default_actions': ('clobber', 01:52:05 INFO - 'read-buildbot-config', 01:52:05 INFO - 'download-and-extract', 01:52:05 INFO - 'populate-webroot', 01:52:05 INFO - 'create-virtualenv', 01:52:05 INFO - 'install', 01:52:05 INFO - 'run-tests'), 01:52:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:52:05 INFO - 'download_minidump_stackwalk': True, 01:52:05 INFO - 'download_symbols': 'ondemand', 01:52:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:52:05 INFO - 'tooltool.py': '/tools/tooltool.py', 01:52:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:52:05 INFO - '/tools/misc-python/virtualenv.py')}, 01:52:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:52:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:52:05 INFO - 'installer_path': 'installer.exe', 01:52:05 INFO - 'log_level': 'info', 01:52:05 INFO - 'log_name': 'talos', 01:52:05 INFO - 'log_to_console': True, 01:52:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:52:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:52:05 INFO - 'opt_config_files': (), 01:52:05 INFO - 'pip_index': False, 01:52:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:52:05 INFO - 'cmd': ('bash', 01:52:05 INFO - '-c', 01:52:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:52:05 INFO - 'enabled': True, 01:52:05 INFO - 'halt_on_failure': False, 01:52:05 INFO - 'name': 'check_screen_resolution'},), 01:52:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:52:05 INFO - 'cmd': ('bash', 01:52:05 INFO - '-c', 01:52:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:52:05 INFO - 'enabled': True, 01:52:05 INFO - 'halt_on_failure': False, 01:52:05 INFO - 'name': 'check_screen_resolution'},), 01:52:05 INFO - 'run_cmd_checks_enabled': True, 01:52:05 INFO - 'sps_profile': False, 01:52:05 INFO - 'sps_profile_interval': 0, 01:52:05 INFO - 'suite': 'dromaeojs-e10s', 01:52:05 INFO - 'system_bits': '32', 01:52:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:52:05 INFO - 'title': 't-yosemite-r7-0055', 01:52:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:52:05 INFO - 'use_talos_json': True, 01:52:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:52:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:52:05 INFO - 'work_dir': 'build'} 01:52:05 INFO - [mozharness: 2016-09-05 08:52:05.905035Z] Running clobber step. 01:52:05 INFO - Running pre-action listener: _resource_record_pre_action 01:52:05 INFO - Running main action method: clobber 01:52:05 INFO - rmtree: /builds/slave/test/build 01:52:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:52:07 INFO - Running post-action listener: _resource_record_post_action 01:52:07 INFO - [mozharness: 2016-09-05 08:52:07.995727Z] Finished clobber step (success) 01:52:07 INFO - [mozharness: 2016-09-05 08:52:07.995894Z] Running read-buildbot-config step. 01:52:07 INFO - Running pre-action listener: _resource_record_pre_action 01:52:07 INFO - Running main action method: read_buildbot_config 01:52:07 INFO - Using buildbot properties: 01:52:07 INFO - { 01:52:07 INFO - "project": "", 01:52:07 INFO - "product": "firefox", 01:52:07 INFO - "script_repo_revision": "production", 01:52:07 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 01:52:07 INFO - "repository": "", 01:52:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 01:52:07 INFO - "buildid": "20160904235820", 01:52:07 INFO - "pgo_build": "False", 01:52:07 INFO - "basedir": "/builds/slave/test", 01:52:07 INFO - "buildnumber": 175, 01:52:07 INFO - "slavename": "t-yosemite-r7-0055", 01:52:07 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 01:52:07 INFO - "platform": "yosemite_r7", 01:52:07 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 01:52:07 INFO - "branch": "autoland", 01:52:07 INFO - "repo_path": "integration/autoland", 01:52:07 INFO - "revision": "03056868056e209bb08eece43061972c66ef4742", 01:52:07 INFO - "stage_platform": "macosx64", 01:52:07 INFO - "builduid": "3adfd84831514e76ba8af2930361ffa2", 01:52:07 INFO - "slavebuilddir": "test" 01:52:07 INFO - } 01:52:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 01:52:07 INFO - Running post-action listener: _resource_record_post_action 01:52:07 INFO - [mozharness: 2016-09-05 08:52:07.999050Z] Finished read-buildbot-config step (success) 01:52:07 INFO - [mozharness: 2016-09-05 08:52:07.999187Z] Running download-and-extract step. 01:52:07 INFO - Running pre-action listener: _resource_record_pre_action 01:52:07 INFO - Running main action method: download_and_extract 01:52:07 INFO - mkdir: /builds/slave/test/build/tests 01:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:07 INFO - trying https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 01:52:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/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 01:52:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/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 01:52:09 INFO - Downloaded 1199 bytes. 01:52:09 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 01:52:09 INFO - Using the following test package requirements: 01:52:09 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 01:52:09 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:09 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 01:52:09 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:09 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 01:52:09 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:09 INFO - u'jsshell-mac.zip'], 01:52:09 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:09 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 01:52:09 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 01:52:09 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:09 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 01:52:09 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:09 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 01:52:09 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:09 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 01:52:09 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:52:09 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 01:52:09 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:52:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 01:52:09 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 01:52:16 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 01:52:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 01:52:16 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 01:52:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 01:52:24 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 01:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:24 INFO - trying https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:52:24 INFO - Downloading https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 01:52:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/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 01:52:27 INFO - Downloaded 111511855 bytes. 01:52:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:52:27 INFO - mkdir: /builds/slave/test/properties 01:52:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:52:27 INFO - Writing to file /builds/slave/test/properties/build_url 01:52:27 INFO - Contents: 01:52:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:52:28 INFO - Running post-action listener: _resource_record_post_action 01:52:28 INFO - Running post-action listener: set_extra_try_arguments 01:52:28 INFO - [mozharness: 2016-09-05 08:52:28.148743Z] Finished download-and-extract step (success) 01:52:28 INFO - [mozharness: 2016-09-05 08:52:28.148873Z] Running populate-webroot step. 01:52:28 INFO - Running pre-action listener: _resource_record_pre_action 01:52:28 INFO - Running main action method: populate_webroot 01:52:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:52:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:52:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:52:28 INFO - '--tppagecycles', 01:52:28 INFO - '7'], 01:52:28 INFO - 'tests': ['tsvgm']}}, 01:52:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:52:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:52:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:52:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:52:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:52:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:52:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:28 INFO - 'talos_options': ['--disable-e10s'], 01:52:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:52:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:52:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:28 INFO - 'talos_options': ['--disable-e10s'], 01:52:28 INFO - 'tests': ['damp', 'tps']}, 01:52:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:28 INFO - 'tests': ['damp', 'tps']}, 01:52:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:52:28 INFO - 'tests': ['dromaeo_dom']}, 01:52:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:52:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:52:28 INFO - 'tests': ['basic_compositor_video']}, 01:52:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:52:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:52:28 INFO - 'tests': ['a11yr', 01:52:28 INFO - 'ts_paint', 01:52:28 INFO - 'tpaint', 01:52:28 INFO - 'sessionrestore', 01:52:28 INFO - 'sessionrestore_no_auto_restore', 01:52:28 INFO - 'tabpaint']}, 01:52:28 INFO - 'other-e10s': {'tests': ['a11yr', 01:52:28 INFO - 'ts_paint', 01:52:28 INFO - 'tpaint', 01:52:28 INFO - 'sessionrestore', 01:52:28 INFO - 'sessionrestore_no_auto_restore', 01:52:28 INFO - 'tabpaint']}, 01:52:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:52:28 INFO - 'ts_paint', 01:52:28 INFO - 'tpaint', 01:52:28 INFO - 'sessionrestore', 01:52:28 INFO - 'sessionrestore_no_auto_restore', 01:52:28 INFO - 'tabpaint']}, 01:52:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:52:28 INFO - 'ts_paint', 01:52:28 INFO - 'tpaint', 01:52:28 INFO - 'sessionrestore', 01:52:28 INFO - 'sessionrestore_no_auto_restore', 01:52:28 INFO - 'tabpaint']}, 01:52:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:52:28 INFO - 'tests': ['a11yr', 01:52:28 INFO - 'ts_paint', 01:52:28 INFO - 'tpaint', 01:52:28 INFO - 'sessionrestore', 01:52:28 INFO - 'sessionrestore_no_auto_restore', 01:52:28 INFO - 'tabpaint']}, 01:52:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:52:28 INFO - 'tests': ['a11yr', 01:52:28 INFO - 'ts_paint', 01:52:28 INFO - 'tpaint', 01:52:28 INFO - 'sessionrestore', 01:52:28 INFO - 'sessionrestore_no_auto_restore', 01:52:28 INFO - 'tabpaint']}, 01:52:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:52:28 INFO - 'tests': ['tsvgx', 01:52:28 INFO - 'tsvgr_opacity', 01:52:28 INFO - 'tart', 01:52:28 INFO - 'tscrollx', 01:52:28 INFO - 'cart']}, 01:52:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:52:28 INFO - 'tsvgr_opacity', 01:52:28 INFO - 'tart', 01:52:28 INFO - 'tscrollx', 01:52:28 INFO - 'cart']}, 01:52:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:28 INFO - 'talos_options': ['--disable-e10s'], 01:52:28 INFO - 'tests': ['tp5o']}, 01:52:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:28 INFO - 'tests': ['tp5o']}, 01:52:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:28 INFO - 'talos_options': ['--disable-e10s', 01:52:28 INFO - '--xperf_path', 01:52:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:52:28 INFO - 'tests': ['tp5n']}, 01:52:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:28 INFO - 'talos_options': ['--xperf_path', 01:52:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:52:28 INFO - 'tests': ['tp5n']}}, 01:52:28 INFO - 'talos.zip': {'path': '', 01:52:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:52:28 INFO - Running post-action listener: _resource_record_post_action 01:52:28 INFO - [mozharness: 2016-09-05 08:52:28.167788Z] Finished populate-webroot step (success) 01:52:28 INFO - [mozharness: 2016-09-05 08:52:28.167918Z] Running create-virtualenv step. 01:52:28 INFO - Running pre-action listener: _resource_record_pre_action 01:52:28 INFO - Running main action method: create_virtualenv 01:52:28 INFO - Creating virtualenv /builds/slave/test/build/venv 01:52:28 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 01:52:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:52:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:52:28 INFO - Using real prefix '/tools/python27' 01:52:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:52:29 INFO - Installing distribute.............................................................................................................................................................................................done. 01:52:31 INFO - Installing pip.................done. 01:52:31 INFO - Return code: 0 01:52:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:31 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 0x100fe0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101075618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c1da49e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f44d30>, '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 0x100ca9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f3cb20>, '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.1XIZcbIuJO/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.RVIVLw7MSH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 01:52:31 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 01:52:31 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 01:52:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1XIZcbIuJO/Render', 01:52:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:31 INFO - 'HOME': '/Users/cltbld', 01:52:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:31 INFO - 'LOGNAME': 'cltbld', 01:52:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:31 INFO - 'MOZ_NO_REMOTE': '1', 01:52:31 INFO - 'NO_EM_RESTART': '1', 01:52:31 INFO - 'PAGER': '/bin/cat', 01:52:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:31 INFO - 'PWD': '/builds/slave/test', 01:52:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:31 INFO - 'SHELL': '/bin/bash', 01:52:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RVIVLw7MSH/Listeners', 01:52:31 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 01:52:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:31 INFO - 'USER': 'cltbld', 01:52:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:31 INFO - 'XPC_FLAGS': '0x0', 01:52:31 INFO - 'XPC_SERVICE_NAME': '0', 01:52:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:32 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 01:52:32 INFO - Cleaning up... 01:52:32 INFO - Return code: 0 01:52:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:32 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 0x100fe0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101075618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c1da49e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f44d30>, '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 0x100ca9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f3cb20>, '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.1XIZcbIuJO/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.RVIVLw7MSH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 01:52:32 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 01:52:32 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 01:52:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1XIZcbIuJO/Render', 01:52:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:32 INFO - 'HOME': '/Users/cltbld', 01:52:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:32 INFO - 'LOGNAME': 'cltbld', 01:52:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:32 INFO - 'MOZ_NO_REMOTE': '1', 01:52:32 INFO - 'NO_EM_RESTART': '1', 01:52:32 INFO - 'PAGER': '/bin/cat', 01:52:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:32 INFO - 'PWD': '/builds/slave/test', 01:52:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:32 INFO - 'SHELL': '/bin/bash', 01:52:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RVIVLw7MSH/Listeners', 01:52:32 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 01:52:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:32 INFO - 'USER': 'cltbld', 01:52:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:32 INFO - 'XPC_FLAGS': '0x0', 01:52:32 INFO - 'XPC_SERVICE_NAME': '0', 01:52:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:32 INFO - Downloading/unpacking psutil>=3.1.1 01:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:52:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:52:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:52:35 INFO - Installing collected packages: psutil 01:52:35 INFO - Running setup.py install for psutil 01:52:36 INFO - building 'psutil._psutil_osx' extension 01:52:36 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 01:52:36 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 01:52:36 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 01:52:36 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 01:52:36 INFO - building 'psutil._psutil_posix' extension 01:52:36 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 01:52:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:52:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:52:36 INFO - ^ 01:52:36 INFO - 1 warning generated. 01:52:36 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 01:52:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:52:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:52:37 INFO - Successfully installed psutil 01:52:37 INFO - Cleaning up... 01:52:37 INFO - Return code: 0 01:52:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:52: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')]} 01:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52: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')]} 01:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100fe0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101075618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c1da49e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f44d30>, '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 0x100ca9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f3cb20>, '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.1XIZcbIuJO/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.RVIVLw7MSH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 01:52: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:52: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 mozsystemmonitor==0.3 01:52:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1XIZcbIuJO/Render', 01:52:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:37 INFO - 'HOME': '/Users/cltbld', 01:52:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:37 INFO - 'LOGNAME': 'cltbld', 01:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:37 INFO - 'MOZ_NO_REMOTE': '1', 01:52:37 INFO - 'NO_EM_RESTART': '1', 01:52:37 INFO - 'PAGER': '/bin/cat', 01:52:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:37 INFO - 'PWD': '/builds/slave/test', 01:52:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:37 INFO - 'SHELL': '/bin/bash', 01:52:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RVIVLw7MSH/Listeners', 01:52:37 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 01:52:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:37 INFO - 'USER': 'cltbld', 01:52:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:37 INFO - 'XPC_FLAGS': '0x0', 01:52:37 INFO - 'XPC_SERVICE_NAME': '0', 01:52:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:52:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:52:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:52:39 INFO - Installing collected packages: mozsystemmonitor 01:52:39 INFO - Running setup.py install for mozsystemmonitor 01:52:40 INFO - Successfully installed mozsystemmonitor 01:52:40 INFO - Cleaning up... 01:52:40 INFO - Return code: 0 01:52:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:52:40 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')]} 01:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:40 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')]} 01:52:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:40 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 0x100fe0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101075618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c1da49e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f44d30>, '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 0x100ca9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f3cb20>, '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.1XIZcbIuJO/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.RVIVLw7MSH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 01:52:40 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 01:52:40 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 01:52:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1XIZcbIuJO/Render', 01:52:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:40 INFO - 'HOME': '/Users/cltbld', 01:52:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:40 INFO - 'LOGNAME': 'cltbld', 01:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:40 INFO - 'MOZ_NO_REMOTE': '1', 01:52:40 INFO - 'NO_EM_RESTART': '1', 01:52:40 INFO - 'PAGER': '/bin/cat', 01:52:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:40 INFO - 'PWD': '/builds/slave/test', 01:52:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:40 INFO - 'SHELL': '/bin/bash', 01:52:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RVIVLw7MSH/Listeners', 01:52:40 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 01:52:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:40 INFO - 'USER': 'cltbld', 01:52:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:40 INFO - 'XPC_FLAGS': '0x0', 01:52:40 INFO - 'XPC_SERVICE_NAME': '0', 01:52:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:40 INFO - Downloading/unpacking jsonschema==2.5.1 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:52:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:52:42 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 01:52:42 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 01:52:42 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 01:52:42 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 01:52:42 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 01:52:42 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 01:52:43 INFO - Downloading functools32-3.2.3-2.tar.gz 01:52:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:52:43 INFO - warning: no files found matching '*.txt' 01:52:43 INFO - no previously-included directories found matching 'build' 01:52:43 INFO - no previously-included directories found matching 'dist' 01:52:43 INFO - no previously-included directories found matching '.git*' 01:52:43 INFO - Installing collected packages: jsonschema, functools32 01:52:43 INFO - Running setup.py install for functools32 01:52:43 INFO - warning: no files found matching '*.txt' 01:52:43 INFO - no previously-included directories found matching 'build' 01:52:43 INFO - no previously-included directories found matching 'dist' 01:52:43 INFO - no previously-included directories found matching '.git*' 01:52:43 INFO - Successfully installed jsonschema functools32 01:52:43 INFO - Cleaning up... 01:52:43 INFO - Return code: 0 01:52:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100fe0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101075618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c1da49e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f44d30>, '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 0x100ca9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f3cb20>, '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.1XIZcbIuJO/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.RVIVLw7MSH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 01:52:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:52:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:52:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1XIZcbIuJO/Render', 01:52:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:43 INFO - 'HOME': '/Users/cltbld', 01:52:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:43 INFO - 'LOGNAME': 'cltbld', 01:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:43 INFO - 'MOZ_NO_REMOTE': '1', 01:52:43 INFO - 'NO_EM_RESTART': '1', 01:52:43 INFO - 'PAGER': '/bin/cat', 01:52:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:43 INFO - 'PWD': '/builds/slave/test', 01:52:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:43 INFO - 'SHELL': '/bin/bash', 01:52:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RVIVLw7MSH/Listeners', 01:52:43 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 01:52:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:43 INFO - 'USER': 'cltbld', 01:52:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:43 INFO - 'XPC_FLAGS': '0x0', 01:52:43 INFO - 'XPC_SERVICE_NAME': '0', 01:52:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:52:43 INFO - Cleaning up... 01:52:43 INFO - Return code: 0 01:52:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100fe0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101075618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c1da49e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f44d30>, '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 0x100ca9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f3cb20>, '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.1XIZcbIuJO/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.RVIVLw7MSH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 01:52:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:52:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:52:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1XIZcbIuJO/Render', 01:52:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:43 INFO - 'HOME': '/Users/cltbld', 01:52:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:43 INFO - 'LOGNAME': 'cltbld', 01:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:43 INFO - 'MOZ_NO_REMOTE': '1', 01:52:43 INFO - 'NO_EM_RESTART': '1', 01:52:43 INFO - 'PAGER': '/bin/cat', 01:52:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:43 INFO - 'PWD': '/builds/slave/test', 01:52:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:43 INFO - 'SHELL': '/bin/bash', 01:52:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RVIVLw7MSH/Listeners', 01:52:43 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 01:52:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:43 INFO - 'USER': 'cltbld', 01:52:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:43 INFO - 'XPC_FLAGS': '0x0', 01:52:43 INFO - 'XPC_SERVICE_NAME': '0', 01:52:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:44 INFO - Downloading/unpacking blobuploader==1.2.4 01:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:46 INFO - Downloading blobuploader-1.2.4.tar.gz 01:52:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:52:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:52:46 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 01:52:46 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 01:52:46 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 01:52:46 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 01:52:46 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 01:52:46 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 01:52:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:52:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:47 INFO - Downloading docopt-0.6.1.tar.gz 01:52:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:52:47 INFO - Installing collected packages: blobuploader, requests, docopt 01:52:47 INFO - Running setup.py install for blobuploader 01:52:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:52:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:52:48 INFO - Running setup.py install for requests 01:52:48 INFO - Running setup.py install for docopt 01:52:48 INFO - Successfully installed blobuploader requests docopt 01:52:48 INFO - Cleaning up... 01:52:48 INFO - Return code: 0 01:52:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:52:48 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')]} 01:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:48 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')]} 01:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:48 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 0x100fe0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101075618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c1da49e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f44d30>, '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 0x100ca9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f3cb20>, '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.1XIZcbIuJO/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.RVIVLw7MSH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 01:52:48 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 01:52:48 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 01:52:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1XIZcbIuJO/Render', 01:52:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:48 INFO - 'HOME': '/Users/cltbld', 01:52:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:48 INFO - 'LOGNAME': 'cltbld', 01:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:48 INFO - 'MOZ_NO_REMOTE': '1', 01:52:48 INFO - 'NO_EM_RESTART': '1', 01:52:48 INFO - 'PAGER': '/bin/cat', 01:52:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:48 INFO - 'PWD': '/builds/slave/test', 01:52:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:48 INFO - 'SHELL': '/bin/bash', 01:52:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RVIVLw7MSH/Listeners', 01:52:48 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 01:52:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:48 INFO - 'USER': 'cltbld', 01:52:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:48 INFO - 'XPC_FLAGS': '0x0', 01:52:48 INFO - 'XPC_SERVICE_NAME': '0', 01:52:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:52:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-yJMKSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:52:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-5tWKeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:52:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-tBN3Wq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:52:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-ecjVey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:52:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-PX52uD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:52:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-OShrlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:52:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-SjHLKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:52:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-NL4OsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:52:49 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-lklJPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:52:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-vVzsVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:52:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-_CmpdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:52:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-mc9yxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:52:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-unkxMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:52:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-HXO39k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-zIW9VU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:52:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-pL3Po7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:52:50 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-LCJPzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:52:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:52:50 INFO - Running setup.py install for manifestparser 01:52:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:52:51 INFO - Running setup.py install for mozcrash 01:52:51 INFO - Running setup.py install for mozdebug 01:52:51 INFO - Running setup.py install for mozdevice 01:52:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:52:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:52:51 INFO - Running setup.py install for mozfile 01:52:51 INFO - Running setup.py install for mozhttpd 01:52:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:52:51 INFO - Running setup.py install for mozinfo 01:52:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:52:51 INFO - Running setup.py install for mozInstall 01:52:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:52:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:52:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:52:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:52:52 INFO - Running setup.py install for mozleak 01:52:52 INFO - Running setup.py install for mozlog 01:52:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:52:52 INFO - Running setup.py install for moznetwork 01:52:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:52:52 INFO - Running setup.py install for mozprocess 01:52:52 INFO - Running setup.py install for mozprofile 01:52:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:52:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:52:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:52:52 INFO - Running setup.py install for mozrunner 01:52:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:52:53 INFO - Running setup.py install for mozscreenshot 01:52:53 INFO - Running setup.py install for moztest 01:52:53 INFO - Running setup.py install for mozversion 01:52:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:52:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:52:53 INFO - Cleaning up... 01:52:53 INFO - Return code: 0 01:52:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:52:53 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')]} 01:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:53 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')]} 01:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:53 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 0x100fe0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101075618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c1da49e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f44d30>, '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 0x100ca9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f3cb20>, '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.1XIZcbIuJO/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.RVIVLw7MSH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 01:52:53 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 01:52:53 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 01:52:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1XIZcbIuJO/Render', 01:52:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:53 INFO - 'HOME': '/Users/cltbld', 01:52:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:53 INFO - 'LOGNAME': 'cltbld', 01:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:53 INFO - 'MOZ_NO_REMOTE': '1', 01:52:53 INFO - 'NO_EM_RESTART': '1', 01:52:53 INFO - 'PAGER': '/bin/cat', 01:52:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:53 INFO - 'PWD': '/builds/slave/test', 01:52:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:53 INFO - 'SHELL': '/bin/bash', 01:52:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RVIVLw7MSH/Listeners', 01:52:53 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 01:52:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:53 INFO - 'USER': 'cltbld', 01:52:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:53 INFO - 'XPC_FLAGS': '0x0', 01:52:53 INFO - 'XPC_SERVICE_NAME': '0', 01:52:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:52:53 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-6oAZLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:52:53 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)) 01:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:52:53 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-DNzlDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:52:53 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)) 01:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:52:53 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-D8H3xj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:52:54 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)) 01:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:52:54 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-PUrj0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:52:54 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)) 01:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:52:54 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-Gpyj_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:52:54 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)) 01:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:52:54 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-BUnpl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:52:54 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)) 01:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:52:54 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-INKPOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:52:54 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)) 01:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:52:54 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-8XgZ4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:52:54 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)) 01:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:52:54 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-YrwElI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:52:54 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)) 01:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:52:54 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-cCUaPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:52:54 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)) 01:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:52:54 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-uXLc7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:52:54 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)) 01:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:52:54 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-hT8qCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:52:55 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)) 01:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:52:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-lBA74y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:52:55 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)) 01:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:52:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-iw9nTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:52:55 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)) 01:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-jVkpGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:55 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)) 01:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:52:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-kjYUXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:52:55 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)) 01:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:52:55 INFO - Running setup.py (path:/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/pip-9QTrWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:52:55 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)) 01:52:55 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)) 01:52:55 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)) 01:52:55 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)) 01:52:55 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)) 01:52:55 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)) 01:52:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:58 INFO - Downloading blessings-1.6.tar.gz 01:52:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:52:58 INFO - Installing collected packages: blessings 01:52:58 INFO - Running setup.py install for blessings 01:52:58 INFO - Successfully installed blessings 01:52:58 INFO - Cleaning up... 01:52:58 INFO - Return code: 0 01:52:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:52:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:52:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:52:58 INFO - Reading from file tmpfile_stdout 01:52:58 INFO - Current package versions: 01:52:58 INFO - blessings == 1.6 01:52:58 INFO - blobuploader == 1.2.4 01:52:58 INFO - docopt == 0.6.1 01:52:58 INFO - functools32 == 3.2.3-2 01:52:58 INFO - jsonschema == 2.5.1 01:52:58 INFO - manifestparser == 1.1 01:52:58 INFO - mozInstall == 1.12 01:52:58 INFO - mozcrash == 0.17 01:52:58 INFO - mozdebug == 0.1 01:52:58 INFO - mozdevice == 0.48 01:52:58 INFO - mozfile == 1.2 01:52:58 INFO - mozhttpd == 0.7 01:52:58 INFO - mozinfo == 0.9 01:52:58 INFO - mozleak == 0.1 01:52:58 INFO - mozlog == 3.3 01:52:58 INFO - moznetwork == 0.27 01:52:58 INFO - mozprocess == 0.23 01:52:58 INFO - mozprofile == 0.28 01:52:58 INFO - mozrunner == 6.12 01:52:58 INFO - mozscreenshot == 0.1 01:52:58 INFO - mozsystemmonitor == 0.3 01:52:58 INFO - moztest == 0.7 01:52:58 INFO - mozversion == 1.4 01:52:58 INFO - psutil == 3.1.1 01:52:58 INFO - requests == 1.2.3 01:52:58 INFO - wsgiref == 0.1.2 01:52:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:52:58 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')]} 01:52:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:58 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')]} 01:52:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:58 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 0x100fe0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101075618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c1da49e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f44d30>, '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 0x100ca9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f3cb20>, '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.1XIZcbIuJO/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.RVIVLw7MSH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 01:52:58 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 01:52:58 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 01:52:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1XIZcbIuJO/Render', 01:52:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:58 INFO - 'HOME': '/Users/cltbld', 01:52:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:58 INFO - 'LOGNAME': 'cltbld', 01:52:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:58 INFO - 'MOZ_NO_REMOTE': '1', 01:52:58 INFO - 'NO_EM_RESTART': '1', 01:52:58 INFO - 'PAGER': '/bin/cat', 01:52:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:58 INFO - 'PWD': '/builds/slave/test', 01:52:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:58 INFO - 'SHELL': '/bin/bash', 01:52:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RVIVLw7MSH/Listeners', 01:52:58 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 01:52:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:58 INFO - 'USER': 'cltbld', 01:52:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:58 INFO - 'XPC_FLAGS': '0x0', 01:52:58 INFO - 'XPC_SERVICE_NAME': '0', 01:52:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:58 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)) 01:52:58 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)) 01:52:58 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)) 01:52:58 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)) 01:52:58 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)) 01:52:58 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)) 01:52:58 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)) 01:52:58 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)) 01:52:58 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)) 01:52:58 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)) 01:52:58 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)) 01:52:58 INFO - Cleaning up... 01:52:58 INFO - Return code: 0 01:52:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:52:58 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')]} 01:52:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:59 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')]} 01:52:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:59 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 0x100fe0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101075618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c1da49e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f44d30>, '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 0x100ca9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f3cb20>, '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.1XIZcbIuJO/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.RVIVLw7MSH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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 01:52:59 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 01:52:59 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 01:52:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1XIZcbIuJO/Render', 01:52:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:59 INFO - 'HOME': '/Users/cltbld', 01:52:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:59 INFO - 'LOGNAME': 'cltbld', 01:52:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:59 INFO - 'MOZ_NO_REMOTE': '1', 01:52:59 INFO - 'NO_EM_RESTART': '1', 01:52:59 INFO - 'PAGER': '/bin/cat', 01:52:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:59 INFO - 'PWD': '/builds/slave/test', 01:52:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:59 INFO - 'SHELL': '/bin/bash', 01:52:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RVIVLw7MSH/Listeners', 01:52:59 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 01:52:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:59 INFO - 'USER': 'cltbld', 01:52:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:59 INFO - 'XPC_FLAGS': '0x0', 01:52:59 INFO - 'XPC_SERVICE_NAME': '0', 01:52:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:52:59 INFO - Cleaning up... 01:52:59 INFO - Return code: 0 01:52:59 INFO - Running post-action listener: _resource_record_post_action 01:52:59 INFO - Running post-action listener: _start_resource_monitoring 01:52:59 INFO - Starting resource monitoring. 01:52:59 INFO - [mozharness: 2016-09-05 08:52:59.293495Z] Finished create-virtualenv step (success) 01:52:59 INFO - [mozharness: 2016-09-05 08:52:59.294481Z] Running install step. 01:52:59 INFO - Running pre-action listener: _resource_record_pre_action 01:52:59 INFO - Running main action method: install 01:52:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:52:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:52:59 INFO - Reading from file tmpfile_stdout 01:52:59 INFO - Detecting whether we're running mozinstall >=1.0... 01:52:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:52:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:52:59 INFO - Reading from file tmpfile_stdout 01:52:59 INFO - Output received: 01:52:59 INFO - Usage: mozinstall [options] installer 01:52:59 INFO - Options: 01:52:59 INFO - -h, --help show this help message and exit 01:52:59 INFO - -d DEST, --destination=DEST 01:52:59 INFO - Directory to install application into. [default: 01:52:59 INFO - "/builds/slave/test"] 01:52:59 INFO - --app=APP Application being installed. [default: firefox] 01:52:59 INFO - mkdir: /builds/slave/test/build/application 01:52:59 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'] 01:52:59 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 01:53:34 INFO - Reading from file tmpfile_stdout 01:53:34 INFO - Output received: 01:53:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:53:34 INFO - Running post-action listener: _resource_record_post_action 01:53:34 INFO - [mozharness: 2016-09-05 08:53:34.078989Z] Finished install step (success) 01:53:34 INFO - [mozharness: 2016-09-05 08:53:34.079124Z] Running run-tests step. 01:53:34 INFO - Running pre-action listener: _resource_record_pre_action 01:53:34 INFO - Running main action method: run_tests 01:53:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:53:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:53:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:53:34 INFO - 2016-09-05 01:53:34.093 screenresolution[1655:14693] starting screenresolution argv=screenresolution get 01:53:34 INFO - 2016-09-05 01:53:34.096 screenresolution[1655:14693] Display 0: 1600x1200x32@60 01:53:34 INFO - 2016-09-05 01:53:34.101 screenresolution[1656:14695] starting screenresolution argv=screenresolution list 01:53:34 INFO - Available Modes on Display 0 01:53:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:53:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:53:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:53:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:53:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:53:34 INFO - Intel Iris: 01:53:34 INFO - Chipset Model: Intel Iris 01:53:34 INFO - Type: GPU 01:53:34 INFO - Bus: Built-In 01:53:34 INFO - VRAM (Dynamic, Max): 1536 MB 01:53:34 INFO - Vendor: Intel (0x8086) 01:53:34 INFO - Device ID: 0x0a2e 01:53:34 INFO - Revision ID: 0x0009 01:53:34 INFO - Displays: 01:53:34 INFO - Display: 01:53:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:53:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:53:34 INFO - Display Serial Number: 1600x1200 60 01:53:34 INFO - Main Display: Yes 01:53:34 INFO - Mirror: Off 01:53:34 INFO - Online: Yes 01:53:34 INFO - Rotation: Supported 01:53:34 INFO - Return code: 0 01:53:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:53:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:53:34 INFO - Python 2.7.3 01:53:34 INFO - Return code: 0 01:53:34 INFO - grabbing minidump binary from tooltool 01:53:34 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')]} 01:53:34 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 0x100f44d30>, '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 0x100ca9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f3cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:53:34 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 01:53:34 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 01:53:34 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 01:53:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:53:34 INFO - Return code: 0 01:53:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:53:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:53:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:53:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:53:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:53:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 01:53:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0055 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 01:53:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1XIZcbIuJO/Render', 01:53:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:53:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:53:34 INFO - 'HOME': '/Users/cltbld', 01:53:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:53:34 INFO - 'LOGNAME': 'cltbld', 01:53:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:53:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:53:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:34 INFO - 'MOZ_NO_REMOTE': '1', 01:53:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:53:34 INFO - 'NO_EM_RESTART': '1', 01:53:34 INFO - 'PAGER': '/bin/cat', 01:53:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:53:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:34 INFO - 'PWD': '/builds/slave/test', 01:53:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:53:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:53:34 INFO - 'SHELL': '/bin/bash', 01:53:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RVIVLw7MSH/Listeners', 01:53:34 INFO - 'TMPDIR': '/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/', 01:53:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:53:34 INFO - 'USER': 'cltbld', 01:53:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:53:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:53:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:34 INFO - 'XPC_FLAGS': '0x0', 01:53:34 INFO - 'XPC_SERVICE_NAME': '0', 01:53:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:53:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 01:53:34 INFO - mozversion application_buildid: 20160904235820 01:53:34 INFO - mozversion application_changeset: 03056868056e209bb08eece43061972c66ef4742 01:53:34 INFO - mozversion application_display_name: Nightly 01:53:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:53:34 INFO - mozversion application_name: Firefox 01:53:34 INFO - mozversion application_remotingname: firefox 01:53:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 01:53:34 INFO - mozversion application_vendor: Mozilla 01:53:34 INFO - mozversion application_version: 51.0a1 01:53:34 INFO - mozversion platform_buildid: 20160904235820 01:53:34 INFO - mozversion platform_changeset: 03056868056e209bb08eece43061972c66ef4742 01:53:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 01:53:34 INFO - mozversion platform_version: 51.0a1 01:53:34 INFO - using testdate: 1473065614 01:53:34 INFO - actual date: 1473065614 01:53:34 INFO - starting webserver on 'localhost:49271' 01:53:34 INFO - SUITE-START | Running 2 tests 01:53:34 INFO - TEST-START | dromaeo_css 01:53:34 INFO - operating with platform_type : mac_ 01:53:34 INFO - Initialising browser for dromaeo_css test... 01:53:34 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpcRDWio/profile http://localhost:49271/getInfo.html) 01:53:37 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 01:53:37 INFO - PROCESS | 1666 | colorDepth:24 01:53:37 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 01:53:37 INFO - PROCESS | 1666 | __metrics 01:53:37 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 01:53:38 INFO - TEST-INFO | 1666: exit 0 01:53:38 INFO - Browser initialized. 01:53:38 INFO - Running cycle 1/1 for dromaeo_css test... 01:53:38 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpcRDWio/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:53:50 INFO - PROCESS | 1670 | RSS: Main: 243957760 01:53:50 INFO - PROCESS | 1670 | 01:54:39 INFO - PROCESS | 1670 | 1473065679964 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] 01:56:52 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 01:56:53 INFO - PROCESS | 1670 | RSS: Main: 266522624 01:56:53 INFO - PROCESS | 1670 | 01:59:56 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 01:59:56 INFO - PROCESS | 1670 | RSS: Main: 273735680 01:59:56 INFO - PROCESS | 1670 | 02:02:58 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 02:02:59 INFO - PROCESS | 1670 | RSS: Main: 275980288 02:02:59 INFO - PROCESS | 1670 | 02:06:01 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 02:06:01 INFO - PROCESS | 1670 | RSS: Main: 272609280 02:06:01 INFO - PROCESS | 1670 | 02:09:04 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 02:09:05 INFO - PROCESS | 1670 | RSS: Main: 277803008 02:09:05 INFO - PROCESS | 1670 | 02:12:07 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 02:12:08 INFO - PROCESS | 1670 | RSS: Main: 279412736 02:12:08 INFO - PROCESS | 1670 | 02:12:08 INFO - PROCESS | 1670 | __start_tp_report 02:12:08 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 02:12:08 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 02:12:08 INFO - PROCESS | 1670 | |i|pagename|runs| 02:12:08 INFO - PROCESS | 1670 | |0;dojo.html;2882.7549658279045;3029.2578318312003;3434.2101316697153;3496.213351995801;3513.1392808761857;4828.010257897132;5334.226537152111;5717.285339332583;5806.622569532978;5849;5833.824985250439;5857.560682948774;5905.616134951225;5937.079752638338;5948.583599148062;5270.683758974455;5297.417284098746;5333.57331413486;5401.756920938567;5412.83761487156;5185.481451854803;5421.918671219935;5452.372977107624;5467.5625949923915;5480.150576660624;5214.191800270983;5251.973740131313;5312.9734351328;5324.307839980816;5354.14333706607;5292.126799078147;5313.548348390392;5315.309009828737;5319.521243088141;5322.2282768998275;5279.841604751864;5327.360716713981;5359.249705041298;5369.060414427459;5419.729013549307;5177.430482646943;5591.658002079513;5703.458171473745;5821.970890145522;5880.911786323209;6302.716377763011;6308.779192728279;6324.241091069055;6355.428011478989;6373.872522549523;6252;6322.652254126021;6324.5889017214195;6337.936620633781;6417.197850268716;5888.263967004124;5903.527717782567;5924.911126333109;5935;5992.161097446361;6318.8420289492215;6353.047042943595;6362.236531616236;6363.554551181465;6367.554271200971;6140.17107690456;6287.685615719196;6293.181886354744;6311.558190926591;6325.114483972247;6200.410960958701;6241.063840423882;6262.3111457739615;6277.183966084471;6286.842828929313;6219.813405597839;6271.184745983084;6271.811845644546;6272.8118156455375;6320.494360451153;6297.086922396227;6310.968445157745;6345.904811427832;6361.809145725636;6365.427111559982;4937;4977.875553111129;4995.225740010304;5003.774830132653;5039.823606173766;4459.219636563588;4509.188346097734;4524.819007239674;4528.139653465831;4568.862934111982;5784.1902133701315;5930.2883653960935;5959.284885813643;5980.342162362137;5980.970095149496;6226.937730622726;6242.09489624002;6261.937380626136;6306.274778400542;6323.715432805535;5966.224390829165;5995.490383317467;5996.820095397145;6003.249593800775;6031.24609423822;5451.318585176853;5495.368032676213;5539.889202215933;5540.36285827135;5549.750261238254;6252.531060170414;6319.557630965788;6336.936630633634;6415.903761443582;6428.903566446507;5555.50000499957;6092;6237.313895471585;6301.023341382093;6314.147590075465;5247.7113758743635;5355.3305836770405;6343.492520598434;6371.840703982438;6418.93581064202;5283.445238250082;6150.7847225346895;6231.2522497298805;6236.594621349598;6308.022256550242;6210.130581718653;6281.403266689657;6282.371762823681;6304.306526282014;6336.20797400325;6154.599951003171;6197.659128747782;6198.380161983766;6199.194104766351;6200.658963757037;6110.315755985069;6122.755089796358;6148.555089554041;6161.630302181883;6235.251769787655;5066.721330326867;5906.173135761082;6094.329623741473;6098.695065246809;6110.5587591967305;6126.969365153146;6198.2252218472695;6212.627242365473;6216.098665693358;6240.12638230639;4903.632227582874;6045.78839740607;6076.118962750465;6083.574149809471;6092.208012958287 02:12:08 INFO - PROCESS | 1670 | |1;ext.html;470.44957399842184;527.8205410160544;535.3629181274283;536.8872536767276;538.919162125681;38265.617343826554;40852;40960.38559421611;41048.79475602622;41129.79435102824;41280.793596032054;41919;41955.58044419555;42026.36960445588;42123;9687.709368718932;9876.851847222297;9884.851727224097;9895.664085348493;9938.950305248463;9708.854367184462;9798;9810;9823.557939892686;9835.967223441543;9734.172595329406;9803.705888823346;9822.6070957162;9834.31159818813;9852.113309802116;9776.022397760202;9810.117089461983;9835.754106147331;9855.211583073336;9857.408555486658;8353.624086916103;9754.46350450725;9768.120869121813;9830.655927042519;9876.90123098767;31633.52549711756;37796.811015944746;39995.00012499682;40146.99632509211;40330.19339613221;2823.788215883817;3089.721925026736;3365.9158521036925;3511.1924257420937;3801.8099095045413;26405.075822346127;30483.847580762176;30927;31036.37927241465;31755.52366714501;3243.221626809572;3315.419801534722;3420.6237313895454;3424.9315013699834;3446.138465383654;351346;355857;357996;358440;358976;33132.503012454836;51719;61857;62324;62419;33736.66263337335;34414.655853441145;34939.126521836646;34939.82530087333;34945.77689780846;14563.635909102146;14739.705205896036;14747.852521474648;14769.926150369394;14796.926015369854;12452.31512266834;12508.624741257778;12559.686007849694;12562.371881405894;12572.811407828889;7658.693652253847;7664.386849052063;7727.884081738778;7729.4202934779;7730.342920851678;2735.5896615507436;3147.27612649092;3204.7436205103536;3211.935761284761;3227.080282119585;846.3694547561983;979.9363041402286;1036.098594223031;1046.053321743823;1124.746931940307;30133.849330753204;30228;30559.541606875915;31103;31118.377632447224;1032.9225308101923;1034.5603118674533;1040.6721882606955;1054.7204990677476;1108.2408550143177;13603.979701522425;13871.930640346936;15366.308516116802;15938.884278100653;16092.206476217201;177451;179510.1024494869;180252;180535.09732451517;180597;57885.71057144687;57934;58375.12437313444;58585.414145858;58586.53533661692;1324.9072564920364;1367.5760514240617;1371.0950772490307;1376.160542069337;1376.2636989210723;28998.855005724836;29680.406391872475;29871;30196.94310699115;30518;50829;51069.74465127651;51193.48806511887;51203.8796120385;51222.48777512177;525324;526507;526659;528691;529226;26132.86933565396;26273.605895911194;26386.604200936617;26552;26650.93396264205;44494.55505444904;44518.3322250173;44612.55387446084;45575.088498230514;46103.9992061124;38185;38476.845694629206;38732.8380148596;39396.60603394044;39909.40135898022;37781.622183777814;38406.80796595999;38416.48232574156;38924.610753893234;39519.81440556788;37480.625193748805;37960.81019594884;38581.807090964365;38708.032299192746;38746.41880371853;31408.685913140576;33793.98618041463;34333.31333373269;34411.4838277421;34528.48207276843;23159.76840231576;23206.83965801737;25330.366740831625;25713.87143064273;25817 02:12:08 INFO - PROCESS | 1670 | |2;jquery.html;307.4113073464315;344.29763282902877;367.58830110276494;372.4729507746539;387.2274811750558;15585.610359740998;18656.90671546642;19022.714659280104;21245.25641602544;21353.2526361577;19452.735572187834;20025.098870550824;20236.494087647818;20409.183632654713;20528.692069618966;5331.306930099082;5402.216678581613;5597.412271711472;5604.075327570967;5605;5399.514043736063;5494.423085576017;5543.52880005198;5553.750081246354;5568.220449137104;5533.6956467394275;5536.003519366513;5543.445655434464;5555.555555555546;5603.887922241553;6039.275286965549;6146.354632763562;6154.5384096192965;6169.475594574466;6170.074488826667;4396.1427521633295;5042.596592272609;5193.13368951943;5379.946200538023;5402.567794576425;10643.201759868041;12734;15947;25373;25609.871950640314;1975.150685205361;2165.004098114869;2202.3392982105306;2547.223096955428;2569.987150064256;9797.559109840075;9809.656662016867;9832.410055396698;10257.692269231935;10265.692029239135;1852.3609354772582;1876.587150826816;1876.774787025552;1890.9527261818564;1905.8094190580969;111290;115660;124833;128015;128261.35869320687;9406.623735050522;10795.83806242907;11082.889171108185;11390.601328953448;11642.941785291188;13856.237906915016;14231.85768142326;14250.786238206436;14257.50098746539;14261.92869035669;8354.415190936697;8618.181272779077;8622.353323500762;8658.437201582003;8733.475991440533;8651.783705407415;8716.477011379338;8757.080506546848;8886.4668119913;8900.243479304203;4380.540043295472;4410.4927933287445;4486.842960496367;4510.143072816154;4564.7032942858605;1733.1940647598926;1769.8761086723805;1920.8271255587065;1922.7596550431197;1928.594995051055;808.2241048593417;843.5655637346771;867.8915135608049;906.6101576322113;960.7261930349816;17632.942023478376;18446.35437759672;20515.38453846387;20627;21266;839.6011894350135;901.7816172216421;910.4855756497558;935.9672411465566;969.9563519641633;8175.427720059539;8537.018242902022;10323.587056517656;10570.894291057144;11232.269902456477;547369;553210;558929.4107058877;578240;578913;108927;126224;138723;138909.30545347207;141748;973.7565608597993;987.7876256604865;999.6301368493704;1003.814294355532;1037.0873631204493;15332.693346132792;15699.607509811889;18512.259509619464;18562.886226826435;18937.90531047336;50324.73538690841;53602.19596706131;56482.7175864118;57012.71493642505;57132.42867571437;80731.59634201792;81139;82215.17784822076;82797.58601207196;84335.57832210799;5887.882242355044;7219.061522002106;7363.263673632594;7719.343855772097;8021.2379823916635;10980.615678451535;12551.246925184518;12837.679058023625;12979.480820767063;13065.738685226052;11067.778644427228;11090.889091108986;11095.72260693489;11223.438828058728;11315.151363647594;9526.333156679244;10344.637937672145;10830;10990.780184396428;11210.159238057015;11015.779684406107;11344.03575696042;11586.768264634491;11749.353785541878;11751.118666100248;6612.966935165294;7877.094134174643;8186.426950113673;8209.343252539902;8295.211954864279;11168.553257869595;11276.267042642203;11310.434478275889;11407.973282404624;11440.370779607201 02:12:08 INFO - PROCESS | 1670 | |3;mootools.html;1398.426645075519;1435.9353829077688;1681.3778901806334;1839.9172037258322;1873.634641244957;7921.405894557903;8086.676532938675;8569.828603427928;8925.732228033154;8961.05908879568;8594.87107693385;8770.605322760475;8771.254443372305;8777.736667899973;8786.956065219665;2365.2312998275543;2383.5471260460545;2383.7616238376195;2388.773066558674;2389.3787615219994;2342.531493701258;2368.7275963264274;2394.9281521554294;2398.880055997202;2403.182917807945;2372.6915500984933;2374.7387787343378;2379.393254720044;2392.7966122879575;2399.316194884458;2343.285297984114;2343.95312093758;2355.8704271265074;2366.538524987628;2368.6802281691926;2322.1640209524558;2353.97646023541;2375.845570037942;2382.1543352109925;2388.235764555343;8742.650293988232;8767.473951562926;8776.16626420489;8839.07189745074;8914.777130571723;5225.5035771602015;5396.163594642798;5402.243685883979;5403.729813509309;5416.781224224557;3388.423967925459;3413.6245013048547;3637.836297366625;3687.4468829675493;3717.182219911615;1081.145894743152;1081.297156848055;1094.63876920616;1097.1716354152643;1103.9558417663284;11370.09039276856;11453.770924581546;11483.368414737186;11483.42582870853;11497.770044599145;3528.5412896323314;4659.4175728034;4793.5685788279225;4857.562819346276;4864.197407427735;8029.87581738557;8713.477191368538;9253.074692530829;9673.467959262309;9891.703248902544;4487.977560112244;4588.242939914877;5091.261767043758;5290.735463226823;5355.678659280377;4398.098389830141;4616;4755.334253204554;4887.340209071801;4916.901661966741;3533.94699079514;3902.0635047588376;4005.599440055971;4019.738716983387;4068.613481719232;1000.9099181073739;1290.9225446473242;1303.724999875205;1312.8884044856104;1316.6181807275975;887.5784002598714;891.8528442806864;898.793277546168;904.9773755656162;943.6791490893129;5477.068898287301;5728.484436400744;5922.200502932135;5922.911156332659;6113.296970848321;380.3325164336627;444.82429440370873;446.2257982400532;959.9280053995979;997.3567049253191;3718.1819999600043;3891.747036442622;3910.2179564087296;3944.915148334231;4038.212548553063;10389.961003899549;10677.412742299093;10738.140948724084;10751.946240268748;10847.620333288454;10418.11446027081;10432.947835260928;10668.253222274367;10727.463626818628;10738.570457181626;8066.395020373496;8097.595120243965;8163.79590510242;8230.053543842416;8257.298129659046;5282.577393808409;5378.327709036371;5776.595638305278;5809.767609295581;5854.560907931837;9851.113399794052;9909.851352229867;9984.201263899016;9986.700398988041;9990.100890919852;9718.514074296398;10209.591616335263;10226.181905447695;10233.48832558384;10241.539130739284;6536.182977127859;6854.834678104635;6977.476689248225;7014.193367762773;7142.9285607159345;8441.409101362846;8447.619857106567;8487.660493580435;8491.108433614381;8491.278241349555;7436.107667079985;7534.434917381328;7554.848903022019;7600.1639819620905;7665.655045523076;7390.224026477143;7392.112946446461;7408.851822963618;7550.622468876424;7570.091589009354;6850;6991.196012458429;6992.30076992317;7003.5797852129035;7009.929900700928;4884.023195360871;4972.353594032753;5038.924416133834;5110.131277682877;5140.228965655182;5010.874728131826;5020.723860187724;5128.640995130305;5133.998870220271;5197.688138711689 02:12:08 INFO - PROCESS | 1670 | |4;prototype.html;268.4443202570679;296.9940601187975;305.8256793627632;308.44787829784684;309.3241267829791;4549.977250113745;5038.823641172555;5039.622028347871;5425.02349577076;5484.808031718891;4341.761203133827;4796.256580230061;5384.596155288349;5505.284313039299;5589.832305030855;3594.2272411431536;3614.9096272593133;3807.02920755207;3930.862419815307;4284.785760711968;3672.577653569847;3933.056066544023;4125.381192821086;4264.4882614086355;4277.935830962538;3327.45097058985;4013.2374848778786;4104.40486129511;4195.790210489463;4209.873703788891;3327.8336083195745;4167.624913757746;4221.514525829538;4249.532551419341;4320.805563749623;3652.2695460907926;3670.137517683342;3685.281370132825;3694.168812017275;3700.2229531798353;4380.34294855771;4529.977350113229;4622.583967442946;4981.701097934136;5410.377806552257;4808.951910480885;4846.158459785259;4858.975705121487;4859.58693511052;4860.003699241647;4736.9526304736855;4944.307796908436;5356.491133342326;5877.382874798127;5915.792947246869;3307.9007629771145;3871.9032024199337;3980.4029395590605;4021.9396709049097;4073.5315438724333;5691.630044047124;6137.171481849938;6187.87624247517;6205.7517699291975;6237.750489980395;3556.861804222624;3660.725445591538;3856.4408160817084;4057.904365821271;4095.894108590664;3916.353801622757;4122.546519882811;4146.253674338588;4150.813213405404;4166.687498437569;4297.290946993711;4351.521332653406;4820.903581928342;4841.716945009575;4865.659403841768;4861.4895435978715;5245.868853278698;5843;5977.462028417463;5999.940000599938;1170.9297442153497;1340.9061365704308;1343.8387393512842;1382.315753701904;1405.0445696925983;919.2002957427081;932.8274269260208;941.915227629503;950.1496160935924;951.5765484359393;4573.679842411066;5404.7297635118875;5431.04008315469;5437.728113594305;5446.618736688394;5300.31095957523;5431.918521222185;5890;5968.582199246011;6015.428534289235;4586.94500264941;5007.820034278259;5164.664296820694;5344.6258761887075;5374.1938709193155;2394.5809483340345;2581.586946088617;2637.7494138056854;2640.392709676785;2641.8018648601433;4212.957870421318;4327.588879056485;4577.427821522309;5083.212102124176;5221.660592061504;4131.628153466202;4237.851675191415;4794.890248290741;5096.96121551375;5106.004329155779;4426.096778385325;5058.266551350107;5151.072806894795;5205.817796377109;5272.604554658339;4744.193487107131;4751.192297309535;5049.84850454487;5052.797888084582;5090.185570308733;4121.9587804122775;4129.905575022562;4962.982588569263;4977.576905963034;4979.526944940225;4546.99898067274;4693.460252071056;4762.666613337033;5123.05424380975;5176.663516871391;3223.4842425211855;3413.368526822546;3509.8069606171907;3515.2442224921765;3568.661751843059;4660.277656963176;4990.476000019946;5023.488255872064;5023.598112151092;5053.545180933586;4866.148424025852;4872.4153101628035;4876.366072410564;4904.768330409726;4905.90188196227;4210.936835947402;4328.502222244356;4400.073950822795;4714.834980775657;4902.558769710743;4017.910686443092;4296.312589985588;4315.0226343823915;4410.316400957857;4806.855794326176;4291.442112525352;4399.73601583906;4586.0827834432575;4931.309616653743;4958.528939750718;3738.813059347076;4226.978865105778;4457.8216871324785;4528.682992190515;4903.978737435269 02:12:08 INFO - PROCESS | 1670 | |5;yui.html;590.4243362721346;673.1484671890057;758.9620518974053;769.3268390158611;803.7829785957791;12944.417501212445;14043.438262469515;15204.619884502865;15789.447369342075;15833.524994250161;14606.050606710556;14713.558593242193;14733.778993315109;14751.631209219775;14761.55715328542;668.9632070236136;819.33633756657;822.8025273934243;852.1521086518892;852.8507511185549;717.5300178383168;857.0786404615033;858.6179150278128;862.4652715316481;862.8576284913013;868.7393781865446;870.8301881133182;892.0544223123477;903.223228023899;909.2544113826632;882.6248844241172;899.3929097858919;899.7390756680555;900.4867225681364;901.2069378946485;669.7354544954765;671.4494114825844;671.8387586979138;674.0967104080555;674.6053558668185;14752.704945901129;14820.110793352433;14855.405783768745;14880;14881.627959300995;14426.92786536071;14482.348294326675;14535.345909433996;14536;14559.78160327596;11840.585579504763;11980.161388702794;12136.332501712403;12195.45120469572;12480.875191248062;3747.5315585551807;3760;3938.5273767147837;3976.383660532622;3977.482927219443;117228.41385793017;117685;119226;119266;121937.39031304787;11017.405347209768;11042.227044106836;11084;11279.548818047186;11397.316161030365;22787.772122278897;23063.423414414774;23079;23105.19131830378;23157.768422315898;7836.177201393885;7912.762617121495;7931.762047138595;7973.960130199428;8106.432549721463;3766.7363284569815;4327.177836211173;4332.220200363965;4348.369486424451;4374.19077470669;3533.7349698772696;3641.4719865619336;3641.7997010164695;3642.85428582859;3657.4148136298063;631.6368088349126;644.1432894250636;658.0819756439739;662.5229834519234;673.6800019990466;620.6617393520543;635.3202073781102;656.2256537285953;657.3820608627874;665.9334066593399;8465.66137354499;8735.650573977096;9372.437653740797;9518.238540916711;9552.379095358918;587.7384563869037;588.5321169670123;625.4402309932585;655.8098151536001;666.277089358052;4390.934135987899;4487.349334346567;4488.483824360175;4546.613537849254;4573.291139873325;156096.2195189017;156340;160444;163190;165426;132498.33750831184;134646.3267683675;135939;136923;136945;90392;110009;110673;111937.44031279955;114377;8692.391532592657;8746.737597872074;8835.823283534164;8909.73270801877;9063.229625481645;58953;64604.570474396045;65481.017784732314;65509;67623.3237667617;80926.59536702279;93127.53436232776;97430;100425.49787251017;101024.433411828;10381.636642717469;10691;11988.340641264815;12084.75830483403;12088.335141567299;11309.886901130883;11805.704857378634;12335.766423357592;12903.483860645845;12941.763063422568;11877.643670689893;11924.976499201677;12020.459079341626;12077.818832717692;12501.56244531437;12119.091068169746;12195;12225.327606981346;12479.313637749648;12596.496140154291;9621.9037809621;11791.35147566892;11994.160408771302;12009.519619215134;12418.00655947504;8274.875876861972;9975.90265070827;10166;10636.627718029831;10934.56261749521;8699.608517616849;9885.357451765612;9971.102600765966;10270;10290.176785857264 02:12:08 INFO - PROCESS | 1670 | __end_tp_report 02:12:08 INFO - PROCESS | 1670 | __start_cc_report 02:12:08 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,385 02:12:08 INFO - PROCESS | 1670 | __end_cc_report 02:12:08 INFO - PROCESS | 1670 | __startTimestamp1473066728179__endTimestamp 02:12:08 INFO - PROCESS | 1670 | 02:12:08 INFO - PROCESS | 1670 | ------- Summary: start ------- 02:12:08 INFO - PROCESS | 1670 | Number of tests: 6 02:12:08 INFO - PROCESS | 1670 | 02:12:08 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5796.16 Median:6045.79 stddev:663.45 (11.0%) stddev-sans-first:624.88 02:12:08 INFO - PROCESS | 1670 | Values: 2882.8 3029.3 3434.2 3496.2 3513.1 4828.0 5334.2 5717.3 5806.6 5849.0 5833.8 5857.6 5905.6 5937.1 5948.6 5270.7 5297.4 5333.6 5401.8 5412.8 5185.5 5421.9 5452.4 5467.6 5480.2 5214.2 5252.0 5313.0 5324.3 5354.1 5292.1 5313.5 5315.3 5319.5 5322.2 5279.8 5327.4 5359.2 5369.1 5419.7 5177.4 5591.7 5703.5 5822.0 5880.9 6302.7 6308.8 6324.2 6355.4 6373.9 6252.0 6322.7 6324.6 6337.9 6417.2 5888.3 5903.5 5924.9 5935.0 5992.2 6318.8 6353.0 6362.2 6363.6 6367.6 6140.2 6287.7 6293.2 6311.6 6325.1 6200.4 6241.1 6262.3 6277.2 6286.8 6219.8 6271.2 6271.8 6272.8 6320.5 6297.1 6311.0 6345.9 6361.8 6365.4 4937.0 4977.9 4995.2 5003.8 5039.8 4459.2 4509.2 4524.8 4528.1 4568.9 5784.2 5930.3 5959.3 5980.3 5981.0 6226.9 6242.1 6261.9 6306.3 6323.7 5966.2 5995.5 5996.8 6003.2 6031.2 5451.3 5495.4 5539.9 5540.4 5549.8 6252.5 6319.6 6336.9 6415.9 6428.9 5555.5 6092.0 6237.3 6301.0 6314.1 5247.7 5355.3 6343.5 6371.8 6418.9 5283.4 6150.8 6231.3 6236.6 6308.0 6210.1 6281.4 6282.4 6304.3 6336.2 6154.6 6197.7 6198.4 6199.2 6200.7 6110.3 6122.8 6148.6 6161.6 6235.3 5066.7 5906.2 6094.3 6098.7 6110.6 6127.0 6198.2 6212.6 6216.1 6240.1 4903.6 6045.8 6076.1 6083.6 6092.2 02:12:08 INFO - PROCESS | 1670 | 02:12:08 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:50854.30 Median:37789.22 stddev:101978.89 (269.9%) stddev-sans-first:102194.81 02:12:08 INFO - PROCESS | 1670 | Values: 470.4 527.8 535.4 536.9 538.9 38265.6 40852.0 40960.4 41048.8 41129.8 41280.8 41919.0 41955.6 42026.4 42123.0 9687.7 9876.9 9884.9 9895.7 9939.0 9708.9 9798.0 9810.0 9823.6 9836.0 9734.2 9803.7 9822.6 9834.3 9852.1 9776.0 9810.1 9835.8 9855.2 9857.4 8353.6 9754.5 9768.1 9830.7 9876.9 31633.5 37796.8 39995.0 40147.0 40330.2 2823.8 3089.7 3365.9 3511.2 3801.8 26405.1 30483.8 30927.0 31036.4 31755.5 3243.2 3315.4 3420.6 3424.9 3446.1 351346.0 355857.0 357996.0 358440.0 358976.0 33132.5 51719.0 61857.0 62324.0 62419.0 33736.7 34414.7 34939.1 34939.8 34945.8 14563.6 14739.7 14747.9 14769.9 14796.9 12452.3 12508.6 12559.7 12562.4 12572.8 7658.7 7664.4 7727.9 7729.4 7730.3 2735.6 3147.3 3204.7 3211.9 3227.1 846.4 979.9 1036.1 1046.1 1124.7 30133.8 30228.0 30559.5 31103.0 31118.4 1032.9 1034.6 1040.7 1054.7 1108.2 13604.0 13871.9 15366.3 15938.9 16092.2 177451.0 179510.1 180252.0 180535.1 180597.0 57885.7 57934.0 58375.1 58585.4 58586.5 1324.9 1367.6 1371.1 1376.2 1376.3 28998.9 29680.4 29871.0 30196.9 30518.0 50829.0 51069.7 51193.5 51203.9 51222.5 525324.0 526507.0 526659.0 528691.0 529226.0 26132.9 26273.6 26386.6 26552.0 26650.9 44494.6 44518.3 44612.6 45575.1 46104.0 38185.0 38476.8 38732.8 39396.6 39909.4 37781.6 38406.8 38416.5 38924.6 39519.8 37480.6 37960.8 38581.8 38708.0 38746.4 31408.7 33794.0 34333.3 34411.5 34528.5 23159.8 23206.8 25330.4 25713.9 25817.0 02:12:08 INFO - PROCESS | 1670 | 02:12:08 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:33977.75 Median:325.85 stddev:94739.48 (29074.2%) stddev-sans-first:94971.52 02:12:08 INFO - PROCESS | 1670 | Values: 307.4 344.3 367.6 372.5 387.2 15585.6 18656.9 19022.7 21245.3 21353.3 19452.7 20025.1 20236.5 20409.2 20528.7 5331.3 5402.2 5597.4 5604.1 5605.0 5399.5 5494.4 5543.5 5553.8 5568.2 5533.7 5536.0 5543.4 5555.6 5603.9 6039.3 6146.4 6154.5 6169.5 6170.1 4396.1 5042.6 5193.1 5379.9 5402.6 10643.2 12734.0 15947.0 25373.0 25609.9 1975.2 2165.0 2202.3 2547.2 2570.0 9797.6 9809.7 9832.4 10257.7 10265.7 1852.4 1876.6 1876.8 1891.0 1905.8 111290.0 115660.0 124833.0 128015.0 128261.4 9406.6 10795.8 11082.9 11390.6 11642.9 13856.2 14231.9 14250.8 14257.5 14261.9 8354.4 8618.2 8622.4 8658.4 8733.5 8651.8 8716.5 8757.1 8886.5 8900.2 4380.5 4410.5 4486.8 4510.1 4564.7 1733.2 1769.9 1920.8 1922.8 1928.6 808.2 843.6 867.9 906.6 960.7 17632.9 18446.4 20515.4 20627.0 21266.0 839.6 901.8 910.5 936.0 970.0 8175.4 8537.0 10323.6 10570.9 11232.3 547369.0 553210.0 558929.4 578240.0 578913.0 108927.0 126224.0 138723.0 138909.3 141748.0 973.8 987.8 999.6 1003.8 1037.1 15332.7 15699.6 18512.3 18562.9 18937.9 50324.7 53602.2 56482.7 57012.7 57132.4 80731.6 81139.0 82215.2 82797.6 84335.6 5887.9 7219.1 7363.3 7719.3 8021.2 10980.6 12551.2 12837.7 12979.5 13065.7 11067.8 11090.9 11095.7 11223.4 11315.2 9526.3 10344.6 10830.0 10990.8 11210.2 11015.8 11344.0 11586.8 11749.4 11751.1 6613.0 7877.1 8186.4 8209.3 8295.2 11168.6 11276.3 11310.4 11408.0 11440.4 02:12:08 INFO - PROCESS | 1670 | 02:12:08 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5650.95 Median:4991.61 stddev:3166.30 (63.4%) stddev-sans-first:3159.05 02:12:08 INFO - PROCESS | 1670 | Values: 1398.4 1435.9 1681.4 1839.9 1873.6 7921.4 8086.7 8569.8 8925.7 8961.1 8594.9 8770.6 8771.3 8777.7 8787.0 2365.2 2383.5 2383.8 2388.8 2389.4 2342.5 2368.7 2394.9 2398.9 2403.2 2372.7 2374.7 2379.4 2392.8 2399.3 2343.3 2344.0 2355.9 2366.5 2368.7 2322.2 2354.0 2375.8 2382.2 2388.2 8742.7 8767.5 8776.2 8839.1 8914.8 5225.5 5396.2 5402.2 5403.7 5416.8 3388.4 3413.6 3637.8 3687.4 3717.2 1081.1 1081.3 1094.6 1097.2 1104.0 11370.1 11453.8 11483.4 11483.4 11497.8 3528.5 4659.4 4793.6 4857.6 4864.2 8029.9 8713.5 9253.1 9673.5 9891.7 4488.0 4588.2 5091.3 5290.7 5355.7 4398.1 4616.0 4755.3 4887.3 4916.9 3533.9 3902.1 4005.6 4019.7 4068.6 1000.9 1290.9 1303.7 1312.9 1316.6 887.6 891.9 898.8 905.0 943.7 5477.1 5728.5 5922.2 5922.9 6113.3 380.3 444.8 446.2 959.9 997.4 3718.2 3891.7 3910.2 3944.9 4038.2 10390.0 10677.4 10738.1 10751.9 10847.6 10418.1 10432.9 10668.3 10727.5 10738.6 8066.4 8097.6 8163.8 8230.1 8257.3 5282.6 5378.3 5776.6 5809.8 5854.6 9851.1 9909.9 9984.2 9986.7 9990.1 9718.5 10209.6 10226.2 10233.5 10241.5 6536.2 6854.8 6977.5 7014.2 7142.9 8441.4 8447.6 8487.7 8491.1 8491.3 7436.1 7534.4 7554.8 7600.2 7665.7 7390.2 7392.1 7408.9 7550.6 7570.1 6850.0 6991.2 6992.3 7003.6 7009.9 4884.0 4972.4 5038.9 5110.1 5140.2 5010.9 5020.7 5128.6 5134.0 5197.7 02:12:08 INFO - PROCESS | 1670 | 02:12:08 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4281.92 Median:4604.76 stddev:1273.92 (27.7%) stddev-sans-first:1241.37 02:12:08 INFO - PROCESS | 1670 | Values: 268.4 297.0 305.8 308.4 309.3 4550.0 5038.8 5039.6 5425.0 5484.8 4341.8 4796.3 5384.6 5505.3 5589.8 3594.2 3614.9 3807.0 3930.9 4284.8 3672.6 3933.1 4125.4 4264.5 4277.9 3327.5 4013.2 4104.4 4195.8 4209.9 3327.8 4167.6 4221.5 4249.5 4320.8 3652.3 3670.1 3685.3 3694.2 3700.2 4380.3 4530.0 4622.6 4981.7 5410.4 4809.0 4846.2 4859.0 4859.6 4860.0 4737.0 4944.3 5356.5 5877.4 5915.8 3307.9 3871.9 3980.4 4021.9 4073.5 5691.6 6137.2 6187.9 6205.8 6237.8 3556.9 3660.7 3856.4 4057.9 4095.9 3916.4 4122.5 4146.3 4150.8 4166.7 4297.3 4351.5 4820.9 4841.7 4865.7 4861.5 5245.9 5843.0 5977.5 5999.9 1170.9 1340.9 1343.8 1382.3 1405.0 919.2 932.8 941.9 950.1 951.6 4573.7 5404.7 5431.0 5437.7 5446.6 5300.3 5431.9 5890.0 5968.6 6015.4 4586.9 5007.8 5164.7 5344.6 5374.2 2394.6 2581.6 2637.7 2640.4 2641.8 4213.0 4327.6 4577.4 5083.2 5221.7 4131.6 4237.9 4794.9 5097.0 5106.0 4426.1 5058.3 5151.1 5205.8 5272.6 4744.2 4751.2 5049.8 5052.8 5090.2 4122.0 4129.9 4963.0 4977.6 4979.5 4547.0 4693.5 4762.7 5123.1 5176.7 3223.5 3413.4 3509.8 3515.2 3568.7 4660.3 4990.5 5023.5 5023.6 5053.5 4866.1 4872.4 4876.4 4904.8 4905.9 4210.9 4328.5 4400.1 4714.8 4902.6 4017.9 4296.3 4315.0 4410.3 4806.9 4291.4 4399.7 4586.1 4931.3 4958.5 3738.8 4227.0 4457.8 4528.7 4904.0 02:12:08 INFO - PROCESS | 1670 | 02:12:08 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:25411.25 Median:3753.77 stddev:41830.55 (1114.4%) stddev-sans-first:41906.38 02:12:08 INFO - PROCESS | 1670 | Values: 590.4 673.1 759.0 769.3 803.8 12944.4 14043.4 15204.6 15789.4 15833.5 14606.1 14713.6 14733.8 14751.6 14761.6 669.0 819.3 822.8 852.2 852.9 717.5 857.1 858.6 862.5 862.9 868.7 870.8 892.1 903.2 909.3 882.6 899.4 899.7 900.5 901.2 669.7 671.4 671.8 674.1 674.6 14752.7 14820.1 14855.4 14880.0 14881.6 14426.9 14482.3 14535.3 14536.0 14559.8 11840.6 11980.2 12136.3 12195.5 12480.9 3747.5 3760.0 3938.5 3976.4 3977.5 117228.4 117685.0 119226.0 119266.0 121937.4 11017.4 11042.2 11084.0 11279.5 11397.3 22787.8 23063.4 23079.0 23105.2 23157.8 7836.2 7912.8 7931.8 7974.0 8106.4 3766.7 4327.2 4332.2 4348.4 4374.2 3533.7 3641.5 3641.8 3642.9 3657.4 631.6 644.1 658.1 662.5 673.7 620.7 635.3 656.2 657.4 665.9 8465.7 8735.7 9372.4 9518.2 9552.4 587.7 588.5 625.4 655.8 666.3 4390.9 4487.3 4488.5 4546.6 4573.3 156096.2 156340.0 160444.0 163190.0 165426.0 132498.3 134646.3 135939.0 136923.0 136945.0 90392.0 110009.0 110673.0 111937.4 114377.0 8692.4 8746.7 8835.8 8909.7 9063.2 58953.0 64604.6 65481.0 65509.0 67623.3 80926.6 93127.5 97430.0 100425.5 101024.4 10381.6 10691.0 11988.3 12084.8 12088.3 11309.9 11805.7 12335.8 12903.5 12941.8 11877.6 11925.0 12020.5 12077.8 12501.6 12119.1 12195.0 12225.3 12479.3 12596.5 9621.9 11791.4 11994.2 12009.5 12418.0 8274.9 9975.9 10166.0 10636.6 10934.6 8699.6 9885.4 9971.1 10270.0 10290.2 02:12:08 INFO - PROCESS | 1670 | -------- Summary: end -------- 02:12:08 INFO - PROCESS | 1670 | 02:12:08 INFO - TEST-INFO | 1670: exit 0 02:12:08 INFO - TEST-OK | dromaeo_css | took 1114108ms 02:12:08 INFO - TEST-START | kraken 02:12:08 INFO - operating with platform_type : mac_ 02:12:08 INFO - Initialising browser for kraken test... 02:12:08 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpWQDFFH/profile http://localhost:49271/getInfo.html) 02:12:11 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 02:12:11 INFO - PROCESS | 1677 | colorDepth:24 02:12:11 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 02:12:11 INFO - PROCESS | 1677 | __metrics 02:12:11 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 02:12:12 INFO - TEST-INFO | 1677: exit 0 02:12:12 INFO - Browser initialized. 02:12:12 INFO - Running cycle 1/1 for kraken test... 02:12:12 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/tmpWQDFFH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:12:24 INFO - PROCESS | 1679 | RSS: Main: 244023296 02:12:24 INFO - PROCESS | 1679 | 02:12:27 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 02:12:28 INFO - PROCESS | 1679 | RSS: Main: 256786432 02:12:28 INFO - PROCESS | 1679 | 02:12:30 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 02:12:31 INFO - PROCESS | 1679 | RSS: Main: 265433088 02:12:31 INFO - PROCESS | 1679 | 02:12:34 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 02:12:35 INFO - PROCESS | 1679 | RSS: Main: 264814592 02:12:35 INFO - PROCESS | 1679 | 02:12:37 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 02:12:38 INFO - PROCESS | 1679 | RSS: Main: 267145216 02:12:38 INFO - PROCESS | 1679 | 02:12:40 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:12:40 INFO - PROCESS | 1679 | RSS: Main: 267829248 02:12:40 INFO - PROCESS | 1679 | 02:12:43 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 02:12:44 INFO - PROCESS | 1679 | RSS: Main: 268726272 02:12:44 INFO - PROCESS | 1679 | 02:12:47 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 02:12:48 INFO - PROCESS | 1679 | RSS: Main: 268914688 02:12:48 INFO - PROCESS | 1679 | 02:12:51 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 02:12:51 INFO - PROCESS | 1679 | RSS: Main: 269049856 02:12:51 INFO - PROCESS | 1679 | 02:12:53 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:12:53 INFO - PROCESS | 1679 | RSS: Main: 268795904 02:12:53 INFO - PROCESS | 1679 | 02:12:55 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:12:56 INFO - PROCESS | 1679 | RSS: Main: 268943360 02:12:56 INFO - PROCESS | 1679 | 02:12:58 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:12:59 INFO - PROCESS | 1679 | RSS: Main: 269336576 02:12:59 INFO - PROCESS | 1679 | 02:13:02 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:13:03 INFO - PROCESS | 1679 | RSS: Main: 269533184 02:13:03 INFO - PROCESS | 1679 | 02:13:06 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:13:06 INFO - PROCESS | 1679 | RSS: Main: 269500416 02:13:06 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 02:13:08 INFO - PROCESS | 1679 | RSS: Main: 271540224 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | __start_tp_report 02:13:08 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 02:13:08 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 02:13:08 INFO - PROCESS | 1679 | |i|pagename|runs| 02:13:08 INFO - PROCESS | 1679 | |0;ai-astar;110;117;139;164;156;117;117;119;120;135 02:13:08 INFO - PROCESS | 1679 | |1;audio-beat-detection;146;114;197;172;111;130;130;114;165;127 02:13:08 INFO - PROCESS | 1679 | |2;audio-dft;197;228;273;203;199;204;199;198;193;191 02:13:08 INFO - PROCESS | 1679 | |3;audio-fft;66;83;76;144;67;51;74;57;124;75 02:13:08 INFO - PROCESS | 1679 | |4;audio-oscillator;68;71;120;99;99;86;116;100;79;129 02:13:08 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;90;126;100;86;90;87;91;88;91;89 02:13:08 INFO - PROCESS | 1679 | |6;imaging-darkroom;172;122;99;100;113;100;101;101;99;156 02:13:08 INFO - PROCESS | 1679 | |7;imaging-desaturate;91;115;146;91;92;89;92;98;89;93 02:13:08 INFO - PROCESS | 1679 | |8;json-parse-financial;57;59;57;57;58;59;59;58;58;76 02:13:08 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;65;70;71;68;75;74;71;74;71;70 02:13:08 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;93;87;90;89;90;86;88;87;85;86 02:13:08 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;145;160;150;158;150;171;151;131;185;164 02:13:08 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;235;174;177;170;171;237;203;172;174;186 02:13:08 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;68;78;90;91;75;61;85;88;62;78 02:13:08 INFO - PROCESS | 1679 | __end_tp_report 02:13:08 INFO - PROCESS | 1679 | __start_cc_report 02:13:08 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,957 02:13:08 INFO - PROCESS | 1679 | __end_cc_report 02:13:08 INFO - PROCESS | 1679 | __startTimestamp1473066788810__endTimestamp 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | ------- Summary: start ------- 02:13:08 INFO - PROCESS | 1679 | Number of tests: 14 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:129.40 Median:127.50 stddev:18.45 (14.5%) stddev-sans-first:18.18 02:13:08 INFO - PROCESS | 1679 | Values: 110.0 117.0 139.0 164.0 156.0 117.0 117.0 119.0 120.0 135.0 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:138.00 stddev:28.85 (20.9%) stddev-sans-first:30.54 02:13:08 INFO - PROCESS | 1679 | Values: 146.0 114.0 197.0 172.0 111.0 130.0 130.0 114.0 165.0 127.0 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:208.50 Median:201.00 stddev:24.86 (12.4%) stddev-sans-first:26.01 02:13:08 INFO - PROCESS | 1679 | Values: 197.0 228.0 273.0 203.0 199.0 204.0 199.0 198.0 193.0 191.0 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:81.70 Median:70.50 stddev:29.48 (41.8%) stddev-sans-first:30.72 02:13:08 INFO - PROCESS | 1679 | Values: 66.0 83.0 76.0 144.0 67.0 51.0 74.0 57.0 124.0 75.0 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:75.00 stddev:20.80 (27.7%) stddev-sans-first:19.29 02:13:08 INFO - PROCESS | 1679 | Values: 68.0 71.0 120.0 99.0 99.0 86.0 116.0 100.0 79.0 129.0 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:93.80 Median:89.50 stddev:11.94 (13.3%) stddev-sans-first:12.59 02:13:08 INFO - PROCESS | 1679 | Values: 90.0 126.0 100.0 86.0 90.0 87.0 91.0 88.0 91.0 89.0 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:116.30 Median:139.00 stddev:26.50 (19.1%) stddev-sans-first:18.95 02:13:08 INFO - PROCESS | 1679 | Values: 172.0 122.0 99.0 100.0 113.0 100.0 101.0 101.0 99.0 156.0 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:99.60 Median:91.50 stddev:18.03 (19.7%) stddev-sans-first:18.85 02:13:08 INFO - PROCESS | 1679 | Values: 91.0 115.0 146.0 91.0 92.0 89.0 92.0 98.0 89.0 93.0 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:58.50 stddev:5.75 (9.8%) stddev-sans-first:6.01 02:13:08 INFO - PROCESS | 1679 | Values: 57.0 59.0 57.0 57.0 58.0 59.0 59.0 58.0 58.0 76.0 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:3.00 (4.2%) stddev-sans-first:2.30 02:13:08 INFO - PROCESS | 1679 | Values: 65.0 70.0 71.0 68.0 75.0 74.0 71.0 74.0 71.0 70.0 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:88.50 stddev:2.42 (2.7%) stddev-sans-first:1.81 02:13:08 INFO - PROCESS | 1679 | Values: 93.0 87.0 90.0 89.0 90.0 86.0 88.0 87.0 85.0 86.0 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:156.50 Median:159.00 stddev:14.87 (9.4%) stddev-sans-first:15.18 02:13:08 INFO - PROCESS | 1679 | Values: 145.0 160.0 150.0 158.0 150.0 171.0 151.0 131.0 185.0 164.0 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.90 Median:181.50 stddev:26.21 (14.4%) stddev-sans-first:22.15 02:13:08 INFO - PROCESS | 1679 | Values: 235.0 174.0 177.0 170.0 171.0 237.0 203.0 172.0 174.0 186.0 02:13:08 INFO - PROCESS | 1679 | 02:13:08 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.60 Median:81.50 stddev:11.13 (13.7%) stddev-sans-first:11.25 02:13:08 INFO - PROCESS | 1679 | Values: 68.0 78.0 90.0 91.0 75.0 61.0 85.0 88.0 62.0 78.0 02:13:08 INFO - PROCESS | 1679 | -------- Summary: end -------- 02:13:08 INFO - PROCESS | 1679 | 02:13:09 INFO - TEST-INFO | 1679: exit 0 02:13:09 INFO - TEST-OK | kraken | took 60833ms 02:13:09 INFO - SUITE-END | took 1174s 02:13:09 INFO - Completed test suite (00:19:35) 02:13:09 INFO - javascript benchmark 02:13:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7094.413434560957, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2882.7549658279045, 3029.2578318312003, 3434.2101316697153, 3496.213351995801, 3513.1392808761857, 4828.010257897132, 5334.226537152111, 5717.285339332583, 5806.622569532978, 5849.0, 5833.824985250439, 5857.560682948774, 5905.616134951225, 5937.079752638338, 5948.583599148062, 5270.683758974455, 5297.417284098746, 5333.57331413486, 5401.756920938567, 5412.83761487156, 5185.481451854803, 5421.918671219935, 5452.372977107624, 5467.5625949923915, 5480.150576660624, 5214.191800270983, 5251.973740131313, 5312.9734351328, 5324.307839980816, 5354.14333706607, 5292.126799078147, 5313.548348390392, 5315.309009828737, 5319.521243088141, 5322.2282768998275, 5279.841604751864, 5327.360716713981, 5359.249705041298, 5369.060414427459, 5419.729013549307, 5177.430482646943, 5591.658002079513, 5703.458171473745, 5821.970890145522, 5880.911786323209, 6302.716377763011, 6308.779192728279, 6324.241091069055, 6355.428011478989, 6373.872522549523, 6252.0, 6322.652254126021, 6324.5889017214195, 6337.936620633781, 6417.197850268716, 5888.263967004124, 5903.527717782567, 5924.911126333109, 5935.0, 5992.161097446361, 6318.8420289492215, 6353.047042943595, 6362.236531616236, 6363.554551181465, 6367.554271200971, 6140.17107690456, 6287.685615719196, 6293.181886354744, 6311.558190926591, 6325.114483972247, 6200.410960958701, 6241.063840423882, 6262.3111457739615, 6277.183966084471, 6286.842828929313, 6219.813405597839, 6271.184745983084, 6271.811845644546, 6272.8118156455375, 6320.494360451153, 6297.086922396227, 6310.968445157745, 6345.904811427832, 6361.809145725636, 6365.427111559982, 4937.0, 4977.875553111129, 4995.225740010304, 5003.774830132653, 5039.823606173766, 4459.219636563588, 4509.188346097734, 4524.819007239674, 4528.139653465831, 4568.862934111982, 5784.1902133701315, 5930.2883653960935, 5959.284885813643, 5980.342162362137, 5980.970095149496, 6226.937730622726, 6242.09489624002, 6261.937380626136, 6306.274778400542, 6323.715432805535, 5966.224390829165, 5995.490383317467, 5996.820095397145, 6003.249593800775, 6031.24609423822, 5451.318585176853, 5495.368032676213, 5539.889202215933, 5540.36285827135, 5549.750261238254, 6252.531060170414, 6319.557630965788, 6336.936630633634, 6415.903761443582, 6428.903566446507, 5555.50000499957, 6092.0, 6237.313895471585, 6301.023341382093, 6314.147590075465, 5247.7113758743635, 5355.3305836770405, 6343.492520598434, 6371.840703982438, 6418.93581064202, 5283.445238250082, 6150.7847225346895, 6231.2522497298805, 6236.594621349598, 6308.022256550242, 6210.130581718653, 6281.403266689657, 6282.371762823681, 6304.306526282014, 6336.20797400325, 6154.599951003171, 6197.659128747782, 6198.380161983766, 6199.194104766351, 6200.658963757037, 6110.315755985069, 6122.755089796358, 6148.555089554041, 6161.630302181883, 6235.251769787655, 5066.721330326867, 5906.173135761082, 6094.329623741473, 6098.695065246809, 6110.5587591967305, 6126.969365153146, 6198.2252218472695, 6212.627242365473, 6216.098665693358, 6240.12638230639, 4903.632227582874, 6045.78839740607, 6076.118962750465, 6083.574149809471, 6092.208012958287], "value": 5753.824675992515, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.44957399842184, 527.8205410160544, 535.3629181274283, 536.8872536767276, 538.919162125681, 38265.617343826554, 40852.0, 40960.38559421611, 41048.79475602622, 41129.79435102824, 41280.793596032054, 41919.0, 41955.58044419555, 42026.36960445588, 42123.0, 9687.709368718932, 9876.851847222297, 9884.851727224097, 9895.664085348493, 9938.950305248463, 9708.854367184462, 9798.0, 9810.0, 9823.557939892686, 9835.967223441543, 9734.172595329406, 9803.705888823346, 9822.6070957162, 9834.31159818813, 9852.113309802116, 9776.022397760202, 9810.117089461983, 9835.754106147331, 9855.211583073336, 9857.408555486658, 8353.624086916103, 9754.46350450725, 9768.120869121813, 9830.655927042519, 9876.90123098767, 31633.52549711756, 37796.811015944746, 39995.00012499682, 40146.99632509211, 40330.19339613221, 2823.788215883817, 3089.721925026736, 3365.9158521036925, 3511.1924257420937, 3801.8099095045413, 26405.075822346127, 30483.847580762176, 30927.0, 31036.37927241465, 31755.52366714501, 3243.221626809572, 3315.419801534722, 3420.6237313895454, 3424.9315013699834, 3446.138465383654, 351346.0, 355857.0, 357996.0, 358440.0, 358976.0, 33132.503012454836, 51719.0, 61857.0, 62324.0, 62419.0, 33736.66263337335, 34414.655853441145, 34939.126521836646, 34939.82530087333, 34945.77689780846, 14563.635909102146, 14739.705205896036, 14747.852521474648, 14769.926150369394, 14796.926015369854, 12452.31512266834, 12508.624741257778, 12559.686007849694, 12562.371881405894, 12572.811407828889, 7658.693652253847, 7664.386849052063, 7727.884081738778, 7729.4202934779, 7730.342920851678, 2735.5896615507436, 3147.27612649092, 3204.7436205103536, 3211.935761284761, 3227.080282119585, 846.3694547561983, 979.9363041402286, 1036.098594223031, 1046.053321743823, 1124.746931940307, 30133.849330753204, 30228.0, 30559.541606875915, 31103.0, 31118.377632447224, 1032.9225308101923, 1034.5603118674533, 1040.6721882606955, 1054.7204990677476, 1108.2408550143177, 13603.979701522425, 13871.930640346936, 15366.308516116802, 15938.884278100653, 16092.206476217201, 177451.0, 179510.1024494869, 180252.0, 180535.09732451517, 180597.0, 57885.71057144687, 57934.0, 58375.12437313444, 58585.414145858, 58586.53533661692, 1324.9072564920364, 1367.5760514240617, 1371.0950772490307, 1376.160542069337, 1376.2636989210723, 28998.855005724836, 29680.406391872475, 29871.0, 30196.94310699115, 30518.0, 50829.0, 51069.74465127651, 51193.48806511887, 51203.8796120385, 51222.48777512177, 525324.0, 526507.0, 526659.0, 528691.0, 529226.0, 26132.86933565396, 26273.605895911194, 26386.604200936617, 26552.0, 26650.93396264205, 44494.55505444904, 44518.3322250173, 44612.55387446084, 45575.088498230514, 46103.9992061124, 38185.0, 38476.845694629206, 38732.8380148596, 39396.60603394044, 39909.40135898022, 37781.622183777814, 38406.80796595999, 38416.48232574156, 38924.610753893234, 39519.81440556788, 37480.625193748805, 37960.81019594884, 38581.807090964365, 38708.032299192746, 38746.41880371853, 31408.685913140576, 33793.98618041463, 34333.31333373269, 34411.4838277421, 34528.48207276843, 23159.76840231576, 23206.83965801737, 25330.366740831625, 25713.87143064273, 25817.0], "value": 17754.94443085379, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.4113073464315, 344.29763282902877, 367.58830110276494, 372.4729507746539, 387.2274811750558, 15585.610359740998, 18656.90671546642, 19022.714659280104, 21245.25641602544, 21353.2526361577, 19452.735572187834, 20025.098870550824, 20236.494087647818, 20409.183632654713, 20528.692069618966, 5331.306930099082, 5402.216678581613, 5597.412271711472, 5604.075327570967, 5605.0, 5399.514043736063, 5494.423085576017, 5543.52880005198, 5553.750081246354, 5568.220449137104, 5533.6956467394275, 5536.003519366513, 5543.445655434464, 5555.555555555546, 5603.887922241553, 6039.275286965549, 6146.354632763562, 6154.5384096192965, 6169.475594574466, 6170.074488826667, 4396.1427521633295, 5042.596592272609, 5193.13368951943, 5379.946200538023, 5402.567794576425, 10643.201759868041, 12734.0, 15947.0, 25373.0, 25609.871950640314, 1975.150685205361, 2165.004098114869, 2202.3392982105306, 2547.223096955428, 2569.987150064256, 9797.559109840075, 9809.656662016867, 9832.410055396698, 10257.692269231935, 10265.692029239135, 1852.3609354772582, 1876.587150826816, 1876.774787025552, 1890.9527261818564, 1905.8094190580969, 111290.0, 115660.0, 124833.0, 128015.0, 128261.35869320687, 9406.623735050522, 10795.83806242907, 11082.889171108185, 11390.601328953448, 11642.941785291188, 13856.237906915016, 14231.85768142326, 14250.786238206436, 14257.50098746539, 14261.92869035669, 8354.415190936697, 8618.181272779077, 8622.353323500762, 8658.437201582003, 8733.475991440533, 8651.783705407415, 8716.477011379338, 8757.080506546848, 8886.4668119913, 8900.243479304203, 4380.540043295472, 4410.4927933287445, 4486.842960496367, 4510.143072816154, 4564.7032942858605, 1733.1940647598926, 1769.8761086723805, 1920.8271255587065, 1922.7596550431197, 1928.594995051055, 808.2241048593417, 843.5655637346771, 867.8915135608049, 906.6101576322113, 960.7261930349816, 17632.942023478376, 18446.35437759672, 20515.38453846387, 20627.0, 21266.0, 839.6011894350135, 901.7816172216421, 910.4855756497558, 935.9672411465566, 969.9563519641633, 8175.427720059539, 8537.018242902022, 10323.587056517656, 10570.894291057144, 11232.269902456477, 547369.0, 553210.0, 558929.4107058877, 578240.0, 578913.0, 108927.0, 126224.0, 138723.0, 138909.30545347207, 141748.0, 973.7565608597993, 987.7876256604865, 999.6301368493704, 1003.814294355532, 1037.0873631204493, 15332.693346132792, 15699.607509811889, 18512.259509619464, 18562.886226826435, 18937.90531047336, 50324.73538690841, 53602.19596706131, 56482.7175864118, 57012.71493642505, 57132.42867571437, 80731.59634201792, 81139.0, 82215.17784822076, 82797.58601207196, 84335.57832210799, 5887.882242355044, 7219.061522002106, 7363.263673632594, 7719.343855772097, 8021.2379823916635, 10980.615678451535, 12551.246925184518, 12837.679058023625, 12979.480820767063, 13065.738685226052, 11067.778644427228, 11090.889091108986, 11095.72260693489, 11223.438828058728, 11315.151363647594, 9526.333156679244, 10344.637937672145, 10830.0, 10990.780184396428, 11210.159238057015, 11015.779684406107, 11344.03575696042, 11586.768264634491, 11749.353785541878, 11751.118666100248, 6612.966935165294, 7877.094134174643, 8186.426950113673, 8209.343252539902, 8295.211954864279, 11168.553257869595, 11276.267042642203, 11310.434478275889, 11407.973282404624, 11440.370779607201], "value": 9266.310639505935, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.426645075519, 1435.9353829077688, 1681.3778901806334, 1839.9172037258322, 1873.634641244957, 7921.405894557903, 8086.676532938675, 8569.828603427928, 8925.732228033154, 8961.05908879568, 8594.87107693385, 8770.605322760475, 8771.254443372305, 8777.736667899973, 8786.956065219665, 2365.2312998275543, 2383.5471260460545, 2383.7616238376195, 2388.773066558674, 2389.3787615219994, 2342.531493701258, 2368.7275963264274, 2394.9281521554294, 2398.880055997202, 2403.182917807945, 2372.6915500984933, 2374.7387787343378, 2379.393254720044, 2392.7966122879575, 2399.316194884458, 2343.285297984114, 2343.95312093758, 2355.8704271265074, 2366.538524987628, 2368.6802281691926, 2322.1640209524558, 2353.97646023541, 2375.845570037942, 2382.1543352109925, 2388.235764555343, 8742.650293988232, 8767.473951562926, 8776.16626420489, 8839.07189745074, 8914.777130571723, 5225.5035771602015, 5396.163594642798, 5402.243685883979, 5403.729813509309, 5416.781224224557, 3388.423967925459, 3413.6245013048547, 3637.836297366625, 3687.4468829675493, 3717.182219911615, 1081.145894743152, 1081.297156848055, 1094.63876920616, 1097.1716354152643, 1103.9558417663284, 11370.09039276856, 11453.770924581546, 11483.368414737186, 11483.42582870853, 11497.770044599145, 3528.5412896323314, 4659.4175728034, 4793.5685788279225, 4857.562819346276, 4864.197407427735, 8029.87581738557, 8713.477191368538, 9253.074692530829, 9673.467959262309, 9891.703248902544, 4487.977560112244, 4588.242939914877, 5091.261767043758, 5290.735463226823, 5355.678659280377, 4398.098389830141, 4616.0, 4755.334253204554, 4887.340209071801, 4916.901661966741, 3533.94699079514, 3902.0635047588376, 4005.599440055971, 4019.738716983387, 4068.613481719232, 1000.9099181073739, 1290.9225446473242, 1303.724999875205, 1312.8884044856104, 1316.6181807275975, 887.5784002598714, 891.8528442806864, 898.793277546168, 904.9773755656162, 943.6791490893129, 5477.068898287301, 5728.484436400744, 5922.200502932135, 5922.911156332659, 6113.296970848321, 380.3325164336627, 444.82429440370873, 446.2257982400532, 959.9280053995979, 997.3567049253191, 3718.1819999600043, 3891.747036442622, 3910.2179564087296, 3944.915148334231, 4038.212548553063, 10389.961003899549, 10677.412742299093, 10738.140948724084, 10751.946240268748, 10847.620333288454, 10418.11446027081, 10432.947835260928, 10668.253222274367, 10727.463626818628, 10738.570457181626, 8066.395020373496, 8097.595120243965, 8163.79590510242, 8230.053543842416, 8257.298129659046, 5282.577393808409, 5378.327709036371, 5776.595638305278, 5809.767609295581, 5854.560907931837, 9851.113399794052, 9909.851352229867, 9984.201263899016, 9986.700398988041, 9990.100890919852, 9718.514074296398, 10209.591616335263, 10226.181905447695, 10233.48832558384, 10241.539130739284, 6536.182977127859, 6854.834678104635, 6977.476689248225, 7014.193367762773, 7142.9285607159345, 8441.409101362846, 8447.619857106567, 8487.660493580435, 8491.108433614381, 8491.278241349555, 7436.107667079985, 7534.434917381328, 7554.848903022019, 7600.1639819620905, 7665.655045523076, 7390.224026477143, 7392.112946446461, 7408.851822963618, 7550.622468876424, 7570.091589009354, 6850.0, 6991.196012458429, 6992.30076992317, 7003.5797852129035, 7009.929900700928, 4884.023195360871, 4972.353594032753, 5038.924416133834, 5110.131277682877, 5140.228965655182, 5010.874728131826, 5020.723860187724, 5128.640995130305, 5133.998870220271, 5197.688138711689], "value": 4517.265789280919, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.4443202570679, 296.9940601187975, 305.8256793627632, 308.44787829784684, 309.3241267829791, 4549.977250113745, 5038.823641172555, 5039.622028347871, 5425.02349577076, 5484.808031718891, 4341.761203133827, 4796.256580230061, 5384.596155288349, 5505.284313039299, 5589.832305030855, 3594.2272411431536, 3614.9096272593133, 3807.02920755207, 3930.862419815307, 4284.785760711968, 3672.577653569847, 3933.056066544023, 4125.381192821086, 4264.4882614086355, 4277.935830962538, 3327.45097058985, 4013.2374848778786, 4104.40486129511, 4195.790210489463, 4209.873703788891, 3327.8336083195745, 4167.624913757746, 4221.514525829538, 4249.532551419341, 4320.805563749623, 3652.2695460907926, 3670.137517683342, 3685.281370132825, 3694.168812017275, 3700.2229531798353, 4380.34294855771, 4529.977350113229, 4622.583967442946, 4981.701097934136, 5410.377806552257, 4808.951910480885, 4846.158459785259, 4858.975705121487, 4859.58693511052, 4860.003699241647, 4736.9526304736855, 4944.307796908436, 5356.491133342326, 5877.382874798127, 5915.792947246869, 3307.9007629771145, 3871.9032024199337, 3980.4029395590605, 4021.9396709049097, 4073.5315438724333, 5691.630044047124, 6137.171481849938, 6187.87624247517, 6205.7517699291975, 6237.750489980395, 3556.861804222624, 3660.725445591538, 3856.4408160817084, 4057.904365821271, 4095.894108590664, 3916.353801622757, 4122.546519882811, 4146.253674338588, 4150.813213405404, 4166.687498437569, 4297.290946993711, 4351.521332653406, 4820.903581928342, 4841.716945009575, 4865.659403841768, 4861.4895435978715, 5245.868853278698, 5843.0, 5977.462028417463, 5999.940000599938, 1170.9297442153497, 1340.9061365704308, 1343.8387393512842, 1382.315753701904, 1405.0445696925983, 919.2002957427081, 932.8274269260208, 941.915227629503, 950.1496160935924, 951.5765484359393, 4573.679842411066, 5404.7297635118875, 5431.04008315469, 5437.728113594305, 5446.618736688394, 5300.31095957523, 5431.918521222185, 5890.0, 5968.582199246011, 6015.428534289235, 4586.94500264941, 5007.820034278259, 5164.664296820694, 5344.6258761887075, 5374.1938709193155, 2394.5809483340345, 2581.586946088617, 2637.7494138056854, 2640.392709676785, 2641.8018648601433, 4212.957870421318, 4327.588879056485, 4577.427821522309, 5083.212102124176, 5221.660592061504, 4131.628153466202, 4237.851675191415, 4794.890248290741, 5096.96121551375, 5106.004329155779, 4426.096778385325, 5058.266551350107, 5151.072806894795, 5205.817796377109, 5272.604554658339, 4744.193487107131, 4751.192297309535, 5049.84850454487, 5052.797888084582, 5090.185570308733, 4121.9587804122775, 4129.905575022562, 4962.982588569263, 4977.576905963034, 4979.526944940225, 4546.99898067274, 4693.460252071056, 4762.666613337033, 5123.05424380975, 5176.663516871391, 3223.4842425211855, 3413.368526822546, 3509.8069606171907, 3515.2442224921765, 3568.661751843059, 4660.277656963176, 4990.476000019946, 5023.488255872064, 5023.598112151092, 5053.545180933586, 4866.148424025852, 4872.4153101628035, 4876.366072410564, 4904.768330409726, 4905.90188196227, 4210.936835947402, 4328.502222244356, 4400.073950822795, 4714.834980775657, 4902.558769710743, 4017.910686443092, 4296.312589985588, 4315.0226343823915, 4410.316400957857, 4806.855794326176, 4291.442112525352, 4399.73601583906, 4586.0827834432575, 4931.309616653743, 4958.528939750718, 3738.813059347076, 4226.978865105778, 4457.8216871324785, 4528.682992190515, 4903.978737435269], "value": 3889.9858465928364, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.4243362721346, 673.1484671890057, 758.9620518974053, 769.3268390158611, 803.7829785957791, 12944.417501212445, 14043.438262469515, 15204.619884502865, 15789.447369342075, 15833.524994250161, 14606.050606710556, 14713.558593242193, 14733.778993315109, 14751.631209219775, 14761.55715328542, 668.9632070236136, 819.33633756657, 822.8025273934243, 852.1521086518892, 852.8507511185549, 717.5300178383168, 857.0786404615033, 858.6179150278128, 862.4652715316481, 862.8576284913013, 868.7393781865446, 870.8301881133182, 892.0544223123477, 903.223228023899, 909.2544113826632, 882.6248844241172, 899.3929097858919, 899.7390756680555, 900.4867225681364, 901.2069378946485, 669.7354544954765, 671.4494114825844, 671.8387586979138, 674.0967104080555, 674.6053558668185, 14752.704945901129, 14820.110793352433, 14855.405783768745, 14880.0, 14881.627959300995, 14426.92786536071, 14482.348294326675, 14535.345909433996, 14536.0, 14559.78160327596, 11840.585579504763, 11980.161388702794, 12136.332501712403, 12195.45120469572, 12480.875191248062, 3747.5315585551807, 3760.0, 3938.5273767147837, 3976.383660532622, 3977.482927219443, 117228.41385793017, 117685.0, 119226.0, 119266.0, 121937.39031304787, 11017.405347209768, 11042.227044106836, 11084.0, 11279.548818047186, 11397.316161030365, 22787.772122278897, 23063.423414414774, 23079.0, 23105.19131830378, 23157.768422315898, 7836.177201393885, 7912.762617121495, 7931.762047138595, 7973.960130199428, 8106.432549721463, 3766.7363284569815, 4327.177836211173, 4332.220200363965, 4348.369486424451, 4374.19077470669, 3533.7349698772696, 3641.4719865619336, 3641.7997010164695, 3642.85428582859, 3657.4148136298063, 631.6368088349126, 644.1432894250636, 658.0819756439739, 662.5229834519234, 673.6800019990466, 620.6617393520543, 635.3202073781102, 656.2256537285953, 657.3820608627874, 665.9334066593399, 8465.66137354499, 8735.650573977096, 9372.437653740797, 9518.238540916711, 9552.379095358918, 587.7384563869037, 588.5321169670123, 625.4402309932585, 655.8098151536001, 666.277089358052, 4390.934135987899, 4487.349334346567, 4488.483824360175, 4546.613537849254, 4573.291139873325, 156096.2195189017, 156340.0, 160444.0, 163190.0, 165426.0, 132498.33750831184, 134646.3267683675, 135939.0, 136923.0, 136945.0, 90392.0, 110009.0, 110673.0, 111937.44031279955, 114377.0, 8692.391532592657, 8746.737597872074, 8835.823283534164, 8909.73270801877, 9063.229625481645, 58953.0, 64604.570474396045, 65481.017784732314, 65509.0, 67623.3237667617, 80926.59536702279, 93127.53436232776, 97430.0, 100425.49787251017, 101024.433411828, 10381.636642717469, 10691.0, 11988.340641264815, 12084.75830483403, 12088.335141567299, 11309.886901130883, 11805.704857378634, 12335.766423357592, 12903.483860645845, 12941.763063422568, 11877.643670689893, 11924.976499201677, 12020.459079341626, 12077.818832717692, 12501.56244531437, 12119.091068169746, 12195.0, 12225.327606981346, 12479.313637749648, 12596.496140154291, 9621.9037809621, 11791.35147566892, 11994.160408771302, 12009.519619215134, 12418.00655947504, 8274.875876861972, 9975.90265070827, 10166.0, 10636.627718029831, 10934.56261749521, 8699.608517616849, 9885.357451765612, 9971.102600765966, 10270.0, 10290.176785857264], "value": 7663.87743277908, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1609.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 117.0, 139.0, 164.0, 156.0, 117.0, 117.0, 119.0, 120.0, 135.0], "value": 129.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 114.0, 197.0, 172.0, 111.0, 130.0, 130.0, 114.0, 165.0, 127.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 228.0, 273.0, 203.0, 199.0, 204.0, 199.0, 198.0, 193.0, 191.0], "value": 208.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 83.0, 76.0, 144.0, 67.0, 51.0, 74.0, 57.0, 124.0, 75.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 120.0, 99.0, 99.0, 86.0, 116.0, 100.0, 79.0, 129.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 126.0, 100.0, 86.0, 90.0, 87.0, 91.0, 88.0, 91.0, 89.0], "value": 93.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 122.0, 99.0, 100.0, 113.0, 100.0, 101.0, 101.0, 99.0, 156.0], "value": 116.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 115.0, 146.0, 91.0, 92.0, 89.0, 92.0, 98.0, 89.0, 93.0], "value": 99.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 57.0, 57.0, 58.0, 59.0, 59.0, 58.0, 58.0, 76.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 71.0, 68.0, 75.0, 74.0, 71.0, 74.0, 71.0, 70.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 87.0, 90.0, 89.0, 90.0, 86.0, 88.0, 87.0, 85.0, 86.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 160.0, 150.0, 158.0, 150.0, 171.0, 151.0, 131.0, 185.0, 164.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [235.0, 174.0, 177.0, 170.0, 171.0, 237.0, 203.0, 172.0, 174.0, 186.0], "value": 189.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 78.0, 90.0, 91.0, 75.0, 61.0, 85.0, 88.0, 62.0, 78.0], "value": 77.6, "unit": "score"}]}]} 02:13:10 INFO - Return code: 0 02:13:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:13:10 ERROR - # TBPL SUCCESS # 02:13:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:13:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:13:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:13:10 INFO - 2016-09-05 02:13:10.111 screenresolution[1684:17731] starting screenresolution argv=screenresolution get 02:13:10 INFO - 2016-09-05 02:13:10.113 screenresolution[1684:17731] Display 0: 1600x1200x32@60 02:13:10 INFO - 2016-09-05 02:13:10.119 screenresolution[1685:17734] starting screenresolution argv=screenresolution list 02:13:10 INFO - Available Modes on Display 0 02:13:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:13:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:13:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:13:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:13:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:13:10 INFO - Intel Iris: 02:13:10 INFO - Chipset Model: Intel Iris 02:13:10 INFO - Type: GPU 02:13:10 INFO - Bus: Built-In 02:13:10 INFO - VRAM (Dynamic, Max): 1536 MB 02:13:10 INFO - Vendor: Intel (0x8086) 02:13:10 INFO - Device ID: 0x0a2e 02:13:10 INFO - Revision ID: 0x0009 02:13:10 INFO - Displays: 02:13:10 INFO - Display: 02:13:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:13:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:13:10 INFO - Display Serial Number: 1600x1200 60 02:13:10 INFO - Main Display: Yes 02:13:10 INFO - Mirror: Off 02:13:10 INFO - Online: Yes 02:13:10 INFO - Rotation: Supported 02:13:10 INFO - Return code: 0 02:13:10 INFO - Running post-action listener: _resource_record_post_action 02:13:10 INFO - [mozharness: 2016-09-05 09:13:10.355931Z] Finished run-tests step (success) 02:13:10 INFO - Running post-run listener: _resource_record_post_run 02:13:10 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 33258496; Write bytes: 708384768; Read time: 317; Write time: 1949 02:13:10 INFO - TinderboxPrint: CPU usage
54.7% 02:13:10 INFO - TinderboxPrint: I/O read bytes / time
33,258,496 / 317 02:13:10 INFO - TinderboxPrint: I/O write bytes / time
708,384,768 / 1,949 02:13:10 INFO - TinderboxPrint: CPU idle
2,194.8 (45.3%) 02:13:10 INFO - TinderboxPrint: CPU system
978.6 (20.2%) 02:13:10 INFO - TinderboxPrint: CPU user
1,668.4 (34.5%) 02:13:10 INFO - TinderboxPrint: Swap in / out
434,470,912 / 0 02:13:10 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389923328; Write bytes: 385243136; Read time: 29679; Write time: 688 02:13:10 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 32304128; Write bytes: 298967040; Read time: 298; Write time: 1213 02:13:10 INFO - Running post-run listener: _upload_blobber_files 02:13:10 INFO - Blob upload gear active. 02:13:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:13:10 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 02:13:10 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:13:10 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:13:11 INFO - (blobuploader) - INFO - Open directory for files ... 02:13:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:13:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:13:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:13:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:13:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:13:13 INFO - (blobuploader) - INFO - Done attempting. 02:13:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:13:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:13:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:13:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:13:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:13:14 INFO - (blobuploader) - INFO - Done attempting. 02:13:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:13:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:13:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:13:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:13:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:13:16 INFO - (blobuploader) - INFO - Done attempting. 02:13:16 INFO - (blobuploader) - INFO - Iteration through files over. 02:13:16 INFO - Return code: 0 02:13:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:13:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:13:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04eba25ce039869a864fc78050c26ad57321d39a71acaa9203e50229b8743aea151ad0f8b068c418d200bb3cff100c13e9bb124ab291bfd42ec66597c90086ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9cf9397edbc5e6ea05368d70c225404c17216a206b3c3919471ac08e9dad91c28b15228c64bd9c94a4fbdc1d70c619836aeb9140122dd70a9f4be07f6c7f6754", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/94b9b83a2b30df44d24772006a6f221ec351cd9d69e1cdedefbb5e495b7783bab4c8e24cf26e70c5f62ef46c23738183ccd233c7d62ad75a119ff8aacb57124e"} 02:13:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:13:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:13:16 INFO - Contents: 02:13:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04eba25ce039869a864fc78050c26ad57321d39a71acaa9203e50229b8743aea151ad0f8b068c418d200bb3cff100c13e9bb124ab291bfd42ec66597c90086ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9cf9397edbc5e6ea05368d70c225404c17216a206b3c3919471ac08e9dad91c28b15228c64bd9c94a4fbdc1d70c619836aeb9140122dd70a9f4be07f6c7f6754", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/94b9b83a2b30df44d24772006a6f221ec351cd9d69e1cdedefbb5e495b7783bab4c8e24cf26e70c5f62ef46c23738183ccd233c7d62ad75a119ff8aacb57124e"} 02:13:16 INFO - Running post-run listener: copy_logs_to_upload_dir 02:13:16 INFO - Copying logs to upload dir... 02:13:16 INFO - mkdir: /builds/slave/test/build/upload/logs 02:13:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.990727 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-05 02:13:16.737099) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-05 02:13:16.741074) ========= 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.1XIZcbIuJO/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.RVIVLw7MSH/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04eba25ce039869a864fc78050c26ad57321d39a71acaa9203e50229b8743aea151ad0f8b068c418d200bb3cff100c13e9bb124ab291bfd42ec66597c90086ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9cf9397edbc5e6ea05368d70c225404c17216a206b3c3919471ac08e9dad91c28b15228c64bd9c94a4fbdc1d70c619836aeb9140122dd70a9f4be07f6c7f6754", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/94b9b83a2b30df44d24772006a6f221ec351cd9d69e1cdedefbb5e495b7783bab4c8e24cf26e70c5f62ef46c23738183ccd233c7d62ad75a119ff8aacb57124e"} build_url:https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010978 build_url: 'https://queue.taskcluster.net/v1/task/Wp0ovm12QkuP2_B9nSkfYQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04eba25ce039869a864fc78050c26ad57321d39a71acaa9203e50229b8743aea151ad0f8b068c418d200bb3cff100c13e9bb124ab291bfd42ec66597c90086ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9cf9397edbc5e6ea05368d70c225404c17216a206b3c3919471ac08e9dad91c28b15228c64bd9c94a4fbdc1d70c619836aeb9140122dd70a9f4be07f6c7f6754", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/94b9b83a2b30df44d24772006a6f221ec351cd9d69e1cdedefbb5e495b7783bab4c8e24cf26e70c5f62ef46c23738183ccd233c7d62ad75a119ff8aacb57124e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-05 02:13:16.773240) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 02:13:16.773549) ========= 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.1XIZcbIuJO/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.RVIVLw7MSH/Listeners TMPDIR=/var/folders/2f/bd59dv5s1jjgys2lwzvcz7vm00000w/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.005247 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 02:13:16.822438) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-05 02:13:16.822768) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-05 02:13:16.823100) ========= ========= Total master_lag: 0.18 =========