builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0067 starttime: 1473351416.83 results: success (0) buildid: 20160908062131 builduid: d5ba2ee822654fc5be536814e30af313 revision: d441ac0b35aedaf1df5ffe995cd4ffaaa697ee49 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 09:16:56.833495) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 09:16:56.838809) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 09:16:56.839112) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 09:16:56.877716) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 09:16:56.878073) ========= 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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-08 09:16:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.58M=0.001s 2016-09-08 09:16:57 (9.58 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.462293 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 09:16:57.365576) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 09:16:57.365887) ========= 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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.030724 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 09:16:57.418010) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 09:16:57.418403) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d441ac0b35aedaf1df5ffe995cd4ffaaa697ee49 --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 d441ac0b35aedaf1df5ffe995cd4ffaaa697ee49 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1PVSozYIbu/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.ciMNoEGVwZ/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-08 09:16:57,497 truncating revision to first 12 chars 2016-09-08 09:16:57,498 Setting DEBUG logging. 2016-09-08 09:16:57,498 attempt 1/10 2016-09-08 09:16:57,498 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d441ac0b35ae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 09:16:58,501 unpacking tar archive at: autoland-d441ac0b35ae/testing/mozharness/ program finished with exit code 0 elapsedTime=1.255699 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 09:16:58.696447) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 09:16:58.696771) ========= script_repo_revision: d441ac0b35aedaf1df5ffe995cd4ffaaa697ee49 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 09:16:58.697155) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 09:16:58.697437) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 09:16:58.715875) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-08 09:16:58.716308) ========= /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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:16:58 INFO - MultiFileLogger online at 20160908 09:16:58 in /builds/slave/test 09:16:58 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 09:16:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:16:58 INFO - {'append_to_log': False, 09:16:58 INFO - 'base_work_dir': '/builds/slave/test', 09:16:58 INFO - 'blob_upload_branch': 'Autoland', 09:16:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:16:58 INFO - 'branch': 'Autoland', 09:16:58 INFO - 'buildbot_json_path': 'buildprops.json', 09:16:58 INFO - 'config_files': ('talos/mac_config.py',), 09:16:58 INFO - 'default_actions': ('clobber', 09:16:58 INFO - 'read-buildbot-config', 09:16:58 INFO - 'download-and-extract', 09:16:58 INFO - 'populate-webroot', 09:16:58 INFO - 'create-virtualenv', 09:16:58 INFO - 'install', 09:16:58 INFO - 'run-tests'), 09:16:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:16:58 INFO - 'download_minidump_stackwalk': True, 09:16:58 INFO - 'download_symbols': 'ondemand', 09:16:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:16:58 INFO - 'tooltool.py': '/tools/tooltool.py', 09:16:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:16:58 INFO - '/tools/misc-python/virtualenv.py')}, 09:16:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:16:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:16:58 INFO - 'installer_path': 'installer.exe', 09:16:58 INFO - 'log_level': 'info', 09:16:58 INFO - 'log_name': 'talos', 09:16:58 INFO - 'log_to_console': True, 09:16:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:16:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:16:58 INFO - 'opt_config_files': (), 09:16:58 INFO - 'pip_index': False, 09:16:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:16:58 INFO - 'cmd': ('bash', 09:16:58 INFO - '-c', 09:16:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:16:58 INFO - 'enabled': True, 09:16:58 INFO - 'halt_on_failure': False, 09:16:58 INFO - 'name': 'check_screen_resolution'},), 09:16:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:16:58 INFO - 'cmd': ('bash', 09:16:58 INFO - '-c', 09:16:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:16:58 INFO - 'enabled': True, 09:16:58 INFO - 'halt_on_failure': False, 09:16:58 INFO - 'name': 'check_screen_resolution'},), 09:16:58 INFO - 'run_cmd_checks_enabled': True, 09:16:58 INFO - 'sps_profile': False, 09:16:58 INFO - 'sps_profile_interval': 0, 09:16:58 INFO - 'suite': 'dromaeojs-e10s', 09:16:58 INFO - 'system_bits': '32', 09:16:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:16:58 INFO - 'title': 't-yosemite-r7-0067', 09:16:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:16:58 INFO - 'use_talos_json': True, 09:16:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:16:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:16:58 INFO - 'work_dir': 'build'} 09:16:58 INFO - [mozharness: 2016-09-08 16:16:58.915027Z] Running clobber step. 09:16:58 INFO - Running pre-action listener: _resource_record_pre_action 09:16:58 INFO - Running main action method: clobber 09:16:58 INFO - rmtree: /builds/slave/test/build 09:16:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:17:00 INFO - Running post-action listener: _resource_record_post_action 09:17:00 INFO - [mozharness: 2016-09-08 16:17:00.931419Z] Finished clobber step (success) 09:17:00 INFO - [mozharness: 2016-09-08 16:17:00.931492Z] Running read-buildbot-config step. 09:17:00 INFO - Running pre-action listener: _resource_record_pre_action 09:17:00 INFO - Running main action method: read_buildbot_config 09:17:00 INFO - Using buildbot properties: 09:17:00 INFO - { 09:17:00 INFO - "project": "", 09:17:00 INFO - "product": "firefox", 09:17:00 INFO - "script_repo_revision": "production", 09:17:00 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 09:17:00 INFO - "repository": "", 09:17:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 09:17:00 INFO - "buildid": "20160908062131", 09:17:00 INFO - "pgo_build": "False", 09:17:00 INFO - "basedir": "/builds/slave/test", 09:17:00 INFO - "buildnumber": 189, 09:17:00 INFO - "slavename": "t-yosemite-r7-0067", 09:17:00 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:17:00 INFO - "platform": "yosemite_r7", 09:17:00 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 09:17:00 INFO - "branch": "autoland", 09:17:00 INFO - "repo_path": "integration/autoland", 09:17:00 INFO - "revision": "d441ac0b35aedaf1df5ffe995cd4ffaaa697ee49", 09:17:00 INFO - "stage_platform": "macosx64", 09:17:00 INFO - "builduid": "d5ba2ee822654fc5be536814e30af313", 09:17:00 INFO - "slavebuilddir": "test" 09:17:00 INFO - } 09:17:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:17:00 INFO - Running post-action listener: _resource_record_post_action 09:17:00 INFO - [mozharness: 2016-09-08 16:17:00.934954Z] Finished read-buildbot-config step (success) 09:17:00 INFO - [mozharness: 2016-09-08 16:17:00.935087Z] Running download-and-extract step. 09:17:00 INFO - Running pre-action listener: _resource_record_pre_action 09:17:00 INFO - Running main action method: download_and_extract 09:17:00 INFO - mkdir: /builds/slave/test/build/tests 09:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:00 INFO - trying https://queue.taskcluster.net/v1/task/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:17:00 INFO - Downloading https://queue.taskcluster.net/v1/task/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:17:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 09:17:01 INFO - Downloaded 1199 bytes. 09:17:01 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:17:01 INFO - Using the following test package requirements: 09:17:01 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:17:01 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:01 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:17:01 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:01 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:17:01 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:01 INFO - u'jsshell-mac.zip'], 09:17:01 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:01 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:17:01 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:17:01 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:01 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:17:01 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:01 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:17:01 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:01 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:17:01 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:01 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:17:01 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:17:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:17:02 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:17:09 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:17:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:17:09 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:17:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:17:17 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:17 INFO - trying https://queue.taskcluster.net/v1/task/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:17:17 INFO - Downloading https://queue.taskcluster.net/v1/task/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:17:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 09:17:23 INFO - Downloaded 111529043 bytes. 09:17:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:17:23 INFO - mkdir: /builds/slave/test/properties 09:17:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:17:23 INFO - Writing to file /builds/slave/test/properties/build_url 09:17:23 INFO - Contents: 09:17:23 INFO - build_url:https://queue.taskcluster.net/v1/task/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:17:24 INFO - Running post-action listener: _resource_record_post_action 09:17:24 INFO - Running post-action listener: set_extra_try_arguments 09:17:24 INFO - [mozharness: 2016-09-08 16:17:24.261795Z] Finished download-and-extract step (success) 09:17:24 INFO - [mozharness: 2016-09-08 16:17:24.261915Z] Running populate-webroot step. 09:17:24 INFO - Running pre-action listener: _resource_record_pre_action 09:17:24 INFO - Running main action method: populate_webroot 09:17:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:17:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:17:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:17:24 INFO - '--tppagecycles', 09:17:24 INFO - '7'], 09:17:24 INFO - 'tests': ['tsvgm']}}, 09:17:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:17:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:17:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:17:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:17:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:17:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:17:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:24 INFO - 'talos_options': ['--disable-e10s'], 09:17:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:24 INFO - 'talos_options': ['--disable-e10s'], 09:17:24 INFO - 'tests': ['damp', 'tps']}, 09:17:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:24 INFO - 'tests': ['damp', 'tps']}, 09:17:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:17:24 INFO - 'tests': ['dromaeo_dom']}, 09:17:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:17:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:17:24 INFO - 'tests': ['basic_compositor_video']}, 09:17:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:17:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:17:24 INFO - 'tests': ['a11yr', 09:17:24 INFO - 'ts_paint', 09:17:24 INFO - 'tpaint', 09:17:24 INFO - 'sessionrestore', 09:17:24 INFO - 'sessionrestore_no_auto_restore', 09:17:24 INFO - 'tabpaint']}, 09:17:24 INFO - 'other-e10s': {'tests': ['a11yr', 09:17:24 INFO - 'ts_paint', 09:17:24 INFO - 'tpaint', 09:17:24 INFO - 'sessionrestore', 09:17:24 INFO - 'sessionrestore_no_auto_restore', 09:17:24 INFO - 'tabpaint']}, 09:17:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:17:24 INFO - 'ts_paint', 09:17:24 INFO - 'tpaint', 09:17:24 INFO - 'sessionrestore', 09:17:24 INFO - 'sessionrestore_no_auto_restore', 09:17:24 INFO - 'tabpaint']}, 09:17:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:17:24 INFO - 'ts_paint', 09:17:24 INFO - 'tpaint', 09:17:24 INFO - 'sessionrestore', 09:17:24 INFO - 'sessionrestore_no_auto_restore', 09:17:24 INFO - 'tabpaint']}, 09:17:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:17:24 INFO - 'tests': ['a11yr', 09:17:24 INFO - 'ts_paint', 09:17:24 INFO - 'tpaint', 09:17:24 INFO - 'sessionrestore', 09:17:24 INFO - 'sessionrestore_no_auto_restore', 09:17:24 INFO - 'tabpaint']}, 09:17:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:17:24 INFO - 'tests': ['a11yr', 09:17:24 INFO - 'ts_paint', 09:17:24 INFO - 'tpaint', 09:17:24 INFO - 'sessionrestore', 09:17:24 INFO - 'sessionrestore_no_auto_restore', 09:17:24 INFO - 'tabpaint']}, 09:17:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:17:24 INFO - 'tests': ['tsvgx', 09:17:24 INFO - 'tsvgr_opacity', 09:17:24 INFO - 'tart', 09:17:24 INFO - 'tscrollx', 09:17:24 INFO - 'cart']}, 09:17:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:17:24 INFO - 'tsvgr_opacity', 09:17:24 INFO - 'tart', 09:17:24 INFO - 'tscrollx', 09:17:24 INFO - 'cart']}, 09:17:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:24 INFO - 'talos_options': ['--disable-e10s'], 09:17:24 INFO - 'tests': ['tp5o']}, 09:17:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:24 INFO - 'tests': ['tp5o']}, 09:17:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:24 INFO - 'talos_options': ['--disable-e10s', 09:17:24 INFO - '--xperf_path', 09:17:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:24 INFO - 'tests': ['tp5n']}, 09:17:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:24 INFO - 'talos_options': ['--xperf_path', 09:17:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:24 INFO - 'tests': ['tp5n']}}, 09:17:24 INFO - 'talos.zip': {'path': '', 09:17:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:17:24 INFO - Running post-action listener: _resource_record_post_action 09:17:24 INFO - [mozharness: 2016-09-08 16:17:24.280615Z] Finished populate-webroot step (success) 09:17:24 INFO - [mozharness: 2016-09-08 16:17:24.280724Z] Running create-virtualenv step. 09:17:24 INFO - Running pre-action listener: _resource_record_pre_action 09:17:24 INFO - Running main action method: create_virtualenv 09:17:24 INFO - Creating virtualenv /builds/slave/test/build/venv 09:17:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:17:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:17:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:17:24 INFO - Using real prefix '/tools/python27' 09:17:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:17:25 INFO - Installing distribute.............................................................................................................................................................................................done. 09:17:28 INFO - Installing pip.................done. 09:17:28 INFO - Return code: 0 09:17:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:17:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e417a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ac618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc2dd1d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37bd30>, '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 0x10e0e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e373b20>, '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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:17:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:17:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1PVSozYIbu/Render', 09:17:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:28 INFO - 'HOME': '/Users/cltbld', 09:17:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:28 INFO - 'LOGNAME': 'cltbld', 09:17:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:28 INFO - 'MOZ_NO_REMOTE': '1', 09:17:28 INFO - 'NO_EM_RESTART': '1', 09:17:28 INFO - 'PAGER': '/bin/cat', 09:17:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:28 INFO - 'PWD': '/builds/slave/test', 09:17:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:28 INFO - 'SHELL': '/bin/bash', 09:17:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ciMNoEGVwZ/Listeners', 09:17:28 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:17:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:28 INFO - 'USER': 'cltbld', 09:17:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:28 INFO - 'XPC_FLAGS': '0x0', 09:17:28 INFO - 'XPC_SERVICE_NAME': '0', 09:17:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:28 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:17:28 INFO - Cleaning up... 09:17:28 INFO - Return code: 0 09:17:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:17:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e417a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ac618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc2dd1d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37bd30>, '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 0x10e0e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e373b20>, '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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:17:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:17:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1PVSozYIbu/Render', 09:17:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:28 INFO - 'HOME': '/Users/cltbld', 09:17:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:28 INFO - 'LOGNAME': 'cltbld', 09:17:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:28 INFO - 'MOZ_NO_REMOTE': '1', 09:17:28 INFO - 'NO_EM_RESTART': '1', 09:17:28 INFO - 'PAGER': '/bin/cat', 09:17:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:28 INFO - 'PWD': '/builds/slave/test', 09:17:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:28 INFO - 'SHELL': '/bin/bash', 09:17:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ciMNoEGVwZ/Listeners', 09:17:28 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:17:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:28 INFO - 'USER': 'cltbld', 09:17:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:28 INFO - 'XPC_FLAGS': '0x0', 09:17:28 INFO - 'XPC_SERVICE_NAME': '0', 09:17:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:28 INFO - Downloading/unpacking psutil>=3.1.1 09:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:17:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:32 INFO - Installing collected packages: psutil 09:17:32 INFO - Running setup.py install for psutil 09:17:32 INFO - building 'psutil._psutil_osx' extension 09:17:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:17:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:17:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:17:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:17:33 INFO - building 'psutil._psutil_posix' extension 09:17:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:17:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:17:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:17:33 INFO - ^ 09:17:33 INFO - 1 warning generated. 09:17:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:17:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:33 INFO - Successfully installed psutil 09:17:33 INFO - Cleaning up... 09:17:33 INFO - Return code: 0 09:17:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:17:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e417a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ac618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc2dd1d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37bd30>, '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 0x10e0e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e373b20>, '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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:17:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:17:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1PVSozYIbu/Render', 09:17:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:33 INFO - 'HOME': '/Users/cltbld', 09:17:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:33 INFO - 'LOGNAME': 'cltbld', 09:17:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:33 INFO - 'MOZ_NO_REMOTE': '1', 09:17:33 INFO - 'NO_EM_RESTART': '1', 09:17:33 INFO - 'PAGER': '/bin/cat', 09:17:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:33 INFO - 'PWD': '/builds/slave/test', 09:17:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:33 INFO - 'SHELL': '/bin/bash', 09:17:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ciMNoEGVwZ/Listeners', 09:17:33 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:17:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:33 INFO - 'USER': 'cltbld', 09:17:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:33 INFO - 'XPC_FLAGS': '0x0', 09:17:33 INFO - 'XPC_SERVICE_NAME': '0', 09:17:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:17:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:17:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:17:36 INFO - Installing collected packages: mozsystemmonitor 09:17:36 INFO - Running setup.py install for mozsystemmonitor 09:17:36 INFO - Successfully installed mozsystemmonitor 09:17:36 INFO - Cleaning up... 09:17:36 INFO - Return code: 0 09:17:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e417a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ac618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc2dd1d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37bd30>, '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 0x10e0e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e373b20>, '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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:17:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1PVSozYIbu/Render', 09:17:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:36 INFO - 'HOME': '/Users/cltbld', 09:17:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:36 INFO - 'LOGNAME': 'cltbld', 09:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:36 INFO - 'MOZ_NO_REMOTE': '1', 09:17:36 INFO - 'NO_EM_RESTART': '1', 09:17:36 INFO - 'PAGER': '/bin/cat', 09:17:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:36 INFO - 'PWD': '/builds/slave/test', 09:17:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:36 INFO - 'SHELL': '/bin/bash', 09:17:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ciMNoEGVwZ/Listeners', 09:17:36 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:17:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:36 INFO - 'USER': 'cltbld', 09:17:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:36 INFO - 'XPC_FLAGS': '0x0', 09:17:36 INFO - 'XPC_SERVICE_NAME': '0', 09:17:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:36 INFO - Downloading/unpacking jsonschema==2.5.1 09:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:17:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:39 INFO - Downloading functools32-3.2.3-2.tar.gz 09:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:17:39 INFO - warning: no files found matching '*.txt' 09:17:39 INFO - no previously-included directories found matching 'build' 09:17:39 INFO - no previously-included directories found matching 'dist' 09:17:39 INFO - no previously-included directories found matching '.git*' 09:17:39 INFO - Installing collected packages: jsonschema, functools32 09:17:39 INFO - Running setup.py install for functools32 09:17:40 INFO - warning: no files found matching '*.txt' 09:17:40 INFO - no previously-included directories found matching 'build' 09:17:40 INFO - no previously-included directories found matching 'dist' 09:17:40 INFO - no previously-included directories found matching '.git*' 09:17:40 INFO - Successfully installed jsonschema functools32 09:17:40 INFO - Cleaning up... 09:17:40 INFO - Return code: 0 09:17:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:17: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')]} 09:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17: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')]} 09:17:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17: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', '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 0x10e417a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ac618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc2dd1d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37bd30>, '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 0x10e0e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e373b20>, '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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:17: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 functools32==3.2.3-2 09:17:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1PVSozYIbu/Render', 09:17:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:40 INFO - 'HOME': '/Users/cltbld', 09:17:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:40 INFO - 'LOGNAME': 'cltbld', 09:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:40 INFO - 'MOZ_NO_REMOTE': '1', 09:17:40 INFO - 'NO_EM_RESTART': '1', 09:17:40 INFO - 'PAGER': '/bin/cat', 09:17:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:40 INFO - 'PWD': '/builds/slave/test', 09:17:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:40 INFO - 'SHELL': '/bin/bash', 09:17:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ciMNoEGVwZ/Listeners', 09:17:40 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:17:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:40 INFO - 'USER': 'cltbld', 09:17:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:40 INFO - 'XPC_FLAGS': '0x0', 09:17:40 INFO - 'XPC_SERVICE_NAME': '0', 09:17:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:17:40 INFO - Cleaning up... 09:17:40 INFO - Return code: 0 09:17:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:17: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')]} 09:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17: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')]} 09:17:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17: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', '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 0x10e417a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ac618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc2dd1d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37bd30>, '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 0x10e0e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e373b20>, '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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:17: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 blobuploader==1.2.4 09:17:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1PVSozYIbu/Render', 09:17:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:40 INFO - 'HOME': '/Users/cltbld', 09:17:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:40 INFO - 'LOGNAME': 'cltbld', 09:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:40 INFO - 'MOZ_NO_REMOTE': '1', 09:17:40 INFO - 'NO_EM_RESTART': '1', 09:17:40 INFO - 'PAGER': '/bin/cat', 09:17:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:40 INFO - 'PWD': '/builds/slave/test', 09:17:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:40 INFO - 'SHELL': '/bin/bash', 09:17:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ciMNoEGVwZ/Listeners', 09:17:40 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:17:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:40 INFO - 'USER': 'cltbld', 09:17:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:40 INFO - 'XPC_FLAGS': '0x0', 09:17:40 INFO - 'XPC_SERVICE_NAME': '0', 09:17:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:40 INFO - Downloading/unpacking blobuploader==1.2.4 09:17: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 09:17: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 09:17: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 09:17: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 09:17: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 09:17: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 09:17:43 INFO - Downloading blobuploader-1.2.4.tar.gz 09:17:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:17:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:17:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:44 INFO - Downloading docopt-0.6.1.tar.gz 09:17:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:17:44 INFO - Installing collected packages: blobuploader, requests, docopt 09:17:44 INFO - Running setup.py install for blobuploader 09:17:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:17:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:17:44 INFO - Running setup.py install for requests 09:17:45 INFO - Running setup.py install for docopt 09:17:45 INFO - Successfully installed blobuploader requests docopt 09:17:45 INFO - Cleaning up... 09:17:45 INFO - Return code: 0 09:17:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:45 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 0x10e417a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ac618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc2dd1d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37bd30>, '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 0x10e0e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e373b20>, '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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:17:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:17:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1PVSozYIbu/Render', 09:17:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:45 INFO - 'HOME': '/Users/cltbld', 09:17:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:45 INFO - 'LOGNAME': 'cltbld', 09:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:45 INFO - 'MOZ_NO_REMOTE': '1', 09:17:45 INFO - 'NO_EM_RESTART': '1', 09:17:45 INFO - 'PAGER': '/bin/cat', 09:17:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:45 INFO - 'PWD': '/builds/slave/test', 09:17:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:45 INFO - 'SHELL': '/bin/bash', 09:17:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ciMNoEGVwZ/Listeners', 09:17:45 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:17:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:45 INFO - 'USER': 'cltbld', 09:17:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:45 INFO - 'XPC_FLAGS': '0x0', 09:17:45 INFO - 'XPC_SERVICE_NAME': '0', 09:17:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:45 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ZUAfCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:45 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-cANB5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:45 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-DdOF9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:45 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-VaFPvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:45 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ZP37ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-GeAaJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-QEA9jN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-dX2f5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-nXuAJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-pSJow4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-SJ1J4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-R1pnBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-hljfQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-AVEGBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:47 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-trKr5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:47 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-JCWXNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:47 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-hbGC32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:17:47 INFO - Running setup.py install for manifestparser 09:17:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:17:47 INFO - Running setup.py install for mozcrash 09:17:47 INFO - Running setup.py install for mozdebug 09:17:47 INFO - Running setup.py install for mozdevice 09:17:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:17:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:17:47 INFO - Running setup.py install for mozfile 09:17:48 INFO - Running setup.py install for mozhttpd 09:17:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Running setup.py install for mozinfo 09:17:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Running setup.py install for mozInstall 09:17:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Running setup.py install for mozleak 09:17:48 INFO - Running setup.py install for mozlog 09:17:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Running setup.py install for moznetwork 09:17:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Running setup.py install for mozprocess 09:17:49 INFO - Running setup.py install for mozprofile 09:17:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:17:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:17:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:17:49 INFO - Running setup.py install for mozrunner 09:17:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:17:49 INFO - Running setup.py install for mozscreenshot 09:17:49 INFO - Running setup.py install for moztest 09:17:49 INFO - Running setup.py install for mozversion 09:17:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:17:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:17:49 INFO - Cleaning up... 09:17:49 INFO - Return code: 0 09:17:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:49 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 0x10e417a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ac618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc2dd1d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37bd30>, '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 0x10e0e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e373b20>, '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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:17:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:17:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1PVSozYIbu/Render', 09:17:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:49 INFO - 'HOME': '/Users/cltbld', 09:17:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:49 INFO - 'LOGNAME': 'cltbld', 09:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:49 INFO - 'MOZ_NO_REMOTE': '1', 09:17:49 INFO - 'NO_EM_RESTART': '1', 09:17:49 INFO - 'PAGER': '/bin/cat', 09:17:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:49 INFO - 'PWD': '/builds/slave/test', 09:17:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:49 INFO - 'SHELL': '/bin/bash', 09:17:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ciMNoEGVwZ/Listeners', 09:17:49 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:17:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:49 INFO - 'USER': 'cltbld', 09:17:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:49 INFO - 'XPC_FLAGS': '0x0', 09:17:49 INFO - 'XPC_SERVICE_NAME': '0', 09:17:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-RtQs6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ggnK9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-svG35o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-GMEfVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-RmjQYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-twvv_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-3Fg4_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-qMq5kt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:51 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-qtp7gB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:51 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-FHkzUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:51 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-_ynUlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:51 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-rYrp1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:51 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-pPyOxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:51 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-hOA8us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:51 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-wXprUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:51 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Ygrqgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:51 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-8i7Ebq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:17:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:54 INFO - Downloading blessings-1.6.tar.gz 09:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:17:54 INFO - Installing collected packages: blessings 09:17:54 INFO - Running setup.py install for blessings 09:17:54 INFO - Successfully installed blessings 09:17:54 INFO - Cleaning up... 09:17:54 INFO - Return code: 0 09:17:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:17:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:55 INFO - Reading from file tmpfile_stdout 09:17:55 INFO - Current package versions: 09:17:55 INFO - blessings == 1.6 09:17:55 INFO - blobuploader == 1.2.4 09:17:55 INFO - docopt == 0.6.1 09:17:55 INFO - functools32 == 3.2.3-2 09:17:55 INFO - jsonschema == 2.5.1 09:17:55 INFO - manifestparser == 1.1 09:17:55 INFO - mozInstall == 1.12 09:17:55 INFO - mozcrash == 0.17 09:17:55 INFO - mozdebug == 0.1 09:17:55 INFO - mozdevice == 0.48 09:17:55 INFO - mozfile == 1.2 09:17:55 INFO - mozhttpd == 0.7 09:17:55 INFO - mozinfo == 0.9 09:17:55 INFO - mozleak == 0.1 09:17:55 INFO - mozlog == 3.3 09:17:55 INFO - moznetwork == 0.27 09:17:55 INFO - mozprocess == 0.23 09:17:55 INFO - mozprofile == 0.28 09:17:55 INFO - mozrunner == 6.12 09:17:55 INFO - mozscreenshot == 0.1 09:17:55 INFO - mozsystemmonitor == 0.3 09:17:55 INFO - moztest == 0.7 09:17:55 INFO - mozversion == 1.4 09:17:55 INFO - psutil == 3.1.1 09:17:55 INFO - requests == 1.2.3 09:17:55 INFO - wsgiref == 0.1.2 09:17:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:55 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 0x10e417a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ac618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc2dd1d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37bd30>, '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 0x10e0e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e373b20>, '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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:17:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1PVSozYIbu/Render', 09:17:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:55 INFO - 'HOME': '/Users/cltbld', 09:17:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:55 INFO - 'LOGNAME': 'cltbld', 09:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:55 INFO - 'MOZ_NO_REMOTE': '1', 09:17:55 INFO - 'NO_EM_RESTART': '1', 09:17:55 INFO - 'PAGER': '/bin/cat', 09:17:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:55 INFO - 'PWD': '/builds/slave/test', 09:17:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:55 INFO - 'SHELL': '/bin/bash', 09:17:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ciMNoEGVwZ/Listeners', 09:17:55 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:17:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:55 INFO - 'USER': 'cltbld', 09:17:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:55 INFO - 'XPC_FLAGS': '0x0', 09:17:55 INFO - 'XPC_SERVICE_NAME': '0', 09:17:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:17:55 INFO - Cleaning up... 09:17:55 INFO - Return code: 0 09:17:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:55 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 0x10e417a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ac618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc2dd1d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e37bd30>, '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 0x10e0e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e373b20>, '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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:17:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1PVSozYIbu/Render', 09:17:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:55 INFO - 'HOME': '/Users/cltbld', 09:17:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:55 INFO - 'LOGNAME': 'cltbld', 09:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:55 INFO - 'MOZ_NO_REMOTE': '1', 09:17:55 INFO - 'NO_EM_RESTART': '1', 09:17:55 INFO - 'PAGER': '/bin/cat', 09:17:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:55 INFO - 'PWD': '/builds/slave/test', 09:17:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:55 INFO - 'SHELL': '/bin/bash', 09:17:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ciMNoEGVwZ/Listeners', 09:17:55 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:17:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:55 INFO - 'USER': 'cltbld', 09:17:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:55 INFO - 'XPC_FLAGS': '0x0', 09:17:55 INFO - 'XPC_SERVICE_NAME': '0', 09:17:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:17:55 INFO - Cleaning up... 09:17:55 INFO - Return code: 0 09:17:55 INFO - Running post-action listener: _resource_record_post_action 09:17:55 INFO - Running post-action listener: _start_resource_monitoring 09:17:55 INFO - Starting resource monitoring. 09:17:55 INFO - [mozharness: 2016-09-08 16:17:55.628611Z] Finished create-virtualenv step (success) 09:17:55 INFO - [mozharness: 2016-09-08 16:17:55.629612Z] Running install step. 09:17:55 INFO - Running pre-action listener: _resource_record_pre_action 09:17:55 INFO - Running main action method: install 09:17:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:55 INFO - Reading from file tmpfile_stdout 09:17:55 INFO - Detecting whether we're running mozinstall >=1.0... 09:17:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:17:55 INFO - Reading from file tmpfile_stdout 09:17:55 INFO - Output received: 09:17:55 INFO - Usage: mozinstall [options] installer 09:17:55 INFO - Options: 09:17:55 INFO - -h, --help show this help message and exit 09:17:55 INFO - -d DEST, --destination=DEST 09:17:55 INFO - Directory to install application into. [default: 09:17:55 INFO - "/builds/slave/test"] 09:17:55 INFO - --app=APP Application being installed. [default: firefox] 09:17:55 INFO - mkdir: /builds/slave/test/build/application 09:17:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:18:29 INFO - Reading from file tmpfile_stdout 09:18:29 INFO - Output received: 09:18:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:18:29 INFO - Running post-action listener: _resource_record_post_action 09:18:29 INFO - [mozharness: 2016-09-08 16:18:29.718634Z] Finished install step (success) 09:18:29 INFO - [mozharness: 2016-09-08 16:18:29.718766Z] Running run-tests step. 09:18:29 INFO - Running pre-action listener: _resource_record_pre_action 09:18:29 INFO - Running main action method: run_tests 09:18:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:18:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:18:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:18:29 INFO - 2016-09-08 09:18:29.733 screenresolution[1646:8109] starting screenresolution argv=screenresolution get 09:18:29 INFO - 2016-09-08 09:18:29.735 screenresolution[1646:8109] Display 0: 1600x1200x32@60 09:18:29 INFO - 2016-09-08 09:18:29.741 screenresolution[1647:8111] starting screenresolution argv=screenresolution list 09:18:29 INFO - Available Modes on Display 0 09:18:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:18:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:18:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:18:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:18:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:18:29 INFO - Intel Iris: 09:18:29 INFO - Chipset Model: Intel Iris 09:18:29 INFO - Type: GPU 09:18:29 INFO - Bus: Built-In 09:18:29 INFO - VRAM (Dynamic, Max): 1536 MB 09:18:29 INFO - Vendor: Intel (0x8086) 09:18:29 INFO - Device ID: 0x0a2e 09:18:29 INFO - Revision ID: 0x0009 09:18:29 INFO - Displays: 09:18:29 INFO - Display: 09:18:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:18:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:18:29 INFO - Display Serial Number: 1600x1200 60 09:18:29 INFO - Main Display: Yes 09:18:29 INFO - Mirror: Off 09:18:29 INFO - Online: Yes 09:18:29 INFO - Rotation: Supported 09:18:29 INFO - Return code: 0 09:18:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:18:29 INFO - Python 2.7.3 09:18:29 INFO - Return code: 0 09:18:29 INFO - grabbing minidump binary from tooltool 09:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:29 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 0x10e37bd30>, '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 0x10e0e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e373b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:18:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:18:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:18:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:18:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:18:30 INFO - Return code: 0 09:18:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:18:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:18:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:18:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:18:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:18:30 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/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:18:30 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/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0067 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:18:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1PVSozYIbu/Render', 09:18:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:30 INFO - 'HOME': '/Users/cltbld', 09:18:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:30 INFO - 'LOGNAME': 'cltbld', 09:18:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:18:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:30 INFO - 'MOZ_NO_REMOTE': '1', 09:18:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:18:30 INFO - 'NO_EM_RESTART': '1', 09:18:30 INFO - 'PAGER': '/bin/cat', 09:18:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:30 INFO - 'PWD': '/builds/slave/test', 09:18:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:18:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:30 INFO - 'SHELL': '/bin/bash', 09:18:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ciMNoEGVwZ/Listeners', 09:18:30 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:18:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:30 INFO - 'USER': 'cltbld', 09:18:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:30 INFO - 'XPC_FLAGS': '0x0', 09:18:30 INFO - 'XPC_SERVICE_NAME': '0', 09:18:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:30 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/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:18:30 INFO - mozversion application_buildid: 20160908062131 09:18:30 INFO - mozversion application_changeset: d441ac0b35aedaf1df5ffe995cd4ffaaa697ee49 09:18:30 INFO - mozversion application_display_name: Nightly 09:18:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:18:30 INFO - mozversion application_name: Firefox 09:18:30 INFO - mozversion application_remotingname: firefox 09:18:30 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:18:30 INFO - mozversion application_vendor: Mozilla 09:18:30 INFO - mozversion application_version: 51.0a1 09:18:30 INFO - mozversion platform_buildid: 20160908062131 09:18:30 INFO - mozversion platform_changeset: d441ac0b35aedaf1df5ffe995cd4ffaaa697ee49 09:18:30 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:18:30 INFO - mozversion platform_version: 51.0a1 09:18:30 INFO - using testdate: 1473351510 09:18:30 INFO - actual date: 1473351510 09:18:30 INFO - starting webserver on 'localhost:49268' 09:18:30 INFO - SUITE-START | Running 2 tests 09:18:30 INFO - TEST-START | dromaeo_css 09:18:30 INFO - operating with platform_type : mac_ 09:18:30 INFO - Initialising browser for dromaeo_css test... 09:18:30 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpvB6HMM/profile http://localhost:49268/getInfo.html) 09:18:33 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 09:18:33 INFO - PROCESS | 1657 | colorDepth:24 09:18:33 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 09:18:33 INFO - PROCESS | 1657 | __metrics 09:18:33 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 09:18:33 INFO - TEST-INFO | 1657: exit 0 09:18:33 INFO - Browser initialized. 09:18:33 INFO - Running cycle 1/1 for dromaeo_css test... 09:18:33 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpvB6HMM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:18:45 INFO - PROCESS | 1661 | RSS: Main: 244183040 09:18:45 INFO - PROCESS | 1661 | 09:19:35 INFO - PROCESS | 1661 | 1473351575435 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:21:47 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 09:21:48 INFO - PROCESS | 1661 | RSS: Main: 261304320 09:21:48 INFO - PROCESS | 1661 | 09:24:50 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 09:24:51 INFO - PROCESS | 1661 | RSS: Main: 267640832 09:24:51 INFO - PROCESS | 1661 | 09:27:53 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 09:27:54 INFO - PROCESS | 1661 | RSS: Main: 273629184 09:27:54 INFO - PROCESS | 1661 | 09:30:56 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 09:30:56 INFO - PROCESS | 1661 | RSS: Main: 268959744 09:30:56 INFO - PROCESS | 1661 | 09:33:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 09:34:00 INFO - PROCESS | 1661 | RSS: Main: 274300928 09:34:00 INFO - PROCESS | 1661 | 09:37:02 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 09:37:03 INFO - PROCESS | 1661 | RSS: Main: 277266432 09:37:03 INFO - PROCESS | 1661 | 09:37:03 INFO - PROCESS | 1661 | __start_tp_report 09:37:03 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 09:37:03 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 09:37:03 INFO - PROCESS | 1661 | |i|pagename|runs| 09:37:03 INFO - PROCESS | 1661 | |0;dojo.html;3357.3956687796194;3382.103742508236;3394.2193295542024;3415.795052296862;3429.2112814052753;5366.8389948301565;5732.369439361677;5889.705514724268;5984.04255319149;5987.6407415555095;5881.323647780507;5884.029135192688;5927.347991720918;5944.227250457446;6015.368386319428;5239.502247286502;5345.064613692608;5352.652077614943;5449.209864569635;5452;5416.91874621881;5456.236126942231;5465.699386533739;5495.258140151089;5521.751521181556;5065.924011139854;5165.457626949192;5222.3472065991755;5297.576193904479;5361.490658387447;5315.681059136426;5320.600954928396;5324.068288049576;5344.385395679507;5359.571234301286;5303.5226829585135;5352.678839269656;5369.382521010056;5387.838364849061;5388.811391601275;5737.110747834093;5826.126081087828;5909.527237820964;5936.554758393095;5945.4946329562035;5224.451432599599;6255.78104766331;6272.529560282951;6294.898392781291;6307.369263073701;6324.9051264231075;6355.459785918202;6367.745290188387;6373.42639162473;6387.361263873622;5877.412258774131;5954.761809527614;5972.999999999956;5995.160677505153;6095.329513753483;6423.4861211103;6468.741250350027;6489.578177418547;6508.739650414025;6510.804675859732;5152.871178220499;6252.124702541648;6273.62358258506;6293.653849038347;6315.210598675166;6114.145302971479;6209.689515524296;6262.843428914313;6304.509718661103;6352.936470635327;6128.472568454217;6176.444120029129;6229.906551401733;6258.655773932477;6318.778842740574;5224.608154388436;6262.903991801417;6275.780347687809;6285.717142728588;6289.591176573508;4989.950100499022;5029.245613158056;5056.140456122468;5101.897962040738;5104.2088476286235;4524.16302983949;4533.818647254139;4549.3176023596725;4618.422697162855;4626.86119416418;5918.7928422505;5981.312149102821;5988.341282458926;5994.940050599438;5997.850053748692;5186;5509.650135716775;6194.628322300677;6336.366363363719;6336.6831658416895;4685.906281874343;4689.273162659793;5028.64799464034;5258.39528454225;5910.704464776744;5557.249771280906;5560.388357280696;5560.388357280696;5583.832485025456;5626.493615574536;6040.822039702216;6157.4458298753325;6411.9740841465145;6412.294647588761;6523.934760652332;6234.594751341148;6363.90454143179;6367.52243581724;6382.20222472191;6393.840153996187;6473.1261279728515;6548.967255163694;6555.541112122296;6556.213254409501;6605.768798092044;6097.766665674493;6138.81758342749;6289.40250676185;6292.52806039558;6302.873942521032;6209.192804935329;6218.844528886633;6277.466415354746;6280.560360774702;6282;6033.42682445167;6237.75048998035;6240.344763799736;6246.562740608114;6261.279952805486;6095.438338978274;6150.907736383869;6239.844003899939;6261.279952805486;6261.937380626136;6053.667048312385;6059;6134.9079763804475;6161.291451482957;6164.753409863555;6180.196574445294;6193.433983691915;6235.282942461494;6264.436200741955;6272.121902933502;6002.159697642246;6110.236220472441;6169.444749972524;6173.6913154343;6193.256809182927 09:37:03 INFO - PROCESS | 1661 | |1;ext.html;491.29989764585457;527.2855281094118;529.4811085136566;533.9800980127956;556.6103727390822;38606.420903686434;41605.583944160586;41702.37446438302;41768.164636707326;41789.79105104478;42339.78830105853;42915.14169716604;42991.78504107475;43060.56939430605;43939.56060439395;9552.665656702016;9667.516624168798;9737.659181928633;9741.464219467927;9807.509624518781;9665.22678185747;9701.563429645683;9746.366486178376;9785.412875227508;9791.755206119833;9801.607935682565;9814.852777208347;9821.754456138582;9822.410655360702;9827.803443931118;9736.610535578568;9784.95107524465;9793.804123917482;9802.117809397188;9815.165710914582;9668.274879384004;9730.951345243298;9734.561944712505;9752.21982241419;9778.315517913748;40131.3980290293;40177.59822401797;40253.39619905704;40482.987925302106;40547.98630034243;3317.717993970515;3329.5505106810756;3370.0900756795527;3395.6434574369828;3398.9490157647406;26809.79355928988;30878.22804429885;31181.752729890777;31633;32196;3224.967750322514;3245.496947973068;3274.7052765251005;3356.9664303357144;3384.5769278840144;361411;362053;454433;457247;458478.70760646317;30952.845235774126;34059.65940340614;57349.13976290359;57543;57666.42333576694;34094.48858267128;34247.65752342495;34394.796182133505;34628.44172012265;34632.13419664528;14701.04443211188;14915;14919.104853708595;14945.17801520905;14946.32741526634;12691.301978391093;12712.237265764084;12725.427355769012;12762.42569084375;12790.168639038618;7654.387648988068;7659.272369124924;7727.227277272228;7826.530408175528;7844.058712954368;3217.3565286942703;3257.3648138613216;3262.217067903686;3268.3790079884748;3282.9179270517952;827.9420440569223;915.9633614655339;1058.5871510111062;1068.1935138970027;1119.994400028011;25444.363890902507;29493.557596636067;30636.54045189324;31172.37655246928;31281.530777038362;850.9829803403898;884.4825776920445;1020.1583693452931;1027.4503140819625;1110.4558766204502;13670.52153174634;13677.931610342084;15686.05883646962;16029.67940641204;16083.919580402024;699862;711451;712492;712706;714594;59065.40934590685;59351.406485935455;59506.40493595095;59610.70194649086;59703.70148149232;1340.9195448273135;1342.4428862022346;1374.7731624281882;1376.9793453098212;1387.6253411578816;25114;27274.318142046606;28691.426171476873;29635.851820740758;30531.847340763154;52046.47953520416;52320.738396307774;52384.476155239485;52389.7380513095;52456.737716311174;599962;603541;605846;606273;606961.9651901713;26522.469550608494;26823.061192858157;27120.864395677894;27319.590206146528;28337;47772.2834157481;47835.80410489766;49052.75473622609;49381.75309123431;49621.503784961686;39587.406188906614;40123.598764011986;40397.334563840115;40985.795071024455;41579.792101039304;39858.60141398549;39913.401298981116;40057.599424005384;41061.794691026356;41204.175916480905;37531.43702844514;38346.80826595849;39281;39347.60652393554;39586.40620390754;32867;35126.77056303017;35383.646163539066;35613.64386356207;35766.28467430585;24230.75769242285;24320.51358972846;24866.88099035584;25150.496990060463;25892.870535647202 09:37:03 INFO - PROCESS | 1661 | |2;jquery.html;369.97965111918853;372.7558449215764;374.72083297943027;384.35236626284694;400.92783299006186;18899.77151485154;20393.796062039393;20558.972051397428;20773.883917412397;21034.684479732816;19872.40382788515;20122.798772012276;20550;20630.071646775894;20803.959802009915;4557.407537020183;5824.679642619675;5889.793857214999;6148.692565371736;6188.350223226564;6021.217241758565;6106.816795496142;6129.264488261415;6129.356417576158;6154.446099851012;4648.48866624671;5931.228940237763;6026.156338112668;6047.032474804032;6062.666553339586;5764.193012978187;5945.16767652529;5993.490553302952;5999.190109335271;6032.607880487776;5169.741512924339;5204.42751297357;5221.451747566489;5238.449962753894;5261.1319132343115;20734.792652073436;25255.242342729747;25344.746552534423;25366.239012829643;25552.744472555223;2095.8323334133383;2187.409399462152;2214.977850221493;2243.9663405048937;2373.8694371809543;15248.466303679428;15457.922710386489;15692;15827;16225;1499.7825315329217;1831.642829648219;1833.468294194682;1846.9538261543569;1933.6712758831034;119655;124047;124247.9999999991;124434;124511.13233301508;10372.533236004247;10510.947445262726;10782.568697252022;11026.173037022256;11365.147613928988;13587.524436644868;13689.726205476034;13704.177749335071;13712.588622341345;13712.65718357049;8305.79565962944;8588.055313915589;8588.8711669325;8630.654773809103;8674.08922063187;8556.529390883561;8588.398812083095;8621.31029517637;8720.607572659246;8744.912550874536;5481.917771233355;5855;5945.16767652529;5967.433093856077;5972.402759723993;1469.4563011685532;1706.871984601135;1924.9711254331196;1927.4603111128906;1933.951651208703;862.1723145779996;895.4627223665749;942.1191186240886;988.3921388346259;997.7206382212992;21444.463888402915;21768.80271585078;21834.344969650934;21930.342089737333;22131.889340553193;933.7945651956559;947.9146876781123;958.566942421085;1010.2726037253166;1040.2760884827946;11511.539538418538;11712.941435292769;11779.411029448493;11803.52785888572;11823.704407389712;552788;599606.0315174776;602244;603339;622392;117683.0579235526;120012;123692;125774.37112814377;126269.895571658;967.6371360739722;985.4383001689112;1010.545254635417;1013.8479228115841;1018.6282007067367;18447.446576602724;18695.439136825917;18702.849172345355;18753;18948.621027579647;55336;55436.445635543125;58258;58659;59016.934407295535;86964.1303586956;87148.56425717831;87696.561517192;88000;90011;7782.883256751266;7876.6849326028605;7900.644470998934;8068.798280043045;8164.7550573482895;10736.355818650905;11493.597724079616;12692.365381730691;12707.745845083231;12809.551665691655;11002.284851484628;11104.944475277573;11201.159913006393;11267.830982535104;11385.772284554429;10823.567057317934;10868.836967445337;10900.836487452536;11261.71845703864;11270.83093753578;11018.614348497764;11353.716157096138;11471.655850324503;11479.655610331703;11515.92713311612;7765.650545725569;7841.490303130278;7984.001999750031;7989.760307190793;8001.199880011952;10788.352698838095;11158.609448669258;11244;11257.774844503228;11269.098472122047 09:37:03 INFO - PROCESS | 1661 | |3;mootools.html;1355.491690616019;1512;1696.1604006017023;1857.9256829726812;1878.9530261743455;7948.9602551987155;8408.705695300665;8642.308615310776;9006.954965225164;9036.457812531253;6857.485688573364;8385.951756030496;8671.91328086719;8858.468491890491;8874.245689116417;1754.9912250438763;2413.3966508372905;2416.4804567018086;2417.3835671903726;2421.7093948726088;2357.834951553392;2387.6060450025725;2416.0818888822223;2417.286900364397;2420.20133355993;2108.6520724080583;2390.557746816836;2397.712274527059;2406.795422389099;2423.7333893271725;2311.3297143828268;2363.929082127539;2380.547695937766;2397.2808157552663;2401.3756423329887;2352.811775058008;2377.7741114594087;2382.225776622587;2384.833061685683;2385.7256415512265;7658.923410765877;8829.779255518664;8903.065178156265;8914.554272286425;8923.018467968519;5242.55438287746;5386.407495175527;5400.054990376708;5401.567874570064;5425.864353391157;3527.6119626841282;3535.381308271068;3560.9821950890337;3598.2623562169692;3602.5676918769914;1081.1891081688734;1090.198703952602;1095.1020163465962;1106.6790630717162;1117.9105671546256;11534.711632209179;11577.94211028948;11608.24546404481;11632.243904146204;11641.941790290994;4535.569120933506;4625.283081122431;4789.281607758864;4795.064962332312;4813.109574728686;9820.263480239011;9921.851172232422;9944.303898727165;9944.651937182309;9973.60105595768;5300.469953004746;5333.57331413486;5359.410464848864;5362.2224777407055;5366.195070739343;4395.846145384896;4745.928811067837;4893;4907.141250281258;4911.607071434277;3860.343741563941;3924.117073658404;4002.1795531915886;4005.9799701001893;4021.7076266528384;1288.6005338345144;1295.812107244463;1300.486307908371;1305.09948135786;1305.8628843971437;832.629479881459;842.9072801991776;852.7015544559355;878.7188099808128;912.762681702762;5313.203019547099;5455.5908306877145;5530.032244357221;5687.260656114679;5865.941340586625;414.6330497509726;437.3046855499707;440.90961352923216;447.2620176708457;447.61952340510305;3809.695224382043;3816.46569480273;3907.5897030811925;3939.350007248829;4015.975926138816;10409.739756505996;10875.891241087487;10907.072898803532;10909.290896091728;11035.44822758859;8802;10587.682369528926;10807.945960270306;10850.29473084247;10852.565897364175;8097.473664211808;8197.303229225463;8241.25828675422;8301.252887240178;8304.7542868569;5695;5815.185873977731;5840.269966254218;5854.507100689297;5854.853628659318;9626.566804493674;10095.89904100979;10150.847737283799;10200.540975655966;10212.948935255276;10213.285070045025;10220.386776793417;10272.897271027194;10293.485325733833;10346.068853803194;6771.830704232433;6835.829104272433;7270;7408.962955185405;7483.289087536676;7384.929185267999;7661.731839385818;8336.541490218096;8360.205780450848;8410.621522031646;7417.629118544159;7509.3617042552;7555.546667199986;7558.9244107558225;7673.386129109769;7337.413006959324;7347.33873951347;7402.740904068547;7457.47797654159;7491.70033198666;6811.69347379379;6883.586984780929;6964.199117101598;6975.895361569479;6999.12510936133;4204.264253755642;4860.226186683606;4972;5020.397552293748;5053.36832895888;4872.439669437918;4891.73095479752;4960;5030.446650868328;5047.545720885138 09:37:03 INFO - PROCESS | 1661 | |4;prototype.html;309.9054788289571;310.57141145219595;320.1069017441339;320.1979042498541;323.1936318884384;5381.973090134554;5523.453433038756;5585.0784620537615;5678.558219965414;5722.656640601562;5271.130263506517;5556.333240011194;5579;5591.608587398884;5621.690807005613;3912.3740201567753;4271.850485233017;4273.529911709709;4278.208531421681;4283.400323954641;4072.8188825240645;4196.685248606352;4228.281192197333;4275.679324050693;4291;4181.498220213563;4189.49726032875;4199.593136299343;4224.957750422503;4225.197212529625;3568.536090308269;3584.551931008624;4020.7185497015075;4227.239096962546;4244.410909345297;3659.4144936809985;3673.3755261605593;3685.0971511979737;3715.9071023224637;3740.8116251992615;4668.253079507263;5058.519440653132;5207.244949482304;5413.729313534347;5570.832875013756;4665.88335291617;4773.570378665937;4774.307725379836;4787.377640906695;4789.521047895218;4871.853844384638;4975.606830087581;5187.6512106852115;5899.764009439617;5927.762889484416;3900.9219815603815;3914.0625390431633;3918.784466854322;3936.960630393688;3962.7820469874146;5418.783248670048;6176.227971503562;6197.783077592308;6200.534959877982;6219.50243980485;3971.483707118114;3979.8607048753156;3982.3628219484744;3995.2409042282457;4012.9799351003057;3764.378877485184;4005.459262999516;4043.6765058795227;4053.8986525336513;4065.5324637666454;3996.980015099964;4272.931767058236;4756.548127927841;4761.738174367148;4820.638452116105;4468.530804265571;5199.844004679866;5583.246261754611;5923.318818335861;6016.518678505709;1105.6738262212666;1371.9984411379749;1406.367134789349;1439.2659743530664;1454.185656032625;775.8874963130314;883.4610887358706;931.7484279244566;950.4914870544226;986.8963758805365;4586.4266966629175;4680.461746899082;4685.250359942393;4688.507706690748;4705.835295764632;4711.246200607886;4732.787024583902;5134.947335796152;5138;5144.225242291403;4135.937960930589;4359.825606975749;4617.215073437456;4624.815007399667;4625.843539115222;2371.8102551795814;2523.9273308843667;2605.7654811067096;2611.2818974782085;2698.892044318205;4825.852495639477;4874.683145595525;4950.579200767975;5013.39839219296;5271.4464981176425;4735.732344682158;4835.24605719193;4906.828261010847;5078.619103567247;5123.590112790967;5277.551408130275;5562.3047119110115;6082.931803186938;6326.165412030459;6372.107904893412;6175.722092505758;6177.62934223948;6199.628022318675;6233.283172435228;6247.781327653692;5583.2462617547735;5776.711164441677;6025.397460254115;6114.785982490769;6176.752929882754;5812.6049748061005;5964.735214321608;6363.5545511813725;6444.45222156122;6449.774257900951;3724.2179142380405;4050.5746896576616;4051.39229115635;4073.124278280184;4082.652974497201;5795.710214489343;6020.51835853122;6026.698665066817;6047.485963693135;6080.543959202989;5314;5560.026995275892;5974.701264936649;5996.430339117776;6047.818565442868;5316.6544174628525;5926.7925622603;5941.15824094532;5962.850928726816;5988.6706231157705;4739.905201896012;5708.372079071381;5789.3921138279875;5809.215755873072;5851.268591426072;5054.826424637441;5969.73136208863;5975.312839023568;5995.88008239824;6031.366706495754;4884.511548845087;5623.493885550321;5878.823635290948;5881.235439392852;5897.941020589739 09:37:03 INFO - PROCESS | 1661 | |5;yui.html;703.5005146346094;733.6991833348328;740.822202671359;764.0754686828936;808.6563210635478;14885.106893586379;15656.217189140554;15682.294296756645;15685.215739213036;15703.450379236729;11871.70320741982;13520.391582378792;14016.719665606683;14085.929570352135;14139.646508837284;826.8263664630434;835.5863847395517;839.1314988986401;843.5613480989881;848.4188330993259;832.1803024021334;845.3955421873359;847.6609356257485;850.4557083466586;850.5152063323908;703.7747920665389;872.62477134832;898.0525545549443;901.8647202919549;902.0483390023522;874.6501399440211;877.5261358866204;885.1679421343916;890.5191196753746;890.5858775669312;660.5541259649717;663.1909070933501;663.9004149377583;665.9766908158191;666.1173944523478;14297.85702142986;14411.279436028157;14527.418903243857;14566.344514496874;14600.561983140522;11549.884501154966;11793.882061179364;13549.119307245097;13852.584422467342;14271.714565708628;11781.823272650918;12216.389180540937;12604;12608.621741347773;12616.621501354974;4541.886452838672;4548.579627544562;4549.61328287096;4634.096351211515;4770.522947705236;97008;121873.39063304715;122608;124515;125507;9168.174864262248;11014.229003969645;11079.667609971713;11100.278481898651;11165.162612804072;17974.37089701854;21096.578068438546;21629.24297649575;21645;21658;6960.756373526902;7613.695452181962;7696.884546731803;7730.342920851678;7837.9216207838335;4203.76879271643;4248.638865696388;4316.740995540277;4404.427424434803;4476.2837945928495;3367.1750421146976;3482.791032538056;3501.0197144799495;3513.2270900401872;3543.7342199335153;635.2123367024948;642.0497663458108;643.462708638283;648.8832010238202;660.1055569703079;539.9973000135053;583.5851781274644;599.2090440618342;626.6208943589063;628.4752231886334;9106.180443760093;9205.81588368229;9227.953860230657;9250.90749092514;9275.860862087075;590.2032256453753;607.5868409481595;611.2054329371888;611.721666641677;629.4955057412787;4405.449318835145;4414.911701766011;4432.82268709248;4482.327650852333;4494.438195225597;144805.27597361946;145713;162478;163609;168009;124541;133001;138133.3093334527;140084.2995785035;145946.2702686501;63119;112618.87381126084;124861;131468.34265828802;131994.34002829925;8465.98408191021;8736.56317184151;8740.78148046304;8747.60635771379;8924.955375223082;67088.6645566769;67263.99104013346;68846.65576672084;69576.95634565587;70280;66151;70091.64954175196;99783;101708;105611;11304.265222760494;11518.308901465938;11532.365719885487;11704;12094.213876098025;12101.394930253628;12117.515299388278;12153.817692734792;12493.18794278369;12502.937485312515;11246.493907774333;11686.766264674488;11802.52789888395;12136.817947730968;12181.939090304846;11541.884581154081;11758.82361764557;11876.465559050037;12090.093243006633;12165.817512737493;9692.806143877224;11177.88822111801;11543.942280288546;11781.998530125036;11867.05063594893;9077.319201059814;9640.204916405315;9854.55654495535;9885;10225.233107516817;7525.510841795266;8317.792055198668;8966.282697384324;9647.469389183661;9664.565094570902 09:37:03 INFO - PROCESS | 1661 | __end_tp_report 09:37:03 INFO - PROCESS | 1661 | __start_cc_report 09:37:03 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,371 09:37:03 INFO - PROCESS | 1661 | __end_cc_report 09:37:03 INFO - PROCESS | 1661 | __startTimestamp1473352623191__endTimestamp 09:37:03 INFO - PROCESS | 1661 | 09:37:03 INFO - PROCESS | 1661 | ------- Summary: start ------- 09:37:03 INFO - PROCESS | 1661 | Number of tests: 6 09:37:03 INFO - PROCESS | 1661 | 09:37:03 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5818.29 Median:6053.67 stddev:659.09 (10.9%) stddev-sans-first:632.20 09:37:03 INFO - PROCESS | 1661 | Values: 3357.4 3382.1 3394.2 3415.8 3429.2 5366.8 5732.4 5889.7 5984.0 5987.6 5881.3 5884.0 5927.3 5944.2 6015.4 5239.5 5345.1 5352.7 5449.2 5452.0 5416.9 5456.2 5465.7 5495.3 5521.8 5065.9 5165.5 5222.3 5297.6 5361.5 5315.7 5320.6 5324.1 5344.4 5359.6 5303.5 5352.7 5369.4 5387.8 5388.8 5737.1 5826.1 5909.5 5936.6 5945.5 5224.5 6255.8 6272.5 6294.9 6307.4 6324.9 6355.5 6367.7 6373.4 6387.4 5877.4 5954.8 5973.0 5995.2 6095.3 6423.5 6468.7 6489.6 6508.7 6510.8 5152.9 6252.1 6273.6 6293.7 6315.2 6114.1 6209.7 6262.8 6304.5 6352.9 6128.5 6176.4 6229.9 6258.7 6318.8 5224.6 6262.9 6275.8 6285.7 6289.6 4990.0 5029.2 5056.1 5101.9 5104.2 4524.2 4533.8 4549.3 4618.4 4626.9 5918.8 5981.3 5988.3 5994.9 5997.9 5186.0 5509.7 6194.6 6336.4 6336.7 4685.9 4689.3 5028.6 5258.4 5910.7 5557.2 5560.4 5560.4 5583.8 5626.5 6040.8 6157.4 6412.0 6412.3 6523.9 6234.6 6363.9 6367.5 6382.2 6393.8 6473.1 6549.0 6555.5 6556.2 6605.8 6097.8 6138.8 6289.4 6292.5 6302.9 6209.2 6218.8 6277.5 6280.6 6282.0 6033.4 6237.8 6240.3 6246.6 6261.3 6095.4 6150.9 6239.8 6261.3 6261.9 6053.7 6059.0 6134.9 6161.3 6164.8 6180.2 6193.4 6235.3 6264.4 6272.1 6002.2 6110.2 6169.4 6173.7 6193.3 09:37:03 INFO - PROCESS | 1661 | 09:37:03 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:69681.42 Median:40127.50 stddev:158657.86 (395.4%) stddev-sans-first:159017.89 09:37:03 INFO - PROCESS | 1661 | Values: 491.3 527.3 529.5 534.0 556.6 38606.4 41605.6 41702.4 41768.2 41789.8 42339.8 42915.1 42991.8 43060.6 43939.6 9552.7 9667.5 9737.7 9741.5 9807.5 9665.2 9701.6 9746.4 9785.4 9791.8 9801.6 9814.9 9821.8 9822.4 9827.8 9736.6 9785.0 9793.8 9802.1 9815.2 9668.3 9731.0 9734.6 9752.2 9778.3 40131.4 40177.6 40253.4 40483.0 40548.0 3317.7 3329.6 3370.1 3395.6 3398.9 26809.8 30878.2 31181.8 31633.0 32196.0 3225.0 3245.5 3274.7 3357.0 3384.6 361411.0 362053.0 454433.0 457247.0 458478.7 30952.8 34059.7 57349.1 57543.0 57666.4 34094.5 34247.7 34394.8 34628.4 34632.1 14701.0 14915.0 14919.1 14945.2 14946.3 12691.3 12712.2 12725.4 12762.4 12790.2 7654.4 7659.3 7727.2 7826.5 7844.1 3217.4 3257.4 3262.2 3268.4 3282.9 827.9 916.0 1058.6 1068.2 1120.0 25444.4 29493.6 30636.5 31172.4 31281.5 851.0 884.5 1020.2 1027.5 1110.5 13670.5 13677.9 15686.1 16029.7 16083.9 699862.0 711451.0 712492.0 712706.0 714594.0 59065.4 59351.4 59506.4 59610.7 59703.7 1340.9 1342.4 1374.8 1377.0 1387.6 25114.0 27274.3 28691.4 29635.9 30531.8 52046.5 52320.7 52384.5 52389.7 52456.7 599962.0 603541.0 605846.0 606273.0 606962.0 26522.5 26823.1 27120.9 27319.6 28337.0 47772.3 47835.8 49052.8 49381.8 49621.5 39587.4 40123.6 40397.3 40985.8 41579.8 39858.6 39913.4 40057.6 41061.8 41204.2 37531.4 38346.8 39281.0 39347.6 39586.4 32867.0 35126.8 35383.6 35613.6 35766.3 24230.8 24320.5 24866.9 25150.5 25892.9 09:37:03 INFO - PROCESS | 1661 | 09:37:03 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:35517.87 Median:12058.88 stddev:99695.55 (826.7%) stddev-sans-first:99940.29 09:37:03 INFO - PROCESS | 1661 | Values: 370.0 372.8 374.7 384.4 400.9 18899.8 20393.8 20559.0 20773.9 21034.7 19872.4 20122.8 20550.0 20630.1 20804.0 4557.4 5824.7 5889.8 6148.7 6188.4 6021.2 6106.8 6129.3 6129.4 6154.4 4648.5 5931.2 6026.2 6047.0 6062.7 5764.2 5945.2 5993.5 5999.2 6032.6 5169.7 5204.4 5221.5 5238.4 5261.1 20734.8 25255.2 25344.7 25366.2 25552.7 2095.8 2187.4 2215.0 2244.0 2373.9 15248.5 15457.9 15692.0 15827.0 16225.0 1499.8 1831.6 1833.5 1847.0 1933.7 119655.0 124047.0 124248.0 124434.0 124511.1 10372.5 10510.9 10782.6 11026.2 11365.1 13587.5 13689.7 13704.2 13712.6 13712.7 8305.8 8588.1 8588.9 8630.7 8674.1 8556.5 8588.4 8621.3 8720.6 8744.9 5481.9 5855.0 5945.2 5967.4 5972.4 1469.5 1706.9 1925.0 1927.5 1934.0 862.2 895.5 942.1 988.4 997.7 21444.5 21768.8 21834.3 21930.3 22131.9 933.8 947.9 958.6 1010.3 1040.3 11511.5 11712.9 11779.4 11803.5 11823.7 552788.0 599606.0 602244.0 603339.0 622392.0 117683.1 120012.0 123692.0 125774.4 126269.9 967.6 985.4 1010.5 1013.8 1018.6 18447.4 18695.4 18702.8 18753.0 18948.6 55336.0 55436.4 58258.0 58659.0 59016.9 86964.1 87148.6 87696.6 88000.0 90011.0 7782.9 7876.7 7900.6 8068.8 8164.8 10736.4 11493.6 12692.4 12707.7 12809.6 11002.3 11104.9 11201.2 11267.8 11385.8 10823.6 10868.8 10900.8 11261.7 11270.8 11018.6 11353.7 11471.7 11479.7 11515.9 7765.7 7841.5 7984.0 7989.8 8001.2 10788.4 11158.6 11244.0 11257.8 11269.1 09:37:03 INFO - PROCESS | 1661 | 09:37:03 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5662.61 Median:4836.67 stddev:3201.35 (66.2%) stddev-sans-first:3193.97 09:37:03 INFO - PROCESS | 1661 | Values: 1355.5 1512.0 1696.2 1857.9 1879.0 7949.0 8408.7 8642.3 9007.0 9036.5 6857.5 8386.0 8671.9 8858.5 8874.2 1755.0 2413.4 2416.5 2417.4 2421.7 2357.8 2387.6 2416.1 2417.3 2420.2 2108.7 2390.6 2397.7 2406.8 2423.7 2311.3 2363.9 2380.5 2397.3 2401.4 2352.8 2377.8 2382.2 2384.8 2385.7 7658.9 8829.8 8903.1 8914.6 8923.0 5242.6 5386.4 5400.1 5401.6 5425.9 3527.6 3535.4 3561.0 3598.3 3602.6 1081.2 1090.2 1095.1 1106.7 1117.9 11534.7 11577.9 11608.2 11632.2 11641.9 4535.6 4625.3 4789.3 4795.1 4813.1 9820.3 9921.9 9944.3 9944.7 9973.6 5300.5 5333.6 5359.4 5362.2 5366.2 4395.8 4745.9 4893.0 4907.1 4911.6 3860.3 3924.1 4002.2 4006.0 4021.7 1288.6 1295.8 1300.5 1305.1 1305.9 832.6 842.9 852.7 878.7 912.8 5313.2 5455.6 5530.0 5687.3 5865.9 414.6 437.3 440.9 447.3 447.6 3809.7 3816.5 3907.6 3939.4 4016.0 10409.7 10875.9 10907.1 10909.3 11035.4 8802.0 10587.7 10807.9 10850.3 10852.6 8097.5 8197.3 8241.3 8301.3 8304.8 5695.0 5815.2 5840.3 5854.5 5854.9 9626.6 10095.9 10150.8 10200.5 10212.9 10213.3 10220.4 10272.9 10293.5 10346.1 6771.8 6835.8 7270.0 7409.0 7483.3 7384.9 7661.7 8336.5 8360.2 8410.6 7417.6 7509.4 7555.5 7558.9 7673.4 7337.4 7347.3 7402.7 7457.5 7491.7 6811.7 6883.6 6964.2 6975.9 6999.1 4204.3 4860.2 4972.0 5020.4 5053.4 4872.4 4891.7 4960.0 5030.4 5047.5 09:37:03 INFO - PROCESS | 1661 | 09:37:03 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4628.66 Median:4895.67 stddev:1447.97 (29.6%) stddev-sans-first:1415.28 09:37:03 INFO - PROCESS | 1661 | Values: 309.9 310.6 320.1 320.2 323.2 5382.0 5523.5 5585.1 5678.6 5722.7 5271.1 5556.3 5579.0 5591.6 5621.7 3912.4 4271.9 4273.5 4278.2 4283.4 4072.8 4196.7 4228.3 4275.7 4291.0 4181.5 4189.5 4199.6 4225.0 4225.2 3568.5 3584.6 4020.7 4227.2 4244.4 3659.4 3673.4 3685.1 3715.9 3740.8 4668.3 5058.5 5207.2 5413.7 5570.8 4665.9 4773.6 4774.3 4787.4 4789.5 4871.9 4975.6 5187.7 5899.8 5927.8 3900.9 3914.1 3918.8 3937.0 3962.8 5418.8 6176.2 6197.8 6200.5 6219.5 3971.5 3979.9 3982.4 3995.2 4013.0 3764.4 4005.5 4043.7 4053.9 4065.5 3997.0 4272.9 4756.5 4761.7 4820.6 4468.5 5199.8 5583.2 5923.3 6016.5 1105.7 1372.0 1406.4 1439.3 1454.2 775.9 883.5 931.7 950.5 986.9 4586.4 4680.5 4685.3 4688.5 4705.8 4711.2 4732.8 5134.9 5138.0 5144.2 4135.9 4359.8 4617.2 4624.8 4625.8 2371.8 2523.9 2605.8 2611.3 2698.9 4825.9 4874.7 4950.6 5013.4 5271.4 4735.7 4835.2 4906.8 5078.6 5123.6 5277.6 5562.3 6082.9 6326.2 6372.1 6175.7 6177.6 6199.6 6233.3 6247.8 5583.2 5776.7 6025.4 6114.8 6176.8 5812.6 5964.7 6363.6 6444.5 6449.8 3724.2 4050.6 4051.4 4073.1 4082.7 5795.7 6020.5 6026.7 6047.5 6080.5 5314.0 5560.0 5974.7 5996.4 6047.8 5316.7 5926.8 5941.2 5962.9 5988.7 4739.9 5708.4 5789.4 5809.2 5851.3 5054.8 5969.7 5975.3 5995.9 6031.4 4884.5 5623.5 5878.8 5881.2 5897.9 09:37:03 INFO - PROCESS | 1661 | 09:37:03 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:25236.09 Median:4454.55 stddev:42103.83 (945.2%) stddev-sans-first:42181.65 09:37:03 INFO - PROCESS | 1661 | Values: 703.5 733.7 740.8 764.1 808.7 14885.1 15656.2 15682.3 15685.2 15703.5 11871.7 13520.4 14016.7 14085.9 14139.6 826.8 835.6 839.1 843.6 848.4 832.2 845.4 847.7 850.5 850.5 703.8 872.6 898.1 901.9 902.0 874.7 877.5 885.2 890.5 890.6 660.6 663.2 663.9 666.0 666.1 14297.9 14411.3 14527.4 14566.3 14600.6 11549.9 11793.9 13549.1 13852.6 14271.7 11781.8 12216.4 12604.0 12608.6 12616.6 4541.9 4548.6 4549.6 4634.1 4770.5 97008.0 121873.4 122608.0 124515.0 125507.0 9168.2 11014.2 11079.7 11100.3 11165.2 17974.4 21096.6 21629.2 21645.0 21658.0 6960.8 7613.7 7696.9 7730.3 7837.9 4203.8 4248.6 4316.7 4404.4 4476.3 3367.2 3482.8 3501.0 3513.2 3543.7 635.2 642.0 643.5 648.9 660.1 540.0 583.6 599.2 626.6 628.5 9106.2 9205.8 9228.0 9250.9 9275.9 590.2 607.6 611.2 611.7 629.5 4405.4 4414.9 4432.8 4482.3 4494.4 144805.3 145713.0 162478.0 163609.0 168009.0 124541.0 133001.0 138133.3 140084.3 145946.3 63119.0 112618.9 124861.0 131468.3 131994.3 8466.0 8736.6 8740.8 8747.6 8925.0 67088.7 67264.0 68846.7 69577.0 70280.0 66151.0 70091.6 99783.0 101708.0 105611.0 11304.3 11518.3 11532.4 11704.0 12094.2 12101.4 12117.5 12153.8 12493.2 12502.9 11246.5 11686.8 11802.5 12136.8 12181.9 11541.9 11758.8 11876.5 12090.1 12165.8 9692.8 11177.9 11543.9 11782.0 11867.1 9077.3 9640.2 9854.6 9885.0 10225.2 7525.5 8317.8 8966.3 9647.5 9664.6 09:37:03 INFO - PROCESS | 1661 | -------- Summary: end -------- 09:37:03 INFO - PROCESS | 1661 | 09:37:03 INFO - TEST-INFO | 1661: exit 0 09:37:03 INFO - TEST-OK | dromaeo_css | took 1113524ms 09:37:03 INFO - TEST-START | kraken 09:37:03 INFO - operating with platform_type : mac_ 09:37:03 INFO - Initialising browser for kraken test... 09:37:03 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpE8lSjt/profile http://localhost:49268/getInfo.html) 09:37:06 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 09:37:06 INFO - PROCESS | 1669 | colorDepth:24 09:37:06 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 09:37:06 INFO - PROCESS | 1669 | __metrics 09:37:06 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 09:37:07 INFO - TEST-INFO | 1669: exit 0 09:37:07 INFO - Browser initialized. 09:37:07 INFO - Running cycle 1/1 for kraken test... 09:37:07 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpE8lSjt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:19 INFO - PROCESS | 1671 | RSS: Main: 244224000 09:37:19 INFO - PROCESS | 1671 | 09:37:22 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 09:37:23 INFO - PROCESS | 1671 | RSS: Main: 257044480 09:37:23 INFO - PROCESS | 1671 | 09:37:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 09:37:26 INFO - PROCESS | 1671 | RSS: Main: 265682944 09:37:26 INFO - PROCESS | 1671 | 09:37:29 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 09:37:30 INFO - PROCESS | 1671 | RSS: Main: 264417280 09:37:30 INFO - PROCESS | 1671 | 09:37:32 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 09:37:33 INFO - PROCESS | 1671 | RSS: Main: 265789440 09:37:33 INFO - PROCESS | 1671 | 09:37:35 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:37:35 INFO - PROCESS | 1671 | RSS: Main: 267423744 09:37:35 INFO - PROCESS | 1671 | 09:37:38 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 09:37:39 INFO - PROCESS | 1671 | RSS: Main: 267952128 09:37:39 INFO - PROCESS | 1671 | 09:37:42 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 09:37:42 INFO - PROCESS | 1671 | RSS: Main: 268095488 09:37:42 INFO - PROCESS | 1671 | 09:37:45 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 09:37:46 INFO - PROCESS | 1671 | RSS: Main: 268242944 09:37:46 INFO - PROCESS | 1671 | 09:37:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:37:48 INFO - PROCESS | 1671 | RSS: Main: 268001280 09:37:48 INFO - PROCESS | 1671 | 09:37:50 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:37:51 INFO - PROCESS | 1671 | RSS: Main: 268132352 09:37:51 INFO - PROCESS | 1671 | 09:37:53 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:37:54 INFO - PROCESS | 1671 | RSS: Main: 268214272 09:37:54 INFO - PROCESS | 1671 | 09:37:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:37:57 INFO - PROCESS | 1671 | RSS: Main: 268570624 09:37:57 INFO - PROCESS | 1671 | 09:38:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:38:01 INFO - PROCESS | 1671 | RSS: Main: 268451840 09:38:01 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 09:38:03 INFO - PROCESS | 1671 | RSS: Main: 270716928 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | __start_tp_report 09:38:03 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 09:38:03 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 09:38:03 INFO - PROCESS | 1671 | |i|pagename|runs| 09:38:03 INFO - PROCESS | 1671 | |0;ai-astar;108;117;115;114;117;117;116;137;124;151 09:38:03 INFO - PROCESS | 1671 | |1;audio-beat-detection;154;112;132;180;141;137;158;139;200;113 09:38:03 INFO - PROCESS | 1671 | |2;audio-dft;205;209;215;211;194;196;197;200;198;191 09:38:03 INFO - PROCESS | 1671 | |3;audio-fft;71;87;76;151;58;58;58;67;165;61 09:38:03 INFO - PROCESS | 1671 | |4;audio-oscillator;69;69;114;73;71;91;112;69;74;112 09:38:03 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;98;116;88;120;77;133;136;86;87;86 09:38:03 INFO - PROCESS | 1671 | |6;imaging-darkroom;112;140;100;172;144;100;100;101;99;115 09:38:03 INFO - PROCESS | 1671 | |7;imaging-desaturate;90;95;90;92;88;96;93;95;81;149 09:38:03 INFO - PROCESS | 1671 | |8;json-parse-financial;58;57;57;57;57;58;58;57;55;56 09:38:03 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;64;68;67;67;77;75;71;69;69;71 09:38:03 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;89;85;89;79;105;113;108;113;87;85 09:38:03 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;141;154;148;154;159;141;158;146;188;142 09:38:03 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;172;176;169;172;173;169;171;241;210;174 09:38:03 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;68;67;61;86;87;87;88;86;88;71 09:38:03 INFO - PROCESS | 1671 | __end_tp_report 09:38:03 INFO - PROCESS | 1671 | __start_cc_report 09:38:03 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,941 09:38:03 INFO - PROCESS | 1671 | __end_cc_report 09:38:03 INFO - PROCESS | 1671 | __startTimestamp1473352683501__endTimestamp 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | ------- Summary: start ------- 09:38:03 INFO - PROCESS | 1671 | Number of tests: 14 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:121.60 Median:117.00 stddev:12.86 (11.0%) stddev-sans-first:12.66 09:38:03 INFO - PROCESS | 1671 | Values: 108.0 117.0 115.0 114.0 117.0 117.0 116.0 137.0 124.0 151.0 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:146.60 Median:147.50 stddev:27.63 (18.7%) stddev-sans-first:29.18 09:38:03 INFO - PROCESS | 1671 | Values: 154.0 112.0 132.0 180.0 141.0 137.0 158.0 139.0 200.0 113.0 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:201.60 Median:202.50 stddev:7.97 (3.9%) stddev-sans-first:8.36 09:38:03 INFO - PROCESS | 1671 | Values: 205.0 209.0 215.0 211.0 194.0 196.0 197.0 200.0 198.0 191.0 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:85.20 Median:64.00 stddev:39.61 (61.9%) stddev-sans-first:41.68 09:38:03 INFO - PROCESS | 1671 | Values: 71.0 87.0 76.0 151.0 58.0 58.0 58.0 67.0 165.0 61.0 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:85.40 Median:70.00 stddev:19.90 (28.4%) stddev-sans-first:20.20 09:38:03 INFO - PROCESS | 1671 | Values: 69.0 69.0 114.0 73.0 71.0 91.0 112.0 69.0 74.0 112.0 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:102.70 Median:86.00 stddev:21.62 (25.1%) stddev-sans-first:22.86 09:38:03 INFO - PROCESS | 1671 | Values: 98.0 116.0 88.0 120.0 77.0 133.0 136.0 86.0 87.0 86.0 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:118.30 Median:127.50 stddev:25.26 (19.8%) stddev-sans-first:26.69 09:38:03 INFO - PROCESS | 1671 | Values: 112.0 140.0 100.0 172.0 144.0 100.0 100.0 101.0 99.0 115.0 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:96.90 Median:92.50 stddev:18.82 (20.3%) stddev-sans-first:19.80 09:38:03 INFO - PROCESS | 1671 | Values: 90.0 95.0 90.0 92.0 88.0 96.0 93.0 95.0 81.0 149.0 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:0.94 (1.7%) stddev-sans-first:0.93 09:38:03 INFO - PROCESS | 1671 | Values: 58.0 57.0 57.0 57.0 57.0 58.0 58.0 57.0 55.0 56.0 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:3.88 (5.5%) stddev-sans-first:3.50 09:38:03 INFO - PROCESS | 1671 | Values: 64.0 68.0 67.0 67.0 77.0 75.0 71.0 69.0 69.0 71.0 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:95.30 Median:85.00 stddev:12.94 (15.2%) stddev-sans-first:13.53 09:38:03 INFO - PROCESS | 1671 | Values: 89.0 85.0 89.0 79.0 105.0 113.0 108.0 113.0 87.0 85.0 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:153.10 Median:154.00 stddev:14.03 (9.1%) stddev-sans-first:14.18 09:38:03 INFO - PROCESS | 1671 | Values: 141.0 154.0 148.0 154.0 159.0 141.0 158.0 146.0 188.0 142.0 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.70 Median:173.50 stddev:23.80 (13.7%) stddev-sans-first:24.93 09:38:03 INFO - PROCESS | 1671 | Values: 172.0 176.0 169.0 172.0 173.0 169.0 171.0 241.0 210.0 174.0 09:38:03 INFO - PROCESS | 1671 | 09:38:03 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.90 Median:86.50 stddev:10.75 (12.4%) stddev-sans-first:10.66 09:38:03 INFO - PROCESS | 1671 | Values: 68.0 67.0 61.0 86.0 87.0 87.0 88.0 86.0 88.0 71.0 09:38:03 INFO - PROCESS | 1671 | -------- Summary: end -------- 09:38:03 INFO - PROCESS | 1671 | 09:38:04 INFO - TEST-INFO | 1671: exit 0 09:38:04 INFO - TEST-OK | kraken | took 60533ms 09:38:04 INFO - SUITE-END | took 1174s 09:38:04 INFO - Completed test suite (00:19:34) 09:38:04 INFO - javascript benchmark 09:38:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7279.451986525222, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3357.3956687796194, 3382.103742508236, 3394.2193295542024, 3415.795052296862, 3429.2112814052753, 5366.8389948301565, 5732.369439361677, 5889.705514724268, 5984.04255319149, 5987.6407415555095, 5881.323647780507, 5884.029135192688, 5927.347991720918, 5944.227250457446, 6015.368386319428, 5239.502247286502, 5345.064613692608, 5352.652077614943, 5449.209864569635, 5452.0, 5416.91874621881, 5456.236126942231, 5465.699386533739, 5495.258140151089, 5521.751521181556, 5065.924011139854, 5165.457626949192, 5222.3472065991755, 5297.576193904479, 5361.490658387447, 5315.681059136426, 5320.600954928396, 5324.068288049576, 5344.385395679507, 5359.571234301286, 5303.5226829585135, 5352.678839269656, 5369.382521010056, 5387.838364849061, 5388.811391601275, 5737.110747834093, 5826.126081087828, 5909.527237820964, 5936.554758393095, 5945.4946329562035, 5224.451432599599, 6255.78104766331, 6272.529560282951, 6294.898392781291, 6307.369263073701, 6324.9051264231075, 6355.459785918202, 6367.745290188387, 6373.42639162473, 6387.361263873622, 5877.412258774131, 5954.761809527614, 5972.999999999956, 5995.160677505153, 6095.329513753483, 6423.4861211103, 6468.741250350027, 6489.578177418547, 6508.739650414025, 6510.804675859732, 5152.871178220499, 6252.124702541648, 6273.62358258506, 6293.653849038347, 6315.210598675166, 6114.145302971479, 6209.689515524296, 6262.843428914313, 6304.509718661103, 6352.936470635327, 6128.472568454217, 6176.444120029129, 6229.906551401733, 6258.655773932477, 6318.778842740574, 5224.608154388436, 6262.903991801417, 6275.780347687809, 6285.717142728588, 6289.591176573508, 4989.950100499022, 5029.245613158056, 5056.140456122468, 5101.897962040738, 5104.2088476286235, 4524.16302983949, 4533.818647254139, 4549.3176023596725, 4618.422697162855, 4626.86119416418, 5918.7928422505, 5981.312149102821, 5988.341282458926, 5994.940050599438, 5997.850053748692, 5186.0, 5509.650135716775, 6194.628322300677, 6336.366363363719, 6336.6831658416895, 4685.906281874343, 4689.273162659793, 5028.64799464034, 5258.39528454225, 5910.704464776744, 5557.249771280906, 5560.388357280696, 5560.388357280696, 5583.832485025456, 5626.493615574536, 6040.822039702216, 6157.4458298753325, 6411.9740841465145, 6412.294647588761, 6523.934760652332, 6234.594751341148, 6363.90454143179, 6367.52243581724, 6382.20222472191, 6393.840153996187, 6473.1261279728515, 6548.967255163694, 6555.541112122296, 6556.213254409501, 6605.768798092044, 6097.766665674493, 6138.81758342749, 6289.40250676185, 6292.52806039558, 6302.873942521032, 6209.192804935329, 6218.844528886633, 6277.466415354746, 6280.560360774702, 6282.0, 6033.42682445167, 6237.75048998035, 6240.344763799736, 6246.562740608114, 6261.279952805486, 6095.438338978274, 6150.907736383869, 6239.844003899939, 6261.279952805486, 6261.937380626136, 6053.667048312385, 6059.0, 6134.9079763804475, 6161.291451482957, 6164.753409863555, 6180.196574445294, 6193.433983691915, 6235.282942461494, 6264.436200741955, 6272.121902933502, 6002.159697642246, 6110.236220472441, 6169.444749972524, 6173.6913154343, 6193.256809182927], "value": 5777.462882290423, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.29989764585457, 527.2855281094118, 529.4811085136566, 533.9800980127956, 556.6103727390822, 38606.420903686434, 41605.583944160586, 41702.37446438302, 41768.164636707326, 41789.79105104478, 42339.78830105853, 42915.14169716604, 42991.78504107475, 43060.56939430605, 43939.56060439395, 9552.665656702016, 9667.516624168798, 9737.659181928633, 9741.464219467927, 9807.509624518781, 9665.22678185747, 9701.563429645683, 9746.366486178376, 9785.412875227508, 9791.755206119833, 9801.607935682565, 9814.852777208347, 9821.754456138582, 9822.410655360702, 9827.803443931118, 9736.610535578568, 9784.95107524465, 9793.804123917482, 9802.117809397188, 9815.165710914582, 9668.274879384004, 9730.951345243298, 9734.561944712505, 9752.21982241419, 9778.315517913748, 40131.3980290293, 40177.59822401797, 40253.39619905704, 40482.987925302106, 40547.98630034243, 3317.717993970515, 3329.5505106810756, 3370.0900756795527, 3395.6434574369828, 3398.9490157647406, 26809.79355928988, 30878.22804429885, 31181.752729890777, 31633.0, 32196.0, 3224.967750322514, 3245.496947973068, 3274.7052765251005, 3356.9664303357144, 3384.5769278840144, 361411.0, 362053.0, 454433.0, 457247.0, 458478.70760646317, 30952.845235774126, 34059.65940340614, 57349.13976290359, 57543.0, 57666.42333576694, 34094.48858267128, 34247.65752342495, 34394.796182133505, 34628.44172012265, 34632.13419664528, 14701.04443211188, 14915.0, 14919.104853708595, 14945.17801520905, 14946.32741526634, 12691.301978391093, 12712.237265764084, 12725.427355769012, 12762.42569084375, 12790.168639038618, 7654.387648988068, 7659.272369124924, 7727.227277272228, 7826.530408175528, 7844.058712954368, 3217.3565286942703, 3257.3648138613216, 3262.217067903686, 3268.3790079884748, 3282.9179270517952, 827.9420440569223, 915.9633614655339, 1058.5871510111062, 1068.1935138970027, 1119.994400028011, 25444.363890902507, 29493.557596636067, 30636.54045189324, 31172.37655246928, 31281.530777038362, 850.9829803403898, 884.4825776920445, 1020.1583693452931, 1027.4503140819625, 1110.4558766204502, 13670.52153174634, 13677.931610342084, 15686.05883646962, 16029.67940641204, 16083.919580402024, 699862.0, 711451.0, 712492.0, 712706.0, 714594.0, 59065.40934590685, 59351.406485935455, 59506.40493595095, 59610.70194649086, 59703.70148149232, 1340.9195448273135, 1342.4428862022346, 1374.7731624281882, 1376.9793453098212, 1387.6253411578816, 25114.0, 27274.318142046606, 28691.426171476873, 29635.851820740758, 30531.847340763154, 52046.47953520416, 52320.738396307774, 52384.476155239485, 52389.7380513095, 52456.737716311174, 599962.0, 603541.0, 605846.0, 606273.0, 606961.9651901713, 26522.469550608494, 26823.061192858157, 27120.864395677894, 27319.590206146528, 28337.0, 47772.2834157481, 47835.80410489766, 49052.75473622609, 49381.75309123431, 49621.503784961686, 39587.406188906614, 40123.598764011986, 40397.334563840115, 40985.795071024455, 41579.792101039304, 39858.60141398549, 39913.401298981116, 40057.599424005384, 41061.794691026356, 41204.175916480905, 37531.43702844514, 38346.80826595849, 39281.0, 39347.60652393554, 39586.40620390754, 32867.0, 35126.77056303017, 35383.646163539066, 35613.64386356207, 35766.28467430585, 24230.75769242285, 24320.51358972846, 24866.88099035584, 25150.496990060463, 25892.870535647202], "value": 18697.365351264987, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.97965111918853, 372.7558449215764, 374.72083297943027, 384.35236626284694, 400.92783299006186, 18899.77151485154, 20393.796062039393, 20558.972051397428, 20773.883917412397, 21034.684479732816, 19872.40382788515, 20122.798772012276, 20550.0, 20630.071646775894, 20803.959802009915, 4557.407537020183, 5824.679642619675, 5889.793857214999, 6148.692565371736, 6188.350223226564, 6021.217241758565, 6106.816795496142, 6129.264488261415, 6129.356417576158, 6154.446099851012, 4648.48866624671, 5931.228940237763, 6026.156338112668, 6047.032474804032, 6062.666553339586, 5764.193012978187, 5945.16767652529, 5993.490553302952, 5999.190109335271, 6032.607880487776, 5169.741512924339, 5204.42751297357, 5221.451747566489, 5238.449962753894, 5261.1319132343115, 20734.792652073436, 25255.242342729747, 25344.746552534423, 25366.239012829643, 25552.744472555223, 2095.8323334133383, 2187.409399462152, 2214.977850221493, 2243.9663405048937, 2373.8694371809543, 15248.466303679428, 15457.922710386489, 15692.0, 15827.0, 16225.0, 1499.7825315329217, 1831.642829648219, 1833.468294194682, 1846.9538261543569, 1933.6712758831034, 119655.0, 124047.0, 124247.9999999991, 124434.0, 124511.13233301508, 10372.533236004247, 10510.947445262726, 10782.568697252022, 11026.173037022256, 11365.147613928988, 13587.524436644868, 13689.726205476034, 13704.177749335071, 13712.588622341345, 13712.65718357049, 8305.79565962944, 8588.055313915589, 8588.8711669325, 8630.654773809103, 8674.08922063187, 8556.529390883561, 8588.398812083095, 8621.31029517637, 8720.607572659246, 8744.912550874536, 5481.917771233355, 5855.0, 5945.16767652529, 5967.433093856077, 5972.402759723993, 1469.4563011685532, 1706.871984601135, 1924.9711254331196, 1927.4603111128906, 1933.951651208703, 862.1723145779996, 895.4627223665749, 942.1191186240886, 988.3921388346259, 997.7206382212992, 21444.463888402915, 21768.80271585078, 21834.344969650934, 21930.342089737333, 22131.889340553193, 933.7945651956559, 947.9146876781123, 958.566942421085, 1010.2726037253166, 1040.2760884827946, 11511.539538418538, 11712.941435292769, 11779.411029448493, 11803.52785888572, 11823.704407389712, 552788.0, 599606.0315174776, 602244.0, 603339.0, 622392.0, 117683.0579235526, 120012.0, 123692.0, 125774.37112814377, 126269.895571658, 967.6371360739722, 985.4383001689112, 1010.545254635417, 1013.8479228115841, 1018.6282007067367, 18447.446576602724, 18695.439136825917, 18702.849172345355, 18753.0, 18948.621027579647, 55336.0, 55436.445635543125, 58258.0, 58659.0, 59016.934407295535, 86964.1303586956, 87148.56425717831, 87696.561517192, 88000.0, 90011.0, 7782.883256751266, 7876.6849326028605, 7900.644470998934, 8068.798280043045, 8164.7550573482895, 10736.355818650905, 11493.597724079616, 12692.365381730691, 12707.745845083231, 12809.551665691655, 11002.284851484628, 11104.944475277573, 11201.159913006393, 11267.830982535104, 11385.772284554429, 10823.567057317934, 10868.836967445337, 10900.836487452536, 11261.71845703864, 11270.83093753578, 11018.614348497764, 11353.716157096138, 11471.655850324503, 11479.655610331703, 11515.92713311612, 7765.650545725569, 7841.490303130278, 7984.001999750031, 7989.760307190793, 8001.199880011952, 10788.352698838095, 11158.609448669258, 11244.0, 11257.774844503228, 11269.098472122047], "value": 9756.181954167168, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.491690616019, 1512.0, 1696.1604006017023, 1857.9256829726812, 1878.9530261743455, 7948.9602551987155, 8408.705695300665, 8642.308615310776, 9006.954965225164, 9036.457812531253, 6857.485688573364, 8385.951756030496, 8671.91328086719, 8858.468491890491, 8874.245689116417, 1754.9912250438763, 2413.3966508372905, 2416.4804567018086, 2417.3835671903726, 2421.7093948726088, 2357.834951553392, 2387.6060450025725, 2416.0818888822223, 2417.286900364397, 2420.20133355993, 2108.6520724080583, 2390.557746816836, 2397.712274527059, 2406.795422389099, 2423.7333893271725, 2311.3297143828268, 2363.929082127539, 2380.547695937766, 2397.2808157552663, 2401.3756423329887, 2352.811775058008, 2377.7741114594087, 2382.225776622587, 2384.833061685683, 2385.7256415512265, 7658.923410765877, 8829.779255518664, 8903.065178156265, 8914.554272286425, 8923.018467968519, 5242.55438287746, 5386.407495175527, 5400.054990376708, 5401.567874570064, 5425.864353391157, 3527.6119626841282, 3535.381308271068, 3560.9821950890337, 3598.2623562169692, 3602.5676918769914, 1081.1891081688734, 1090.198703952602, 1095.1020163465962, 1106.6790630717162, 1117.9105671546256, 11534.711632209179, 11577.94211028948, 11608.24546404481, 11632.243904146204, 11641.941790290994, 4535.569120933506, 4625.283081122431, 4789.281607758864, 4795.064962332312, 4813.109574728686, 9820.263480239011, 9921.851172232422, 9944.303898727165, 9944.651937182309, 9973.60105595768, 5300.469953004746, 5333.57331413486, 5359.410464848864, 5362.2224777407055, 5366.195070739343, 4395.846145384896, 4745.928811067837, 4893.0, 4907.141250281258, 4911.607071434277, 3860.343741563941, 3924.117073658404, 4002.1795531915886, 4005.9799701001893, 4021.7076266528384, 1288.6005338345144, 1295.812107244463, 1300.486307908371, 1305.09948135786, 1305.8628843971437, 832.629479881459, 842.9072801991776, 852.7015544559355, 878.7188099808128, 912.762681702762, 5313.203019547099, 5455.5908306877145, 5530.032244357221, 5687.260656114679, 5865.941340586625, 414.6330497509726, 437.3046855499707, 440.90961352923216, 447.2620176708457, 447.61952340510305, 3809.695224382043, 3816.46569480273, 3907.5897030811925, 3939.350007248829, 4015.975926138816, 10409.739756505996, 10875.891241087487, 10907.072898803532, 10909.290896091728, 11035.44822758859, 8802.0, 10587.682369528926, 10807.945960270306, 10850.29473084247, 10852.565897364175, 8097.473664211808, 8197.303229225463, 8241.25828675422, 8301.252887240178, 8304.7542868569, 5695.0, 5815.185873977731, 5840.269966254218, 5854.507100689297, 5854.853628659318, 9626.566804493674, 10095.89904100979, 10150.847737283799, 10200.540975655966, 10212.948935255276, 10213.285070045025, 10220.386776793417, 10272.897271027194, 10293.485325733833, 10346.068853803194, 6771.830704232433, 6835.829104272433, 7270.0, 7408.962955185405, 7483.289087536676, 7384.929185267999, 7661.731839385818, 8336.541490218096, 8360.205780450848, 8410.621522031646, 7417.629118544159, 7509.3617042552, 7555.546667199986, 7558.9244107558225, 7673.386129109769, 7337.413006959324, 7347.33873951347, 7402.740904068547, 7457.47797654159, 7491.70033198666, 6811.69347379379, 6883.586984780929, 6964.199117101598, 6975.895361569479, 6999.12510936133, 4204.264253755642, 4860.226186683606, 4972.0, 5020.397552293748, 5053.36832895888, 4872.439669437918, 4891.73095479752, 4960.0, 5030.446650868328, 5047.545720885138], "value": 4476.5192802590045, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.9054788289571, 310.57141145219595, 320.1069017441339, 320.1979042498541, 323.1936318884384, 5381.973090134554, 5523.453433038756, 5585.0784620537615, 5678.558219965414, 5722.656640601562, 5271.130263506517, 5556.333240011194, 5579.0, 5591.608587398884, 5621.690807005613, 3912.3740201567753, 4271.850485233017, 4273.529911709709, 4278.208531421681, 4283.400323954641, 4072.8188825240645, 4196.685248606352, 4228.281192197333, 4275.679324050693, 4291.0, 4181.498220213563, 4189.49726032875, 4199.593136299343, 4224.957750422503, 4225.197212529625, 3568.536090308269, 3584.551931008624, 4020.7185497015075, 4227.239096962546, 4244.410909345297, 3659.4144936809985, 3673.3755261605593, 3685.0971511979737, 3715.9071023224637, 3740.8116251992615, 4668.253079507263, 5058.519440653132, 5207.244949482304, 5413.729313534347, 5570.832875013756, 4665.88335291617, 4773.570378665937, 4774.307725379836, 4787.377640906695, 4789.521047895218, 4871.853844384638, 4975.606830087581, 5187.6512106852115, 5899.764009439617, 5927.762889484416, 3900.9219815603815, 3914.0625390431633, 3918.784466854322, 3936.960630393688, 3962.7820469874146, 5418.783248670048, 6176.227971503562, 6197.783077592308, 6200.534959877982, 6219.50243980485, 3971.483707118114, 3979.8607048753156, 3982.3628219484744, 3995.2409042282457, 4012.9799351003057, 3764.378877485184, 4005.459262999516, 4043.6765058795227, 4053.8986525336513, 4065.5324637666454, 3996.980015099964, 4272.931767058236, 4756.548127927841, 4761.738174367148, 4820.638452116105, 4468.530804265571, 5199.844004679866, 5583.246261754611, 5923.318818335861, 6016.518678505709, 1105.6738262212666, 1371.9984411379749, 1406.367134789349, 1439.2659743530664, 1454.185656032625, 775.8874963130314, 883.4610887358706, 931.7484279244566, 950.4914870544226, 986.8963758805365, 4586.4266966629175, 4680.461746899082, 4685.250359942393, 4688.507706690748, 4705.835295764632, 4711.246200607886, 4732.787024583902, 5134.947335796152, 5138.0, 5144.225242291403, 4135.937960930589, 4359.825606975749, 4617.215073437456, 4624.815007399667, 4625.843539115222, 2371.8102551795814, 2523.9273308843667, 2605.7654811067096, 2611.2818974782085, 2698.892044318205, 4825.852495639477, 4874.683145595525, 4950.579200767975, 5013.39839219296, 5271.4464981176425, 4735.732344682158, 4835.24605719193, 4906.828261010847, 5078.619103567247, 5123.590112790967, 5277.551408130275, 5562.3047119110115, 6082.931803186938, 6326.165412030459, 6372.107904893412, 6175.722092505758, 6177.62934223948, 6199.628022318675, 6233.283172435228, 6247.781327653692, 5583.2462617547735, 5776.711164441677, 6025.397460254115, 6114.785982490769, 6176.752929882754, 5812.6049748061005, 5964.735214321608, 6363.5545511813725, 6444.45222156122, 6449.774257900951, 3724.2179142380405, 4050.5746896576616, 4051.39229115635, 4073.124278280184, 4082.652974497201, 5795.710214489343, 6020.51835853122, 6026.698665066817, 6047.485963693135, 6080.543959202989, 5314.0, 5560.026995275892, 5974.701264936649, 5996.430339117776, 6047.818565442868, 5316.6544174628525, 5926.7925622603, 5941.15824094532, 5962.850928726816, 5988.6706231157705, 4739.905201896012, 5708.372079071381, 5789.3921138279875, 5809.215755873072, 5851.268591426072, 5054.826424637441, 5969.73136208863, 5975.312839023568, 5995.88008239824, 6031.366706495754, 4884.511548845087, 5623.493885550321, 5878.823635290948, 5881.235439392852, 5897.941020589739], "value": 4168.550095280636, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.5005146346094, 733.6991833348328, 740.822202671359, 764.0754686828936, 808.6563210635478, 14885.106893586379, 15656.217189140554, 15682.294296756645, 15685.215739213036, 15703.450379236729, 11871.70320741982, 13520.391582378792, 14016.719665606683, 14085.929570352135, 14139.646508837284, 826.8263664630434, 835.5863847395517, 839.1314988986401, 843.5613480989881, 848.4188330993259, 832.1803024021334, 845.3955421873359, 847.6609356257485, 850.4557083466586, 850.5152063323908, 703.7747920665389, 872.62477134832, 898.0525545549443, 901.8647202919549, 902.0483390023522, 874.6501399440211, 877.5261358866204, 885.1679421343916, 890.5191196753746, 890.5858775669312, 660.5541259649717, 663.1909070933501, 663.9004149377583, 665.9766908158191, 666.1173944523478, 14297.85702142986, 14411.279436028157, 14527.418903243857, 14566.344514496874, 14600.561983140522, 11549.884501154966, 11793.882061179364, 13549.119307245097, 13852.584422467342, 14271.714565708628, 11781.823272650918, 12216.389180540937, 12604.0, 12608.621741347773, 12616.621501354974, 4541.886452838672, 4548.579627544562, 4549.61328287096, 4634.096351211515, 4770.522947705236, 97008.0, 121873.39063304715, 122608.0, 124515.0, 125507.0, 9168.174864262248, 11014.229003969645, 11079.667609971713, 11100.278481898651, 11165.162612804072, 17974.37089701854, 21096.578068438546, 21629.24297649575, 21645.0, 21658.0, 6960.756373526902, 7613.695452181962, 7696.884546731803, 7730.342920851678, 7837.9216207838335, 4203.76879271643, 4248.638865696388, 4316.740995540277, 4404.427424434803, 4476.2837945928495, 3367.1750421146976, 3482.791032538056, 3501.0197144799495, 3513.2270900401872, 3543.7342199335153, 635.2123367024948, 642.0497663458108, 643.462708638283, 648.8832010238202, 660.1055569703079, 539.9973000135053, 583.5851781274644, 599.2090440618342, 626.6208943589063, 628.4752231886334, 9106.180443760093, 9205.81588368229, 9227.953860230657, 9250.90749092514, 9275.860862087075, 590.2032256453753, 607.5868409481595, 611.2054329371888, 611.721666641677, 629.4955057412787, 4405.449318835145, 4414.911701766011, 4432.82268709248, 4482.327650852333, 4494.438195225597, 144805.27597361946, 145713.0, 162478.0, 163609.0, 168009.0, 124541.0, 133001.0, 138133.3093334527, 140084.2995785035, 145946.2702686501, 63119.0, 112618.87381126084, 124861.0, 131468.34265828802, 131994.34002829925, 8465.98408191021, 8736.56317184151, 8740.78148046304, 8747.60635771379, 8924.955375223082, 67088.6645566769, 67263.99104013346, 68846.65576672084, 69576.95634565587, 70280.0, 66151.0, 70091.64954175196, 99783.0, 101708.0, 105611.0, 11304.265222760494, 11518.308901465938, 11532.365719885487, 11704.0, 12094.213876098025, 12101.394930253628, 12117.515299388278, 12153.817692734792, 12493.18794278369, 12502.937485312515, 11246.493907774333, 11686.766264674488, 11802.52789888395, 12136.817947730968, 12181.939090304846, 11541.884581154081, 11758.82361764557, 11876.465559050037, 12090.093243006633, 12165.817512737493, 9692.806143877224, 11177.88822111801, 11543.942280288546, 11781.998530125036, 11867.05063594893, 9077.319201059814, 9640.204916405315, 9854.55654495535, 9885.0, 10225.233107516817, 7525.510841795266, 8317.792055198668, 8966.282697384324, 9647.469389183661, 9664.565094570902], "value": 7565.290627019485, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1595.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 117.0, 115.0, 114.0, 117.0, 117.0, 116.0, 137.0, 124.0, 151.0], "value": 121.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 112.0, 132.0, 180.0, 141.0, 137.0, 158.0, 139.0, 200.0, 113.0], "value": 146.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 209.0, 215.0, 211.0, 194.0, 196.0, 197.0, 200.0, 198.0, 191.0], "value": 201.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 87.0, 76.0, 151.0, 58.0, 58.0, 58.0, 67.0, 165.0, 61.0], "value": 85.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 114.0, 73.0, 71.0, 91.0, 112.0, 69.0, 74.0, 112.0], "value": 85.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 116.0, 88.0, 120.0, 77.0, 133.0, 136.0, 86.0, 87.0, 86.0], "value": 102.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 140.0, 100.0, 172.0, 144.0, 100.0, 100.0, 101.0, 99.0, 115.0], "value": 118.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 95.0, 90.0, 92.0, 88.0, 96.0, 93.0, 95.0, 81.0, 149.0], "value": 96.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 57.0, 57.0, 58.0, 58.0, 57.0, 55.0, 56.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 67.0, 67.0, 77.0, 75.0, 71.0, 69.0, 69.0, 71.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 79.0, 105.0, 113.0, 108.0, 113.0, 87.0, 85.0], "value": 95.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 154.0, 148.0, 154.0, 159.0, 141.0, 158.0, 146.0, 188.0, 142.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 176.0, 169.0, 172.0, 173.0, 169.0, 171.0, 241.0, 210.0, 174.0], "value": 182.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 61.0, 86.0, 87.0, 87.0, 88.0, 86.0, 88.0, 71.0], "value": 78.9, "unit": "score"}]}]} 09:38:04 INFO - Return code: 0 09:38:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:38:04 ERROR - # TBPL SUCCESS # 09:38:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:04 INFO - 2016-09-08 09:38:04.849 screenresolution[1676:10862] starting screenresolution argv=screenresolution get 09:38:04 INFO - 2016-09-08 09:38:04.851 screenresolution[1676:10862] Display 0: 1600x1200x32@60 09:38:04 INFO - 2016-09-08 09:38:04.857 screenresolution[1677:10865] starting screenresolution argv=screenresolution list 09:38:04 INFO - Available Modes on Display 0 09:38:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:05 INFO - Intel Iris: 09:38:05 INFO - Chipset Model: Intel Iris 09:38:05 INFO - Type: GPU 09:38:05 INFO - Bus: Built-In 09:38:05 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:05 INFO - Vendor: Intel (0x8086) 09:38:05 INFO - Device ID: 0x0a2e 09:38:05 INFO - Revision ID: 0x0009 09:38:05 INFO - Displays: 09:38:05 INFO - Display: 09:38:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:05 INFO - Display Serial Number: 1600x1200 60 09:38:05 INFO - Main Display: Yes 09:38:05 INFO - Mirror: Off 09:38:05 INFO - Online: Yes 09:38:05 INFO - Rotation: Supported 09:38:05 INFO - Return code: 0 09:38:05 INFO - Running post-action listener: _resource_record_post_action 09:38:05 INFO - [mozharness: 2016-09-08 16:38:05.093541Z] Finished run-tests step (success) 09:38:05 INFO - Running post-run listener: _resource_record_post_run 09:38:05 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 33119232; Write bytes: 709538304; Read time: 310; Write time: 2028 09:38:05 INFO - TinderboxPrint: CPU usage
54.7% 09:38:05 INFO - TinderboxPrint: I/O read bytes / time
33,119,232 / 310 09:38:05 INFO - TinderboxPrint: I/O write bytes / time
709,538,304 / 2,028 09:38:05 INFO - TinderboxPrint: CPU idle
2,190.6 (45.3%) 09:38:05 INFO - TinderboxPrint: CPU system
982.0 (20.3%) 09:38:05 INFO - TinderboxPrint: CPU user
1,665.1 (34.4%) 09:38:05 INFO - TinderboxPrint: Swap in / out
434,561,024 / 0 09:38:05 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 406690304; Write bytes: 408540672; Read time: 29798; Write time: 814 09:38:05 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 29195264; Write bytes: 297413632; Read time: 276; Write time: 1203 09:38:05 INFO - Running post-run listener: _upload_blobber_files 09:38:05 INFO - Blob upload gear active. 09:38:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:38:05 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 09:38:05 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'] 09:38:05 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 09:38:05 INFO - (blobuploader) - INFO - Open directory for files ... 09:38:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:38:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:38:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:06 INFO - (blobuploader) - INFO - Done attempting. 09:38:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:38:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:38:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:07 INFO - (blobuploader) - INFO - Done attempting. 09:38:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:38:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:38:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:09 INFO - (blobuploader) - INFO - Done attempting. 09:38:09 INFO - (blobuploader) - INFO - Iteration through files over. 09:38:09 INFO - Return code: 0 09:38:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:38:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:38:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/da8e66a4ef4724fb20b6f90ccd817de7a55c8bed9675b09cec9fad285c71f28e30ad3257ff77739b249eb3a4fb760fe2124798dca3d0114ecfef50ee0b9380d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/867b554193fccc54d12fa7e96c18132ed3ea2090ebdeb3899eb4cd1469b87f624ca2b7b7e6e45a4c14cd3ba87252bc67aeeccc9230f82af1bcad6d7f5f39f902", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b9e69c665bff842931adae056d467eaecc0a5bdcbba8f4049c303f64a536de6a16246942342c700658a33572096770725516dc96dad976aaa8ff5b0e02ef7ab1"} 09:38:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:38:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:38:09 INFO - Contents: 09:38:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/da8e66a4ef4724fb20b6f90ccd817de7a55c8bed9675b09cec9fad285c71f28e30ad3257ff77739b249eb3a4fb760fe2124798dca3d0114ecfef50ee0b9380d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/867b554193fccc54d12fa7e96c18132ed3ea2090ebdeb3899eb4cd1469b87f624ca2b7b7e6e45a4c14cd3ba87252bc67aeeccc9230f82af1bcad6d7f5f39f902", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b9e69c665bff842931adae056d467eaecc0a5bdcbba8f4049c303f64a536de6a16246942342c700658a33572096770725516dc96dad976aaa8ff5b0e02ef7ab1"} 09:38:09 INFO - Running post-run listener: copy_logs_to_upload_dir 09:38:09 INFO - Copying logs to upload dir... 09:38:09 INFO - mkdir: /builds/slave/test/build/upload/logs 09:38:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.241313 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-08 09:38:09.997018) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 09:38:10.000752) ========= 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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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/da8e66a4ef4724fb20b6f90ccd817de7a55c8bed9675b09cec9fad285c71f28e30ad3257ff77739b249eb3a4fb760fe2124798dca3d0114ecfef50ee0b9380d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/867b554193fccc54d12fa7e96c18132ed3ea2090ebdeb3899eb4cd1469b87f624ca2b7b7e6e45a4c14cd3ba87252bc67aeeccc9230f82af1bcad6d7f5f39f902", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b9e69c665bff842931adae056d467eaecc0a5bdcbba8f4049c303f64a536de6a16246942342c700658a33572096770725516dc96dad976aaa8ff5b0e02ef7ab1"} build_url:https://queue.taskcluster.net/v1/task/RPcO6nR2T0mU3E56reBYSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010927 build_url: 'https://queue.taskcluster.net/v1/task/RPcO6nR2T0mU3E56reBYSw/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/da8e66a4ef4724fb20b6f90ccd817de7a55c8bed9675b09cec9fad285c71f28e30ad3257ff77739b249eb3a4fb760fe2124798dca3d0114ecfef50ee0b9380d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/867b554193fccc54d12fa7e96c18132ed3ea2090ebdeb3899eb4cd1469b87f624ca2b7b7e6e45a4c14cd3ba87252bc67aeeccc9230f82af1bcad6d7f5f39f902", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b9e69c665bff842931adae056d467eaecc0a5bdcbba8f4049c303f64a536de6a16246942342c700658a33572096770725516dc96dad976aaa8ff5b0e02ef7ab1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 09:38:10.034679) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 09:38:10.035016) ========= 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.1PVSozYIbu/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.ciMNoEGVwZ/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.005165 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 09:38:10.084854) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 09:38:10.085233) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 09:38:10.085580) ========= ========= Total master_lag: 0.18 =========