builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0003 starttime: 1473201488.82 results: success (0) buildid: 20160906144116 builduid: 8584a9ae55ae4b8384c945225fb8f958 revision: 2e6b9e57d5a0527bd51f86be22e930acf450cf8d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 15:38:08.823941) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 15:38:08.824705) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 15:38:08.825082) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 15:38:08.917086) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 15:38:08.917496) ========= 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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-06 15:38:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.1M=0.001s 2016-09-06 15:38:09 (14.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137063 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 15:38:09.081722) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 15:38:09.082101) ========= 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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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.028692 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 15:38:09.131751) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 15:38:09.132266) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 2e6b9e57d5a0527bd51f86be22e930acf450cf8d --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 2e6b9e57d5a0527bd51f86be22e930acf450cf8d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TvZKSOnpI2/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.LnoCUeu4FI/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-06 15:38:09,216 truncating revision to first 12 chars 2016-09-06 15:38:09,216 Setting DEBUG logging. 2016-09-06 15:38:09,216 attempt 1/10 2016-09-06 15:38:09,216 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/2e6b9e57d5a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 15:38:10,304 unpacking tar archive at: autoland-2e6b9e57d5a0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.340912 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 15:38:10.498533) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 15:38:10.498849) ========= script_repo_revision: 2e6b9e57d5a0527bd51f86be22e930acf450cf8d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 15:38:10.499294) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 15:38:10.499608) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 15:38:10.519010) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-06 15:38:10.519453) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 15:38:10 INFO - MultiFileLogger online at 20160906 15:38:10 in /builds/slave/test 15:38:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 15:38:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:38:10 INFO - {'append_to_log': False, 15:38:10 INFO - 'base_work_dir': '/builds/slave/test', 15:38:10 INFO - 'blob_upload_branch': 'Autoland', 15:38:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:38:10 INFO - 'branch': 'Autoland', 15:38:10 INFO - 'buildbot_json_path': 'buildprops.json', 15:38:10 INFO - 'config_files': ('talos/mac_config.py',), 15:38:10 INFO - 'default_actions': ('clobber', 15:38:10 INFO - 'read-buildbot-config', 15:38:10 INFO - 'download-and-extract', 15:38:10 INFO - 'populate-webroot', 15:38:10 INFO - 'create-virtualenv', 15:38:10 INFO - 'install', 15:38:10 INFO - 'run-tests'), 15:38:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:38:10 INFO - 'download_minidump_stackwalk': True, 15:38:10 INFO - 'download_symbols': 'ondemand', 15:38:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:38:10 INFO - 'tooltool.py': '/tools/tooltool.py', 15:38:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:38:10 INFO - '/tools/misc-python/virtualenv.py')}, 15:38:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:38:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:38:10 INFO - 'installer_path': 'installer.exe', 15:38:10 INFO - 'log_level': 'info', 15:38:10 INFO - 'log_name': 'talos', 15:38:10 INFO - 'log_to_console': True, 15:38:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:38:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:38:10 INFO - 'opt_config_files': (), 15:38:10 INFO - 'pip_index': False, 15:38:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:38:10 INFO - 'cmd': ('bash', 15:38:10 INFO - '-c', 15:38:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:38:10 INFO - 'enabled': True, 15:38:10 INFO - 'halt_on_failure': False, 15:38:10 INFO - 'name': 'check_screen_resolution'},), 15:38:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:38:10 INFO - 'cmd': ('bash', 15:38:10 INFO - '-c', 15:38:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:38:10 INFO - 'enabled': True, 15:38:10 INFO - 'halt_on_failure': False, 15:38:10 INFO - 'name': 'check_screen_resolution'},), 15:38:10 INFO - 'run_cmd_checks_enabled': True, 15:38:10 INFO - 'sps_profile': False, 15:38:10 INFO - 'sps_profile_interval': 0, 15:38:10 INFO - 'suite': 'dromaeojs', 15:38:10 INFO - 'system_bits': '32', 15:38:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:38:10 INFO - 'title': 't-yosemite-r7-0003', 15:38:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:38:10 INFO - 'use_talos_json': True, 15:38:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:38:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:38:10 INFO - 'work_dir': 'build'} 15:38:10 INFO - [mozharness: 2016-09-06 22:38:10.718111Z] Running clobber step. 15:38:10 INFO - Running pre-action listener: _resource_record_pre_action 15:38:10 INFO - Running main action method: clobber 15:38:10 INFO - rmtree: /builds/slave/test/build 15:38:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:38:12 INFO - Running post-action listener: _resource_record_post_action 15:38:12 INFO - [mozharness: 2016-09-06 22:38:12.848873Z] Finished clobber step (success) 15:38:12 INFO - [mozharness: 2016-09-06 22:38:12.849142Z] Running read-buildbot-config step. 15:38:12 INFO - Running pre-action listener: _resource_record_pre_action 15:38:12 INFO - Running main action method: read_buildbot_config 15:38:12 INFO - Using buildbot properties: 15:38:12 INFO - { 15:38:12 INFO - "project": "", 15:38:12 INFO - "product": "firefox", 15:38:12 INFO - "script_repo_revision": "production", 15:38:12 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 15:38:12 INFO - "repository": "", 15:38:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 15:38:12 INFO - "buildid": "20160906144116", 15:38:12 INFO - "pgo_build": "False", 15:38:12 INFO - "basedir": "/builds/slave/test", 15:38:12 INFO - "buildnumber": 230, 15:38:12 INFO - "slavename": "t-yosemite-r7-0003", 15:38:12 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:38:12 INFO - "platform": "yosemite_r7", 15:38:12 INFO - "scheduler": "tests-autoland-macosx64-talos", 15:38:12 INFO - "branch": "autoland", 15:38:12 INFO - "repo_path": "integration/autoland", 15:38:12 INFO - "revision": "2e6b9e57d5a0527bd51f86be22e930acf450cf8d", 15:38:12 INFO - "stage_platform": "macosx64", 15:38:12 INFO - "builduid": "8584a9ae55ae4b8384c945225fb8f958", 15:38:12 INFO - "slavebuilddir": "test" 15:38:12 INFO - } 15:38:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:38:12 INFO - Running post-action listener: _resource_record_post_action 15:38:12 INFO - [mozharness: 2016-09-06 22:38:12.855249Z] Finished read-buildbot-config step (success) 15:38:12 INFO - [mozharness: 2016-09-06 22:38:12.855458Z] Running download-and-extract step. 15:38:12 INFO - Running pre-action listener: _resource_record_pre_action 15:38:12 INFO - Running main action method: download_and_extract 15:38:12 INFO - mkdir: /builds/slave/test/build/tests 15:38:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:12 INFO - trying https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:38:12 INFO - Downloading https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/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 15:38:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/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 15:38:13 INFO - Downloaded 1199 bytes. 15:38:13 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:38:13 INFO - Using the following test package requirements: 15:38:13 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:38:13 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:38:13 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:38:13 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:38:13 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:38:13 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:38:13 INFO - u'jsshell-mac.zip'], 15:38:13 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:38:13 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:38:13 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:38:13 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:38:13 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:38:13 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:38:13 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:38:13 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:38:13 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:38:13 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:38:13 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:38:13 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:38:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:38:14 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:38:21 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 15:38:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:38:21 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:38:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:38:29 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:38:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:30 INFO - trying https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:38:30 INFO - Downloading https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:38:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/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 15:38:32 INFO - Downloaded 111510967 bytes. 15:38:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:38:32 INFO - mkdir: /builds/slave/test/properties 15:38:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:38:32 INFO - Writing to file /builds/slave/test/properties/build_url 15:38:32 INFO - Contents: 15:38:32 INFO - build_url:https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:38:33 INFO - Running post-action listener: _resource_record_post_action 15:38:33 INFO - Running post-action listener: set_extra_try_arguments 15:38:33 INFO - [mozharness: 2016-09-06 22:38:33.440400Z] Finished download-and-extract step (success) 15:38:33 INFO - [mozharness: 2016-09-06 22:38:33.440545Z] Running populate-webroot step. 15:38:33 INFO - Running pre-action listener: _resource_record_pre_action 15:38:33 INFO - Running main action method: populate_webroot 15:38:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:38:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:38:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:38:33 INFO - '--tppagecycles', 15:38:33 INFO - '7'], 15:38:33 INFO - 'tests': ['tsvgm']}}, 15:38:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:38:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:38:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:38:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:38:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:38:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:38:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['damp', 'tps']}, 15:38:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'tests': ['damp', 'tps']}, 15:38:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['dromaeo_dom']}, 15:38:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:38:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['basic_compositor_video']}, 15:38:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:38:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['a11yr', 15:38:33 INFO - 'ts_paint', 15:38:33 INFO - 'tpaint', 15:38:33 INFO - 'sessionrestore', 15:38:33 INFO - 'sessionrestore_no_auto_restore', 15:38:33 INFO - 'tabpaint']}, 15:38:33 INFO - 'other-e10s': {'tests': ['a11yr', 15:38:33 INFO - 'ts_paint', 15:38:33 INFO - 'tpaint', 15:38:33 INFO - 'sessionrestore', 15:38:33 INFO - 'sessionrestore_no_auto_restore', 15:38:33 INFO - 'tabpaint']}, 15:38:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:38:33 INFO - 'ts_paint', 15:38:33 INFO - 'tpaint', 15:38:33 INFO - 'sessionrestore', 15:38:33 INFO - 'sessionrestore_no_auto_restore', 15:38:33 INFO - 'tabpaint']}, 15:38:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:38:33 INFO - 'ts_paint', 15:38:33 INFO - 'tpaint', 15:38:33 INFO - 'sessionrestore', 15:38:33 INFO - 'sessionrestore_no_auto_restore', 15:38:33 INFO - 'tabpaint']}, 15:38:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['a11yr', 15:38:33 INFO - 'ts_paint', 15:38:33 INFO - 'tpaint', 15:38:33 INFO - 'sessionrestore', 15:38:33 INFO - 'sessionrestore_no_auto_restore', 15:38:33 INFO - 'tabpaint']}, 15:38:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['a11yr', 15:38:33 INFO - 'ts_paint', 15:38:33 INFO - 'tpaint', 15:38:33 INFO - 'sessionrestore', 15:38:33 INFO - 'sessionrestore_no_auto_restore', 15:38:33 INFO - 'tabpaint']}, 15:38:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['tsvgx', 15:38:33 INFO - 'tsvgr_opacity', 15:38:33 INFO - 'tart', 15:38:33 INFO - 'tscrollx', 15:38:33 INFO - 'cart']}, 15:38:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:38:33 INFO - 'tsvgr_opacity', 15:38:33 INFO - 'tart', 15:38:33 INFO - 'tscrollx', 15:38:33 INFO - 'cart']}, 15:38:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'talos_options': ['--disable-e10s'], 15:38:33 INFO - 'tests': ['tp5o']}, 15:38:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'tests': ['tp5o']}, 15:38:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'talos_options': ['--disable-e10s', 15:38:33 INFO - '--xperf_path', 15:38:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:38:33 INFO - 'tests': ['tp5n']}, 15:38:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:33 INFO - 'talos_options': ['--xperf_path', 15:38:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:38:33 INFO - 'tests': ['tp5n']}}, 15:38:33 INFO - 'talos.zip': {'path': '', 15:38:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:38:33 INFO - Running post-action listener: _resource_record_post_action 15:38:33 INFO - [mozharness: 2016-09-06 22:38:33.459630Z] Finished populate-webroot step (success) 15:38:33 INFO - [mozharness: 2016-09-06 22:38:33.459741Z] Running create-virtualenv step. 15:38:33 INFO - Running pre-action listener: _resource_record_pre_action 15:38:33 INFO - Running main action method: create_virtualenv 15:38:33 INFO - Creating virtualenv /builds/slave/test/build/venv 15:38:33 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 15:38:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:38:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:38:33 INFO - Using real prefix '/tools/python27' 15:38:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:38:34 INFO - Installing distribute.............................................................................................................................................................................................done. 15:38:37 INFO - Installing pip.................done. 15:38:37 INFO - Return code: 0 15:38:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:38:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc942c765f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466cd30>, '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 0x1043d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104664b20>, '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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 15:38:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:38:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:38:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TvZKSOnpI2/Render', 15:38:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:38:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:38:37 INFO - 'HOME': '/Users/cltbld', 15:38:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:38:37 INFO - 'LOGNAME': 'cltbld', 15:38:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:37 INFO - 'MOZ_NO_REMOTE': '1', 15:38:37 INFO - 'NO_EM_RESTART': '1', 15:38:37 INFO - 'PAGER': '/bin/cat', 15:38:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:38:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:37 INFO - 'PWD': '/builds/slave/test', 15:38:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:38:37 INFO - 'SHELL': '/bin/bash', 15:38:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LnoCUeu4FI/Listeners', 15:38:37 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 15:38:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:38:37 INFO - 'USER': 'cltbld', 15:38:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:38:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:38:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:37 INFO - 'XPC_FLAGS': '0x0', 15:38:37 INFO - 'XPC_SERVICE_NAME': '0', 15:38:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:38:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:37 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 15:38:37 INFO - Cleaning up... 15:38:37 INFO - Return code: 0 15:38:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:38:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc942c765f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466cd30>, '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 0x1043d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104664b20>, '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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 15:38:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:38:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:38:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TvZKSOnpI2/Render', 15:38:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:38:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:38:37 INFO - 'HOME': '/Users/cltbld', 15:38:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:38:37 INFO - 'LOGNAME': 'cltbld', 15:38:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:37 INFO - 'MOZ_NO_REMOTE': '1', 15:38:37 INFO - 'NO_EM_RESTART': '1', 15:38:37 INFO - 'PAGER': '/bin/cat', 15:38:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:38:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:37 INFO - 'PWD': '/builds/slave/test', 15:38:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:38:37 INFO - 'SHELL': '/bin/bash', 15:38:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LnoCUeu4FI/Listeners', 15:38:37 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 15:38:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:38:37 INFO - 'USER': 'cltbld', 15:38:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:38:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:38:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:37 INFO - 'XPC_FLAGS': '0x0', 15:38:37 INFO - 'XPC_SERVICE_NAME': '0', 15:38:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:38:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:37 INFO - Downloading/unpacking psutil>=3.1.1 15:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:38:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:38:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:38:41 INFO - Installing collected packages: psutil 15:38:41 INFO - Running setup.py install for psutil 15:38:41 INFO - building 'psutil._psutil_osx' extension 15:38:41 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 15:38:42 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 15:38:42 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 15:38:42 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 15:38:42 INFO - building 'psutil._psutil_posix' extension 15:38:42 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 15:38:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:38:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:38:42 INFO - ^ 15:38:42 INFO - 1 warning generated. 15:38:42 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 15:38:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:38:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:38:42 INFO - Successfully installed psutil 15:38:42 INFO - Cleaning up... 15:38:42 INFO - Return code: 0 15:38:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:38:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:42 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 0x104708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc942c765f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466cd30>, '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 0x1043d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104664b20>, '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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 15:38:42 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 15:38:42 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 15:38:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TvZKSOnpI2/Render', 15:38:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:38:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:38:42 INFO - 'HOME': '/Users/cltbld', 15:38:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:38:42 INFO - 'LOGNAME': 'cltbld', 15:38:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:42 INFO - 'MOZ_NO_REMOTE': '1', 15:38:42 INFO - 'NO_EM_RESTART': '1', 15:38:42 INFO - 'PAGER': '/bin/cat', 15:38:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:38:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:42 INFO - 'PWD': '/builds/slave/test', 15:38:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:38:42 INFO - 'SHELL': '/bin/bash', 15:38:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LnoCUeu4FI/Listeners', 15:38:42 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 15:38:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:38:42 INFO - 'USER': 'cltbld', 15:38:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:38:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:38:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:42 INFO - 'XPC_FLAGS': '0x0', 15:38:42 INFO - 'XPC_SERVICE_NAME': '0', 15:38:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:38:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:38: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 15:38: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 15:38: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 15:38: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 15:38: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 15:38: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 15:38:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:38:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:38:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:38:45 INFO - Installing collected packages: mozsystemmonitor 15:38:45 INFO - Running setup.py install for mozsystemmonitor 15:38:45 INFO - Successfully installed mozsystemmonitor 15:38:45 INFO - Cleaning up... 15:38:45 INFO - Return code: 0 15:38:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:38: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')]} 15:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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')]} 15:38:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:45 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 0x104708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc942c765f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466cd30>, '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 0x1043d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104664b20>, '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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 15:38:45 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 15:38:45 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 15:38:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TvZKSOnpI2/Render', 15:38:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:38:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:38:45 INFO - 'HOME': '/Users/cltbld', 15:38:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:38:45 INFO - 'LOGNAME': 'cltbld', 15:38:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:45 INFO - 'MOZ_NO_REMOTE': '1', 15:38:45 INFO - 'NO_EM_RESTART': '1', 15:38:45 INFO - 'PAGER': '/bin/cat', 15:38:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:38:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:45 INFO - 'PWD': '/builds/slave/test', 15:38:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:38:45 INFO - 'SHELL': '/bin/bash', 15:38:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LnoCUeu4FI/Listeners', 15:38:45 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 15:38:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:38:45 INFO - 'USER': 'cltbld', 15:38:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:38:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:38:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:45 INFO - 'XPC_FLAGS': '0x0', 15:38:45 INFO - 'XPC_SERVICE_NAME': '0', 15:38:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:38:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:46 INFO - Downloading/unpacking jsonschema==2.5.1 15:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:38:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:38:48 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 15:38:48 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 15:38:48 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 15:38:48 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 15:38:48 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 15:38:48 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 15:38:49 INFO - Downloading functools32-3.2.3-2.tar.gz 15:38:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:38:49 INFO - warning: no files found matching '*.txt' 15:38:49 INFO - no previously-included directories found matching 'build' 15:38:49 INFO - no previously-included directories found matching 'dist' 15:38:49 INFO - no previously-included directories found matching '.git*' 15:38:49 INFO - Installing collected packages: jsonschema, functools32 15:38:49 INFO - Running setup.py install for functools32 15:38:49 INFO - warning: no files found matching '*.txt' 15:38:49 INFO - no previously-included directories found matching 'build' 15:38:49 INFO - no previously-included directories found matching 'dist' 15:38:49 INFO - no previously-included directories found matching '.git*' 15:38:49 INFO - Successfully installed jsonschema functools32 15:38:49 INFO - Cleaning up... 15:38:49 INFO - Return code: 0 15:38:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:38: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')]} 15:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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')]} 15:38:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:49 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 0x104708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc942c765f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466cd30>, '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 0x1043d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104664b20>, '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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 15:38:49 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 15:38:49 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 15:38:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TvZKSOnpI2/Render', 15:38:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:38:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:38:49 INFO - 'HOME': '/Users/cltbld', 15:38:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:38:49 INFO - 'LOGNAME': 'cltbld', 15:38:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:49 INFO - 'MOZ_NO_REMOTE': '1', 15:38:49 INFO - 'NO_EM_RESTART': '1', 15:38:49 INFO - 'PAGER': '/bin/cat', 15:38:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:38:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:49 INFO - 'PWD': '/builds/slave/test', 15:38:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:38:49 INFO - 'SHELL': '/bin/bash', 15:38:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LnoCUeu4FI/Listeners', 15:38:49 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 15:38:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:38:49 INFO - 'USER': 'cltbld', 15:38:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:38:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:38:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:49 INFO - 'XPC_FLAGS': '0x0', 15:38:49 INFO - 'XPC_SERVICE_NAME': '0', 15:38:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:38:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:38:49 INFO - Cleaning up... 15:38:49 INFO - Return code: 0 15:38:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:38: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')]} 15:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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')]} 15:38:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:49 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 0x104708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc942c765f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466cd30>, '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 0x1043d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104664b20>, '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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 15:38:49 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 15:38:49 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 15:38:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TvZKSOnpI2/Render', 15:38:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:38:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:38:49 INFO - 'HOME': '/Users/cltbld', 15:38:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:38:49 INFO - 'LOGNAME': 'cltbld', 15:38:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:49 INFO - 'MOZ_NO_REMOTE': '1', 15:38:49 INFO - 'NO_EM_RESTART': '1', 15:38:49 INFO - 'PAGER': '/bin/cat', 15:38:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:38:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:49 INFO - 'PWD': '/builds/slave/test', 15:38:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:38:49 INFO - 'SHELL': '/bin/bash', 15:38:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LnoCUeu4FI/Listeners', 15:38:49 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 15:38:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:38:49 INFO - 'USER': 'cltbld', 15:38:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:38:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:38:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:49 INFO - 'XPC_FLAGS': '0x0', 15:38:49 INFO - 'XPC_SERVICE_NAME': '0', 15:38:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:38:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:49 INFO - Downloading/unpacking blobuploader==1.2.4 15:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:52 INFO - Downloading blobuploader-1.2.4.tar.gz 15:38:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:38:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:38:52 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 15:38:52 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 15:38:52 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 15:38:52 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 15:38:52 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 15:38:52 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 15:38:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:38:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:38:53 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 15:38:53 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 15:38:53 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 15:38:53 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 15:38:53 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 15:38:53 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 15:38:53 INFO - Downloading docopt-0.6.1.tar.gz 15:38:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:38:53 INFO - Installing collected packages: blobuploader, requests, docopt 15:38:53 INFO - Running setup.py install for blobuploader 15:38:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:38:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:38:53 INFO - Running setup.py install for requests 15:38:54 INFO - Running setup.py install for docopt 15:38:54 INFO - Successfully installed blobuploader requests docopt 15:38:54 INFO - Cleaning up... 15:38:54 INFO - Return code: 0 15:38:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:38:54 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')]} 15:38:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:54 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')]} 15:38:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:54 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 0x104708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc942c765f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466cd30>, '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 0x1043d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104664b20>, '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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 15:38:54 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 15:38:54 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 15:38:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TvZKSOnpI2/Render', 15:38:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:38:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:38:54 INFO - 'HOME': '/Users/cltbld', 15:38:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:38:54 INFO - 'LOGNAME': 'cltbld', 15:38:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:54 INFO - 'MOZ_NO_REMOTE': '1', 15:38:54 INFO - 'NO_EM_RESTART': '1', 15:38:54 INFO - 'PAGER': '/bin/cat', 15:38:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:38:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:54 INFO - 'PWD': '/builds/slave/test', 15:38:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:38:54 INFO - 'SHELL': '/bin/bash', 15:38:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LnoCUeu4FI/Listeners', 15:38:54 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 15:38:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:38:54 INFO - 'USER': 'cltbld', 15:38:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:38:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:38:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:54 INFO - 'XPC_FLAGS': '0x0', 15:38:54 INFO - 'XPC_SERVICE_NAME': '0', 15:38:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:38:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:38:54 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-yHWJZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:38:54 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-8fjiki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:38:54 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-UKOyjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:38:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-JDr3zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:38:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-J2hQEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:38:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-ltDJFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:38:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-52rWJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:38:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-2MAShD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:38:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-dm9PcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:38:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-K5CaZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:38:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-ErZOGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:38:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-upWyQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:38:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-hxXM5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:38:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:38:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-ngGBAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:38:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:38:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-wO8L1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:38:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:38:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-JCZ5k4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:38:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:38:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-cPDKV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:38:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:38:56 INFO - Running setup.py install for manifestparser 15:38:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:38:56 INFO - Running setup.py install for mozcrash 15:38:56 INFO - Running setup.py install for mozdebug 15:38:56 INFO - Running setup.py install for mozdevice 15:38:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:38:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:38:57 INFO - Running setup.py install for mozfile 15:38:57 INFO - Running setup.py install for mozhttpd 15:38:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:38:57 INFO - Running setup.py install for mozinfo 15:38:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:38:57 INFO - Running setup.py install for mozInstall 15:38:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:38:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:38:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:38:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:38:57 INFO - Running setup.py install for mozleak 15:38:57 INFO - Running setup.py install for mozlog 15:38:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:38:57 INFO - Running setup.py install for moznetwork 15:38:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:38:58 INFO - Running setup.py install for mozprocess 15:38:58 INFO - Running setup.py install for mozprofile 15:38:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:38:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:38:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:38:58 INFO - Running setup.py install for mozrunner 15:38:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:38:58 INFO - Running setup.py install for mozscreenshot 15:38:58 INFO - Running setup.py install for moztest 15:38:58 INFO - Running setup.py install for mozversion 15:38:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:38:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:38:59 INFO - Cleaning up... 15:38:59 INFO - Return code: 0 15:38:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:59 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 0x104708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc942c765f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466cd30>, '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 0x1043d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104664b20>, '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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 15:38:59 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 15:38:59 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 15:38:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TvZKSOnpI2/Render', 15:38:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:38:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:38:59 INFO - 'HOME': '/Users/cltbld', 15:38:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:38:59 INFO - 'LOGNAME': 'cltbld', 15:38:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:59 INFO - 'MOZ_NO_REMOTE': '1', 15:38:59 INFO - 'NO_EM_RESTART': '1', 15:38:59 INFO - 'PAGER': '/bin/cat', 15:38:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:38:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:59 INFO - 'PWD': '/builds/slave/test', 15:38:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:38:59 INFO - 'SHELL': '/bin/bash', 15:38:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LnoCUeu4FI/Listeners', 15:38:59 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 15:38:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:38:59 INFO - 'USER': 'cltbld', 15:38:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:38:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:38:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:59 INFO - 'XPC_FLAGS': '0x0', 15:38:59 INFO - 'XPC_SERVICE_NAME': '0', 15:38:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:38:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:38:59 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-xeGroF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:38:59 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)) 15:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:38:59 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-yYXdCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:38:59 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)) 15:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:38:59 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-OvhdlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:38:59 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)) 15:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:38:59 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-QNpO5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:38:59 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)) 15:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:38:59 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-wW49yL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:38:59 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)) 15:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:38:59 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-_7mhsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:38:59 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)) 15:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:38:59 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-iYKNzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:00 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)) 15:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-iTX393-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:00 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)) 15:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-MGAh2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:00 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)) 15:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-N7miuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:00 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)) 15:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-37UNNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:00 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)) 15:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-zXPPDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:00 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)) 15:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-_QjTG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:00 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)) 15:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-tgL12D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:00 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)) 15:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-5EZAg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:00 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)) 15:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:00 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-32Kvy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:01 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)) 15:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:01 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-ydWCdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:01 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)) 15:39:01 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)) 15:39:01 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)) 15:39:01 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)) 15:39:01 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)) 15:39:01 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)) 15:39:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:39:01 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 15:39:01 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 15:39:01 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 15:39:01 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 15:39:01 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 15:39:01 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 15:39:03 INFO - Downloading blessings-1.6.tar.gz 15:39:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:39:03 INFO - Installing collected packages: blessings 15:39:03 INFO - Running setup.py install for blessings 15:39:03 INFO - Successfully installed blessings 15:39:03 INFO - Cleaning up... 15:39:03 INFO - Return code: 0 15:39:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:39:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:39:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:39:04 INFO - Reading from file tmpfile_stdout 15:39:04 INFO - Current package versions: 15:39:04 INFO - blessings == 1.6 15:39:04 INFO - blobuploader == 1.2.4 15:39:04 INFO - docopt == 0.6.1 15:39:04 INFO - functools32 == 3.2.3-2 15:39:04 INFO - jsonschema == 2.5.1 15:39:04 INFO - manifestparser == 1.1 15:39:04 INFO - mozInstall == 1.12 15:39:04 INFO - mozcrash == 0.17 15:39:04 INFO - mozdebug == 0.1 15:39:04 INFO - mozdevice == 0.48 15:39:04 INFO - mozfile == 1.2 15:39:04 INFO - mozhttpd == 0.7 15:39:04 INFO - mozinfo == 0.9 15:39:04 INFO - mozleak == 0.1 15:39:04 INFO - mozlog == 3.3 15:39:04 INFO - moznetwork == 0.27 15:39:04 INFO - mozprocess == 0.23 15:39:04 INFO - mozprofile == 0.28 15:39:04 INFO - mozrunner == 6.12 15:39:04 INFO - mozscreenshot == 0.1 15:39:04 INFO - mozsystemmonitor == 0.3 15:39:04 INFO - moztest == 0.7 15:39:04 INFO - mozversion == 1.4 15:39:04 INFO - psutil == 3.1.1 15:39:04 INFO - requests == 1.2.3 15:39:04 INFO - wsgiref == 0.1.2 15:39:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:04 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')]} 15:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:04 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')]} 15:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:04 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 0x104708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc942c765f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466cd30>, '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 0x1043d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104664b20>, '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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 15:39:04 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 15:39:04 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 15:39:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TvZKSOnpI2/Render', 15:39:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:04 INFO - 'HOME': '/Users/cltbld', 15:39:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:04 INFO - 'LOGNAME': 'cltbld', 15:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:04 INFO - 'MOZ_NO_REMOTE': '1', 15:39:04 INFO - 'NO_EM_RESTART': '1', 15:39:04 INFO - 'PAGER': '/bin/cat', 15:39:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:04 INFO - 'PWD': '/builds/slave/test', 15:39:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:04 INFO - 'SHELL': '/bin/bash', 15:39:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LnoCUeu4FI/Listeners', 15:39:04 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 15:39:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:04 INFO - 'USER': 'cltbld', 15:39:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:04 INFO - 'XPC_FLAGS': '0x0', 15:39:04 INFO - 'XPC_SERVICE_NAME': '0', 15:39:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:04 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)) 15:39:04 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)) 15:39:04 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)) 15:39:04 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)) 15:39:04 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)) 15:39:04 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)) 15:39:04 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)) 15:39:04 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)) 15:39:04 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)) 15:39:04 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)) 15:39:04 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)) 15:39:04 INFO - Cleaning up... 15:39:04 INFO - Return code: 0 15:39:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:39:04 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')]} 15:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:04 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')]} 15:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:04 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 0x104708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc942c765f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10466cd30>, '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 0x1043d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104664b20>, '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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 15:39:04 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 15:39:04 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 15:39:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TvZKSOnpI2/Render', 15:39:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:04 INFO - 'HOME': '/Users/cltbld', 15:39:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:04 INFO - 'LOGNAME': 'cltbld', 15:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:04 INFO - 'MOZ_NO_REMOTE': '1', 15:39:04 INFO - 'NO_EM_RESTART': '1', 15:39:04 INFO - 'PAGER': '/bin/cat', 15:39:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:04 INFO - 'PWD': '/builds/slave/test', 15:39:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:04 INFO - 'SHELL': '/bin/bash', 15:39:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LnoCUeu4FI/Listeners', 15:39:04 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 15:39:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:04 INFO - 'USER': 'cltbld', 15:39:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:04 INFO - 'XPC_FLAGS': '0x0', 15:39:04 INFO - 'XPC_SERVICE_NAME': '0', 15:39:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:39:04 INFO - Cleaning up... 15:39:04 INFO - Return code: 0 15:39:04 INFO - Running post-action listener: _resource_record_post_action 15:39:04 INFO - Running post-action listener: _start_resource_monitoring 15:39:04 INFO - Starting resource monitoring. 15:39:04 INFO - [mozharness: 2016-09-06 22:39:04.785052Z] Finished create-virtualenv step (success) 15:39:04 INFO - [mozharness: 2016-09-06 22:39:04.786039Z] Running install step. 15:39:04 INFO - Running pre-action listener: _resource_record_pre_action 15:39:04 INFO - Running main action method: install 15:39:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:39:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:39:05 INFO - Reading from file tmpfile_stdout 15:39:05 INFO - Detecting whether we're running mozinstall >=1.0... 15:39:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:39:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:39:05 INFO - Reading from file tmpfile_stdout 15:39:05 INFO - Output received: 15:39:05 INFO - Usage: mozinstall [options] installer 15:39:05 INFO - Options: 15:39:05 INFO - -h, --help show this help message and exit 15:39:05 INFO - -d DEST, --destination=DEST 15:39:05 INFO - Directory to install application into. [default: 15:39:05 INFO - "/builds/slave/test"] 15:39:05 INFO - --app=APP Application being installed. [default: firefox] 15:39:05 INFO - mkdir: /builds/slave/test/build/application 15:39:05 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'] 15:39:05 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 15:39:38 INFO - Reading from file tmpfile_stdout 15:39:38 INFO - Output received: 15:39:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:39:38 INFO - Running post-action listener: _resource_record_post_action 15:39:38 INFO - [mozharness: 2016-09-06 22:39:38.894276Z] Finished install step (success) 15:39:38 INFO - [mozharness: 2016-09-06 22:39:38.894395Z] Running run-tests step. 15:39:38 INFO - Running pre-action listener: _resource_record_pre_action 15:39:38 INFO - Running main action method: run_tests 15:39:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:39:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:39:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:39:38 INFO - 2016-09-06 15:39:38.907 screenresolution[1644:8079] starting screenresolution argv=screenresolution get 15:39:38 INFO - 2016-09-06 15:39:38.910 screenresolution[1644:8079] Display 0: 1600x1200x32@60 15:39:38 INFO - 2016-09-06 15:39:38.915 screenresolution[1645:8081] starting screenresolution argv=screenresolution list 15:39:38 INFO - Available Modes on Display 0 15:39:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:39:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:39:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:39:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:39:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:39:39 INFO - Intel Iris: 15:39:39 INFO - Chipset Model: Intel Iris 15:39:39 INFO - Type: GPU 15:39:39 INFO - Bus: Built-In 15:39:39 INFO - VRAM (Dynamic, Max): 1536 MB 15:39:39 INFO - Vendor: Intel (0x8086) 15:39:39 INFO - Device ID: 0x0a2e 15:39:39 INFO - Revision ID: 0x0009 15:39:39 INFO - Displays: 15:39:39 INFO - Display: 15:39:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:39:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:39:39 INFO - Display Serial Number: 1600x1200 60 15:39:39 INFO - Main Display: Yes 15:39:39 INFO - Mirror: Off 15:39:39 INFO - Online: Yes 15:39:39 INFO - Rotation: Supported 15:39:39 INFO - Return code: 0 15:39:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:39:39 INFO - Python 2.7.3 15:39:39 INFO - Return code: 0 15:39:39 INFO - grabbing minidump binary from tooltool 15:39:39 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')]} 15:39:39 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 0x10466cd30>, '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 0x1043d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104664b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:39:39 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 15:39:39 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 15:39:39 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 15:39:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:39:39 INFO - Return code: 0 15:39:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:39:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:39:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:39:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:39:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:39:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0003 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:39:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TvZKSOnpI2/Render', 15:39:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:39:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:39:39 INFO - 'HOME': '/Users/cltbld', 15:39:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:39:39 INFO - 'LOGNAME': 'cltbld', 15:39:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:39:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:39 INFO - 'MOZ_NO_REMOTE': '1', 15:39:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:39:39 INFO - 'NO_EM_RESTART': '1', 15:39:39 INFO - 'PAGER': '/bin/cat', 15:39:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:39 INFO - 'PWD': '/builds/slave/test', 15:39:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:39:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:39:39 INFO - 'SHELL': '/bin/bash', 15:39:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LnoCUeu4FI/Listeners', 15:39:39 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 15:39:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:39:39 INFO - 'USER': 'cltbld', 15:39:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:39:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:39 INFO - 'XPC_FLAGS': '0x0', 15:39:39 INFO - 'XPC_SERVICE_NAME': '0', 15:39:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:39:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0003', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:39:39 INFO - mozversion application_buildid: 20160906144116 15:39:39 INFO - mozversion application_changeset: 2e6b9e57d5a0527bd51f86be22e930acf450cf8d 15:39:39 INFO - mozversion application_display_name: Nightly 15:39:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:39:39 INFO - mozversion application_name: Firefox 15:39:39 INFO - mozversion application_remotingname: firefox 15:39:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:39:39 INFO - mozversion application_vendor: Mozilla 15:39:39 INFO - mozversion application_version: 51.0a1 15:39:39 INFO - mozversion platform_buildid: 20160906144116 15:39:39 INFO - mozversion platform_changeset: 2e6b9e57d5a0527bd51f86be22e930acf450cf8d 15:39:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:39:39 INFO - mozversion platform_version: 51.0a1 15:39:39 INFO - using testdate: 1473201579 15:39:39 INFO - actual date: 1473201579 15:39:39 INFO - starting webserver on 'localhost:49266' 15:39:39 INFO - SUITE-START | Running 2 tests 15:39:39 INFO - TEST-START | dromaeo_css 15:39:39 INFO - operating with platform_type : mac_ 15:39:39 INFO - Initialising browser for dromaeo_css test... 15:39:39 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpXL9v5y/profile http://localhost:49266/getInfo.html) 15:39:42 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 15:39:42 INFO - PROCESS | 1655 | colorDepth:24 15:39:42 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 15:39:42 INFO - PROCESS | 1655 | __metrics 15:39:42 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:39:42 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:39:42 INFO - TEST-INFO | 1655: exit 0 15:39:42 INFO - Browser initialized. 15:39:42 INFO - Running cycle 1/1 for dromaeo_css test... 15:39:42 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpXL9v5y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:39:54 INFO - PROCESS | 1658 | RSS: Main: 232509440 15:39:54 INFO - PROCESS | 1658 | 15:40:46 INFO - PROCESS | 1658 | 1473201646352 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] 15:42:58 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 15:42:59 INFO - PROCESS | 1658 | RSS: Main: 378576896 15:42:59 INFO - PROCESS | 1658 | 15:46:02 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 15:46:03 INFO - PROCESS | 1658 | RSS: Main: 376467456 15:46:03 INFO - PROCESS | 1658 | 15:49:05 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 15:49:06 INFO - PROCESS | 1658 | RSS: Main: 341442560 15:49:06 INFO - PROCESS | 1658 | 15:52:08 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 15:52:09 INFO - PROCESS | 1658 | RSS: Main: 336330752 15:52:09 INFO - PROCESS | 1658 | 15:55:12 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 15:55:13 INFO - PROCESS | 1658 | RSS: Main: 327344128 15:55:13 INFO - PROCESS | 1658 | 15:58:16 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 15:58:16 INFO - PROCESS | 1658 | RSS: Main: 558977024 15:58:16 INFO - PROCESS | 1658 | 15:58:16 INFO - PROCESS | 1658 | __start_tp_report 15:58:16 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 15:58:16 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 15:58:16 INFO - PROCESS | 1658 | |i|pagename|runs| 15:58:16 INFO - PROCESS | 1658 | |0;dojo.html;3313.4532802087656;3322.1362445764103;3352.296017836254;3361.1765117546206;3401.3877502049636;5547.611667183299;5652.293463317085;5669.546436285098;5694.914576281359;5770.971145144274;5698.914516282259;5726.455986681269;5730.255066841315;5737.082066869301;5751.367349591542;5177.042247184274;5224.869378265555;5251.975864706384;5257.106291930381;5293.497117773824;5269.657472264291;5292;5293.788248470076;5299.894002119955;5304.230886521452;5188.818391356303;5190.143626301656;5216.8174113906025;5220.79116835328;5272.31459910213;5302.072137375963;5306.6550674206055;5326;5332.493413125747;5359.624826262163;5102.311187989648;5117.513836185556;5169.663971841818;5220.738963051832;5257.211418287249;5625.268715067056;5693.28833895763;5719.28508936383;5748.856278593027;5765.4811067004175;6210;6262.5929314594405;6262.78080267193;6271.435570798604;6284.842878928018;6111.2804801863185;6215.844603884894;6274.123592375962;6274.968625156845;6325.810225693236;5795.391483894215;5822.708864556797;5825.097109947965;5838.620489668158;5845.649261044351;6258.718357673915;6298.748050077946;6314.494840412848;6321.462675672619;6325.841853953688;5867.589268751147;6100.054491553817;6106;6151.446369826647;6164.044573091178;6244.75020999164;6259.561830671901;6291.1192433059405;6293.96853015732;6300.779472718433;5017.473165317662;5947.434993675594;6008.218931538872;6027.969860150759;6036.5170786336985;6092.964196086676;6101.877962440726;6112;6113.020255720364;6138.294096178996;4914.140025495524;4919.114559379274;4919.827806026772;4926.556609905054;4932.1861892788;4416.734995900256;4457.375967364572;4459.888502787391;4463.24124898768;4466.3747075409465;5642.351129620146;5680.573956953245;5693.686847223443;5704.914426283609;5705.343885453143;5278.788848446096;6178.980468222783;6180.7527698891545;6203.379662033851;6264.310925798159;5683.204351390808;5693.231413759172;5695.40198279183;5695.829125126253;5729.054705973551;5385.7576409061685;5396.244525766396;5407.188921661782;5416.295881535375;5422.267993820863;6260.154879091356;6262.6868656566985;6263.498920086473;6308.621482711052;6322.968385158137;6296.087067375296;6307.432331090224;6310.968445157745;6323.177986861679;6334.8733025338315;6326.6520341381665;6347.5239357047485;6354.491640668828;6364.872702546016;6366.681665916778;5975.970120149545;6026.517878569616;6094.924532418307;6126.050462178369;6192.411720886508;6257.78097766576;6303.558750887394;6315.5263355247625;6322.810315690536;6324.6521441321165;6151.784687535915;6156.569040167145;6167.383261673796;6169.876602468015;6182.917989351756;5895.823125306248;5979.760809567569;6033.456988871023;6146.0473626587955;6149.2620885494025;5858.121281807763;5954.642721436728;5978.252718410199;5991.610545314541;5993.730282137227;5910.70446477683;6098.298695650057;6116.17431646722;6125.938740612537;6150.292716337678;5719.5710321726865;5893;5948.643081415129;5951.791687290924;5959.28488581373 15:58:16 INFO - PROCESS | 1658 | |1;ext.html;443.87793356826876;469.038471134175;525.915853463446;527.986800329992;552.9806456774008;30005;36760.81619591902;41007;41032.58967410325;41202.381964270484;42534;42915.14169716604;42943.57056429435;43022.35466468005;43026.7848660757;8075.878861817077;9674.854877176847;9871.605135794596;9875;9877.407355558653;8206.548639824807;8960.462372257653;11740.882591174064;11884;11922.225055371417;11906.880931190708;11937.34344611046;11938.283702977806;11957.42915273216;11962.043036557097;12009.339486328248;12080.939595302056;12090.214136081215;12095.939520302341;12106.455209515592;11861.229020113751;11918;11928.04575633956;11940.104492163122;11944.28334299936;39982.20035599272;40149.59850401488;40223.59776402228;40248.396274055915;40290.791276261756;3262.5106234064842;3307.570015897942;3310.2717402171484;3328.683775041367;3340.2150494633743;30220.395592088254;30264.8486757567;30726.84636576825;31092.689073109206;31388.529172062437;3038.255478861961;3062.3569050499423;3086.42901063304;3112.5798017267607;3116.096332063699;468985;471173;472538;473317;477142;60401.69799150976;60822.08766868412;60828;60832.57095150259;60961.695191524646;33961.66038339585;34753.65246347504;63960.603939605666;64087.35912640907;64135.35864641294;15985.200739963188;16544.50366489007;16552;16580.6683866322;16605.50183494473;11475.483603237873;12646.620601381974;12794.104412691022;12804.231746095264;12809;16184.676306473804;16746.832531674772;16761.91619041897;16785.580360491087;18395.63208735818;7617.5048621839405;7800.687972481151;7801.336886364609;7839.33365663925;7922.603869806487;856.7643897928119;860.2086080805674;921.0237148622348;1016.207358260558;1039.9844002339971;29645.703542964726;29669;29696.82856619105;29761;30306.393872122433;1005.9295849290479;1064.4730858225073;1091.1761619976867;1106.468894930438;1160.976780464403;13592.796108058386;14265.57344265586;14578.979471436898;15061.322240499203;15367;687983;695810;696868;697960;699205;49409.50590494121;55790;56882.43117568854;56932.14601780977;57248.71375643178;1352.6212660455087;1371.9862801372058;1393.7142885708406;1393.9512117075853;1394.0729414939156;30220.24449388695;31081;31129;31804.840975794974;31965.520517191795;48705.5129448701;49493.752531238555;49542;49546.25680614721;49586;616713;621938;622972;623579;624430.1905280951;22563.43591410228;25935.481290374464;25935.740642593333;26200.34499137537;26206.34484137912;46361.536384637075;46817.06365872732;47626;48122.79693007563;48283.5171648279;36296.27407451783;36619;38822.80588597039;39181.80409097936;40057.198856022136;38209.853704388916;38696.41955370615;39270;40212;40375;37881.62118378781;38642.42036369513;38708;38728;38766.6123338763;30693;31113.755449781755;34101;34286;34393.484097739056;22715.772842271366;24515.01939922383;25216.74783252144;25773.613395798704;25826.741732583185 15:58:16 INFO - PROCESS | 1658 | |2;jquery.html;351.55879371388903;371.75092687899104;374.0965568152909;384.4425582904788;404.89675132841137;14935.551933441988;15513.922430387847;19494.512637184078;20185.798142018575;20197.99010049499;19845.900770496126;20156;20500.69248961267;20503.897480512613;20995.47511312218;5251.159814429692;5855.355910849792;6097.847553811146;6114.69426528674;6119.3574674659185;4149.647279981205;4423.248047831877;5971.731272092767;6145.661988590626;6419.5506314558;6634.038064480647;6709.9329006709795;6724.226713927912;6732.562383445061;6741.629210393451;5509.449055094478;6383.553151279412;6651.23510796255;6651.334866513358;6653.634050127241;5512.283403157604;5724.341700704431;5747.339056008571;5752.2234498342605;5763.366029736726;25170.993160273567;25276.873615631805;25445.236642900556;25576.48847023049;25984.220473385827;2261.434641339665;2397.436602398435;2469.0370755405406;2524.5708229601014;2819.323362393011;12111;15879.36482540697;15982.280797364147;16052.27764750589;16271.023738575605;1820.8634352423621;1839.3378383781828;1871.4011516314786;1876.4370688793308;1891.782445018813;111545.88454115436;115766;116482.8351716489;117402;117751;10528.420936848393;10746.408947507805;10805.056154503549;10960.616378426717;11096.833547496793;13622.659433514242;13627.931860340834;13649.044566880222;13652.24912629815;13658.180509169482;7984.640691168911;8262.462939908886;8267.875981860278;8412.537310447984;8419.663213471515;8220;8225.906075036371;8256.25693687559;8386.87419688705;8419.915800841913;6592.670366481752;6703;6705.6982435790505;6706.83232919181;6844.452443804484;1647.736362182045;1665.966680666404;1677.7315629499221;1733.566608347913;1794.7128459446426;892.4154678685471;923.5013092929743;930.093159169804;1026.2816028779885;1033.715728174758;21793.782062179493;22543.774562254497;22609.547809044056;22639.434014149778;22649.094036238366;946.1437399153776;965.8164948659733;967.4147140979746;969.0938972061145;1003.6035765872585;11086.944565277123;11817.409129543488;11958.342291173894;12022.218555793845;12038.217515861441;521688;577716.1114194402;580054;603380;604758;79383;81482.59258703669;111724.4413777926;114869;118558.4072079634;915.56053094515;977.750673578233;1007.2697294461603;1024.4467987286782;1025.410389026313;15520.689586208438;19167.656373713493;19712.40862774119;19822.207111715947;19941.700874487186;34736.652633474354;54937.901241974134;54939.72530137324;55391;56268.15597766119;80429.59785201037;81812.59093704731;83466;83530.58234709031;85129.57435212785;7617.352525035212;7767.6406628838595;7963.12405635368;8229.670813167646;8243.546604936786;11708.531658733555;12358.258504489759;12980.935095324463;13189.340532973505;13333.866661333263;8813.206811387006;10126.18990480745;10260.897391026292;10566.788664226826;11403.543858245908;9153.63385464574;11167.944160279147;11355.772884542097;11415.885841141482;11482.655520334403;11041.171912106785;11247.718807029889;11252.718682033015;11747.295162289947;11836.408179591159;7960.363170946194;7985.480943738638;8073.798155046171;8149.511029338259;8180.877286840821;8905.777355565904;10679.412632304988;11417.828732569184;11548.018418434527;11627.767444651228 15:58:16 INFO - PROCESS | 1658 | |3;mootools.html;1388.6736616895028;1500.1824005916772;1692.9915350423246;1844.6218525202335;1900.5935607650342;8296.875546866802;8830.337724670644;8838.381313308055;8840.823183536326;8842.292616590674;6683.431908287789;7742.5354478731315;8436.578171091436;8587.871181932276;8650.783730406743;2345.495718420539;2395.688560487143;2396.4727759892885;2397.1490121007055;2398.2205783120467;2340.3564019894493;2362.6574146748712;2386.1171366594385;2387.259949415678;2388.6178211486167;2064.7935206479383;2148.967765483519;2331.230693871027;2403.170906037423;2406.927792166229;2374.5013547155118;2403.735589085199;2405.3986503374244;2406.230006397936;2410.8312418130563;2348.5537747827857;2381.499885024147;2383.2731017039796;2391.294568102414;2395.1497218487534;8685.826283474295;8709.477431354138;8774.780630484225;8785.42894711848;8809.207171354545;5182.714950677711;5336.039512887679;5342.118550439173;5344.893102137936;5347.6256662033675;2937.9265518362;3388.96611033889;3434.1414646338417;3540.91147721309;3542.2738338640515;1077.2513103393067;1082.355998181081;1085.45184681736;1105.3312745788842;1106.468894930438;9830.606775728962;11374.203805733601;11383.487743051666;11384.544618215345;11403.714907127305;4571.131485017836;4657.184992626277;4746.5490778376;4771.785269662873;4790.592799612003;9823.115919567274;9830.361026533252;9832.065953734384;9834.21326293909;9844.409335439897;5337.8131765388025;5339.519443250048;5341.519263266325;5344.545713614309;5347.144456886879;4710.4347478302825;4856.8300109495995;4909.877253068702;4910.165271903785;4913.263010548446;3722.8510859565313;3765.4163604641326;3796.7342286040266;3833.884983450501;3854.1520865409566;1173.2256710570982;1178.4873579992732;1184.1054313099144;1187.3113594115393;1193.4331192683405;607.5170239659393;743.4684200796456;862.6851199312206;865.3682811547606;919.6827094652334;5203.7918483261;5421.978695837124;5654.604177707603;5727.828165155052;5751.482366587027;465.19985624725416;469.2374890802446;473.67789902865695;485.38841060263616;610.664134725899;3270.721988630993;3603.8558457661925;3800.068983099103;3866.903327416837;3892.4939757831303;9593.568289426992;10621.150307975344;10636.521356538975;10637.893621063846;10643.41461219625;10218.386896786216;10579.259451838452;10579.418132002813;10582.57669693219;10594.523246453928;7965.044194696674;8171.83446386708;8173.141820108803;8175.63209655567;8179.754607361908;4825.010872771144;5470;5559.527440167631;5584.022796010763;5678.290213723285;8844.292456603327;9647.517624118906;9713.757156070873;9889.554970026222;10008.749781255527;10024.448655324028;10037.096661300518;10037.648682296085;10105.292629515863;10171.898281017095;5950.9404905952115;6878.828029299308;6893.517453778188;6967.581945083311;6970.581765094111;7965.442419030613;8170.836583268182;8273.627686754231;8332.750017499484;8333.916660833314;7318.3047610476915;7490.250974902466;7502.474826762074;7525.698972041276;7547.773566793005;7349.265073492608;7385.409167266498;7394.852102958018;7456.925430745623;7460.4031677466755;6779.762708305186;6867.416269617139;6923.411510021705;6948.235694073749;6955.686994085379;4766.761661916959;4815.7351345676325;4849.80600775965;4856.247281671346;4976.975115124401;4845.927311090407;4897.2654101885;4904.975475122602;4934.7285899275885;4950.579200767831 15:58:16 INFO - PROCESS | 1658 | |4;prototype.html;298.12797567116183;302.2232861545828;307.88300445830583;313.69884910485933;316.4825510290677;5159.226116082585;5225.320708307915;5337.626366154371;5371.435999220084;5487.451254874511;4575.611073058785;4979.651424400294;4989.226669866168;5093.567046801017;5166.9224961625605;4092.508898932125;4116.176764647083;4135.379693046037;4154.750714957097;4159;3472.909110891309;3974;4118.135191609766;4137.9448240698575;4160.791960401983;3297.96702032979;4089.8773036808943;4100.733452325604;4127.111022284398;4127.637879499758;3489.214926641498;4069.4302797608357;4083.3262511685534;4084;4090.9386359204636;3576.5708115026364;3585.7230658531757;3590.2281009583066;3603.1892824114366;3635.8909232723063;4885.778555361159;5012.8997420051755;5084.491550844923;5282.550983166436;5373.7581808818695;4612.331211974279;4617.8152873885;4646.582792248363;4666.9766651166865;4685.750839596978;5308.840734777963;5575.916361254584;5662.66024038559;5676.262085928803;5707.5148612368;3266.5263536787033;3697.796621185834;3946.7039972002217;3968.8568173186445;4014.7992600369867;4973.681974276819;5787.363390027093;5907.409259074101;5959.374265702126;5973.685789126385;3346.464565669481;4012.3179059559943;4044.9797751011056;4075.898102547401;4109.691773116969;3235.0456615298544;3918.294706952719;4097.672186225095;4101.487314085739;4125.689613816273;4663.836765713184;4683.578477937002;4730.47964723887;4731.077439899255;4740.762961851894;5686.289213848269;5737.569682273846;5766.913496297559;5768.740406681625;5772;1290.7418516296777;1324.7151862349642;1381.6269607205998;1387.5292190278337;1402.9929850350684;784.9725259615886;843.8354520868372;844.4679851693394;863.3869952333913;867.5468590111537;3990.0822810753107;4262.978685106555;4406.603405693503;4481.574250446202;4503.54964503547;3996.6003399659803;4092.631663150331;4670.602998745145;4814.735189564538;4873.926891096637;3819.5363990226147;3878.631530004645;4186.602272784081;4327.978360108179;4405.735655860659;2165.2677857713766;2222.244436891465;2380.416797884495;2502.974970250274;2548.0125502108012;4400.23990403841;4482.731036137842;4563.732909729413;4949.257611358325;5041.722705251246;4431.8670439886855;4777.362711999804;4871.80512779492;4923.556879880756;4938.592501137158;4307.827686892552;4470.418845550058;4871.2206047032305;4931.5315045070665;5031.4213865405945;4288.378185163197;4609.1703493371515;4850.927236091391;4854.344663470386;4880.536349046977;4074.5314288855975;4210.494740631144;4580.167932826896;4661.3452224459825;4672.6495512837355;4131.97934010328;4429.759667293163;4650.000249946278;4662.860114196579;4720.834770783006;3164.7784655073924;3425.9486107708904;3495.807730574843;3499.580050393969;3511.4908338291316;4349.173657005159;4576;4734.958309171977;4763.928116173834;4945.4807245238735;4483.686141970031;5078.568321692697;5099.592032637454;5292;5571.771291483439;4980.750962451935;5658.9151162733415;5667.234923285304;5684.516816070515;5707.944030354398;4183;4970.7514624267915;5114.820981265638;5415.864603384947;5574.804881829116;4514.600473852985;5302.9469705304;5362.302900622894;5477.890442191054;5699.287589051369;5561.3882472927135;5569.916451253315;5594.265777608792;5605.663660180402;5613.24221230145 15:58:16 INFO - PROCESS | 1658 | |5;yui.html;665.268204974528;773.5165521549036;776.2935728487078;806.7660378490239;816.2531283875254;15036.548903532885;15414.922925385385;15834.920825395886;15865.84134158658;16474.75287870683;14721.631959201026;15040;15041.548753537412;15051.849481505209;15063.39746410145;677.546044150418;848.3594885861166;868.7437206024238;869.9695510657128;871.3900269811157;698.6297262450909;849;863.1196179896501;867.6052396159732;875.2429148786317;888.3470649072926;889.1375365895103;890.1499068389672;891.9598618062171;892.78573142446;716.1585137463512;883.9127872716531;885.4510203673699;885.4775682347446;887.9334049946248;663.1478550063146;664.2062735031639;669.4477056428417;677.793273051719;678.5860625018735;14873.628159295997;14914.925425372912;14942.850571494255;14943.850561494355;14944.775828362583;13774.586762397144;13987.720245595032;14146;14199.432022719078;14478.42086316546;12628.747425051448;12711.364431778375;12722.236665800081;12763.617091487271;12980.350982450935;4541.682082254243;4645.76771161444;4656.743879086649;4669.159551280768;4698.130845793505;121436.39281803534;124537;124541.37729311295;124553.37723311325;125025;10991.230613857113;11098.722531936766;11139.554417823196;11236.213465057532;11501.309921404576;22574.774252257597;22888.198913037962;22925.656115158286;22956.19653312126;22966.770332296463;7678.117016543058;7810.258025487569;7860.528368297921;7902.130765615778;8044.879326810103;4188.979055104705;4328;4341.804618792162;4347.739135651871;4549.340345649863;3487.215376540309;3600.621934696819;3601.405768048295;3604.3692353838496;3610.945835812465;650.2099948562519;661.5435349608755;666.9366410191024;676.2290988273372;677.5629718831326;605.5549171359047;609.7835268479714;610.7099127914205;614.8985417406167;622.4180391334118;8733.126687331216;8779.604917778715;8872.05572266583;8893.822123557493;9120.224780893699;584.8420926349862;590.3565114025732;602.8523011862122;621.88371872489;624.3912185618985;4087.550369459357;4251.298535741568;4269.5090064642345;4341.3068903127805;4379.934300985487;120126.3993680026;163499;167479;168294;172566;136073;139684;140399;141722;145350;116722;120273;123047;125361.37319313528;127285;8158.224968627971;8570.100139485514;8625;8647.092055334351;8922.910770892468;39736.60921867721;54480.09319673792;58780;60277.698611508415;61272;84038.57980710057;94803;95782.04217957731;97930;98390.60461070007;10388.597539332093;11099;11349.14881383883;11440.485178166837;11496;10995;12014.459349329127;12298;12490.250584964931;12686.048546358876;10320.741981450525;11653.6503904883;11989.700257493283;12071.275723456622;12489.625311240678;9774.462404567817;11624.709382265173;11947.10396720232;11960.401979901144;12452;11433.59982400612;11585.304881707125;11684.125304843124;11721.355325456841;11754.823677644672;7562.281583249583;9856;9968.352057116264;10093.293469457067;10267.281290309607;8282.591959366802;8508.914910850812;8786.956065219632;9798.363106398061;9964.850527241952 15:58:16 INFO - PROCESS | 1658 | __end_tp_report 15:58:16 INFO - PROCESS | 1658 | __start_cc_report 15:58:16 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1217 15:58:16 INFO - PROCESS | 1658 | __end_cc_report 15:58:16 INFO - PROCESS | 1658 | __startTimestamp1473202696515__endTimestamp 15:58:16 INFO - PROCESS | 1658 | 15:58:16 INFO - PROCESS | 1658 | ------- Summary: start ------- 15:58:16 INFO - PROCESS | 1658 | Number of tests: 6 15:58:16 INFO - PROCESS | 1658 | 15:58:16 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5735.46 Median:5910.70 stddev:632.13 (10.7%) stddev-sans-first:604.84 15:58:16 INFO - PROCESS | 1658 | Values: 3313.5 3322.1 3352.3 3361.2 3401.4 5547.6 5652.3 5669.5 5694.9 5771.0 5698.9 5726.5 5730.3 5737.1 5751.4 5177.0 5224.9 5252.0 5257.1 5293.5 5269.7 5292.0 5293.8 5299.9 5304.2 5188.8 5190.1 5216.8 5220.8 5272.3 5302.1 5306.7 5326.0 5332.5 5359.6 5102.3 5117.5 5169.7 5220.7 5257.2 5625.3 5693.3 5719.3 5748.9 5765.5 6210.0 6262.6 6262.8 6271.4 6284.8 6111.3 6215.8 6274.1 6275.0 6325.8 5795.4 5822.7 5825.1 5838.6 5845.6 6258.7 6298.7 6314.5 6321.5 6325.8 5867.6 6100.1 6106.0 6151.4 6164.0 6244.8 6259.6 6291.1 6294.0 6300.8 5017.5 5947.4 6008.2 6028.0 6036.5 6093.0 6101.9 6112.0 6113.0 6138.3 4914.1 4919.1 4919.8 4926.6 4932.2 4416.7 4457.4 4459.9 4463.2 4466.4 5642.4 5680.6 5693.7 5704.9 5705.3 5278.8 6179.0 6180.8 6203.4 6264.3 5683.2 5693.2 5695.4 5695.8 5729.1 5385.8 5396.2 5407.2 5416.3 5422.3 6260.2 6262.7 6263.5 6308.6 6323.0 6296.1 6307.4 6311.0 6323.2 6334.9 6326.7 6347.5 6354.5 6364.9 6366.7 5976.0 6026.5 6094.9 6126.1 6192.4 6257.8 6303.6 6315.5 6322.8 6324.7 6151.8 6156.6 6167.4 6169.9 6182.9 5895.8 5979.8 6033.5 6146.0 6149.3 5858.1 5954.6 5978.3 5991.6 5993.7 5910.7 6098.3 6116.2 6125.9 6150.3 5719.6 5893.0 5948.6 5951.8 5959.3 15:58:16 INFO - PROCESS | 1658 | 15:58:16 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:72270.91 Median:34339.74 stddev:161745.21 (471.0%) stddev-sans-first:162109.04 15:58:16 INFO - PROCESS | 1658 | Values: 443.9 469.0 525.9 528.0 553.0 30005.0 36760.8 41007.0 41032.6 41202.4 42534.0 42915.1 42943.6 43022.4 43026.8 8075.9 9674.9 9871.6 9875.0 9877.4 8206.5 8960.5 11740.9 11884.0 11922.2 11906.9 11937.3 11938.3 11957.4 11962.0 12009.3 12080.9 12090.2 12095.9 12106.5 11861.2 11918.0 11928.0 11940.1 11944.3 39982.2 40149.6 40223.6 40248.4 40290.8 3262.5 3307.6 3310.3 3328.7 3340.2 30220.4 30264.8 30726.8 31092.7 31388.5 3038.3 3062.4 3086.4 3112.6 3116.1 468985.0 471173.0 472538.0 473317.0 477142.0 60401.7 60822.1 60828.0 60832.6 60961.7 33961.7 34753.7 63960.6 64087.4 64135.4 15985.2 16544.5 16552.0 16580.7 16605.5 11475.5 12646.6 12794.1 12804.2 12809.0 16184.7 16746.8 16761.9 16785.6 18395.6 7617.5 7800.7 7801.3 7839.3 7922.6 856.8 860.2 921.0 1016.2 1040.0 29645.7 29669.0 29696.8 29761.0 30306.4 1005.9 1064.5 1091.2 1106.5 1161.0 13592.8 14265.6 14579.0 15061.3 15367.0 687983.0 695810.0 696868.0 697960.0 699205.0 49409.5 55790.0 56882.4 56932.1 57248.7 1352.6 1372.0 1393.7 1394.0 1394.1 30220.2 31081.0 31129.0 31804.8 31965.5 48705.5 49493.8 49542.0 49546.3 49586.0 616713.0 621938.0 622972.0 623579.0 624430.2 22563.4 25935.5 25935.7 26200.3 26206.3 46361.5 46817.1 47626.0 48122.8 48283.5 36296.3 36619.0 38822.8 39181.8 40057.2 38209.9 38696.4 39270.0 40212.0 40375.0 37881.6 38642.4 38708.0 38728.0 38766.6 30693.0 31113.8 34101.0 34286.0 34393.5 22715.8 24515.0 25216.7 25773.6 25826.7 15:58:16 INFO - PROCESS | 1658 | 15:58:16 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:33896.56 Median:13900.72 stddev:96107.05 (691.4%) stddev-sans-first:96343.65 15:58:16 INFO - PROCESS | 1658 | Values: 351.6 371.8 374.1 384.4 404.9 14935.6 15513.9 19494.5 20185.8 20198.0 19845.9 20156.0 20500.7 20503.9 20995.5 5251.2 5855.4 6097.8 6114.7 6119.4 4149.6 4423.2 5971.7 6145.7 6419.6 6634.0 6709.9 6724.2 6732.6 6741.6 5509.4 6383.6 6651.2 6651.3 6653.6 5512.3 5724.3 5747.3 5752.2 5763.4 25171.0 25276.9 25445.2 25576.5 25984.2 2261.4 2397.4 2469.0 2524.6 2819.3 12111.0 15879.4 15982.3 16052.3 16271.0 1820.9 1839.3 1871.4 1876.4 1891.8 111545.9 115766.0 116482.8 117402.0 117751.0 10528.4 10746.4 10805.1 10960.6 11096.8 13622.7 13627.9 13649.0 13652.2 13658.2 7984.6 8262.5 8267.9 8412.5 8419.7 8220.0 8225.9 8256.3 8386.9 8419.9 6592.7 6703.0 6705.7 6706.8 6844.5 1647.7 1666.0 1677.7 1733.6 1794.7 892.4 923.5 930.1 1026.3 1033.7 21793.8 22543.8 22609.5 22639.4 22649.1 946.1 965.8 967.4 969.1 1003.6 11086.9 11817.4 11958.3 12022.2 12038.2 521688.0 577716.1 580054.0 603380.0 604758.0 79383.0 81482.6 111724.4 114869.0 118558.4 915.6 977.8 1007.3 1024.4 1025.4 15520.7 19167.7 19712.4 19822.2 19941.7 34736.7 54937.9 54939.7 55391.0 56268.2 80429.6 81812.6 83466.0 83530.6 85129.6 7617.4 7767.6 7963.1 8229.7 8243.5 11708.5 12358.3 12980.9 13189.3 13333.9 8813.2 10126.2 10260.9 10566.8 11403.5 9153.6 11167.9 11355.8 11415.9 11482.7 11041.2 11247.7 11252.7 11747.3 11836.4 7960.4 7985.5 8073.8 8149.5 8180.9 8905.8 10679.4 11417.8 11548.0 11627.8 15:58:16 INFO - PROCESS | 1658 | 15:58:16 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5576.76 Median:4877.05 stddev:3141.14 (64.4%) stddev-sans-first:3134.18 15:58:16 INFO - PROCESS | 1658 | Values: 1388.7 1500.2 1693.0 1844.6 1900.6 8296.9 8830.3 8838.4 8840.8 8842.3 6683.4 7742.5 8436.6 8587.9 8650.8 2345.5 2395.7 2396.5 2397.1 2398.2 2340.4 2362.7 2386.1 2387.3 2388.6 2064.8 2149.0 2331.2 2403.2 2406.9 2374.5 2403.7 2405.4 2406.2 2410.8 2348.6 2381.5 2383.3 2391.3 2395.1 8685.8 8709.5 8774.8 8785.4 8809.2 5182.7 5336.0 5342.1 5344.9 5347.6 2937.9 3389.0 3434.1 3540.9 3542.3 1077.3 1082.4 1085.5 1105.3 1106.5 9830.6 11374.2 11383.5 11384.5 11403.7 4571.1 4657.2 4746.5 4771.8 4790.6 9823.1 9830.4 9832.1 9834.2 9844.4 5337.8 5339.5 5341.5 5344.5 5347.1 4710.4 4856.8 4909.9 4910.2 4913.3 3722.9 3765.4 3796.7 3833.9 3854.2 1173.2 1178.5 1184.1 1187.3 1193.4 607.5 743.5 862.7 865.4 919.7 5203.8 5422.0 5654.6 5727.8 5751.5 465.2 469.2 473.7 485.4 610.7 3270.7 3603.9 3800.1 3866.9 3892.5 9593.6 10621.2 10636.5 10637.9 10643.4 10218.4 10579.3 10579.4 10582.6 10594.5 7965.0 8171.8 8173.1 8175.6 8179.8 4825.0 5470.0 5559.5 5584.0 5678.3 8844.3 9647.5 9713.8 9889.6 10008.7 10024.4 10037.1 10037.6 10105.3 10171.9 5950.9 6878.8 6893.5 6967.6 6970.6 7965.4 8170.8 8273.6 8332.8 8333.9 7318.3 7490.3 7502.5 7525.7 7547.8 7349.3 7385.4 7394.9 7456.9 7460.4 6779.8 6867.4 6923.4 6948.2 6955.7 4766.8 4815.7 4849.8 4856.2 4977.0 4845.9 4897.3 4905.0 4934.7 4950.6 15:58:16 INFO - PROCESS | 1658 | 15:58:16 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4259.39 Median:4610.75 stddev:1284.30 (27.9%) stddev-sans-first:1253.02 15:58:16 INFO - PROCESS | 1658 | Values: 298.1 302.2 307.9 313.7 316.5 5159.2 5225.3 5337.6 5371.4 5487.5 4575.6 4979.7 4989.2 5093.6 5166.9 4092.5 4116.2 4135.4 4154.8 4159.0 3472.9 3974.0 4118.1 4137.9 4160.8 3298.0 4089.9 4100.7 4127.1 4127.6 3489.2 4069.4 4083.3 4084.0 4090.9 3576.6 3585.7 3590.2 3603.2 3635.9 4885.8 5012.9 5084.5 5282.6 5373.8 4612.3 4617.8 4646.6 4667.0 4685.8 5308.8 5575.9 5662.7 5676.3 5707.5 3266.5 3697.8 3946.7 3968.9 4014.8 4973.7 5787.4 5907.4 5959.4 5973.7 3346.5 4012.3 4045.0 4075.9 4109.7 3235.0 3918.3 4097.7 4101.5 4125.7 4663.8 4683.6 4730.5 4731.1 4740.8 5686.3 5737.6 5766.9 5768.7 5772.0 1290.7 1324.7 1381.6 1387.5 1403.0 785.0 843.8 844.5 863.4 867.5 3990.1 4263.0 4406.6 4481.6 4503.5 3996.6 4092.6 4670.6 4814.7 4873.9 3819.5 3878.6 4186.6 4328.0 4405.7 2165.3 2222.2 2380.4 2503.0 2548.0 4400.2 4482.7 4563.7 4949.3 5041.7 4431.9 4777.4 4871.8 4923.6 4938.6 4307.8 4470.4 4871.2 4931.5 5031.4 4288.4 4609.2 4850.9 4854.3 4880.5 4074.5 4210.5 4580.2 4661.3 4672.6 4132.0 4429.8 4650.0 4662.9 4720.8 3164.8 3425.9 3495.8 3499.6 3511.5 4349.2 4576.0 4735.0 4763.9 4945.5 4483.7 5078.6 5099.6 5292.0 5571.8 4980.8 5658.9 5667.2 5684.5 5707.9 4183.0 4970.8 5114.8 5415.9 5574.8 4514.6 5302.9 5362.3 5477.9 5699.3 5561.4 5569.9 5594.3 5605.7 5613.2 15:58:16 INFO - PROCESS | 1658 | 15:58:16 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:25747.45 Median:4298.75 stddev:43139.92 (1003.5%) stddev-sans-first:43219.83 15:58:16 INFO - PROCESS | 1658 | Values: 665.3 773.5 776.3 806.8 816.3 15036.5 15414.9 15834.9 15865.8 16474.8 14721.6 15040.0 15041.5 15051.8 15063.4 677.5 848.4 868.7 870.0 871.4 698.6 849.0 863.1 867.6 875.2 888.3 889.1 890.1 892.0 892.8 716.2 883.9 885.5 885.5 887.9 663.1 664.2 669.4 677.8 678.6 14873.6 14914.9 14942.9 14943.9 14944.8 13774.6 13987.7 14146.0 14199.4 14478.4 12628.7 12711.4 12722.2 12763.6 12980.4 4541.7 4645.8 4656.7 4669.2 4698.1 121436.4 124537.0 124541.4 124553.4 125025.0 10991.2 11098.7 11139.6 11236.2 11501.3 22574.8 22888.2 22925.7 22956.2 22966.8 7678.1 7810.3 7860.5 7902.1 8044.9 4189.0 4328.0 4341.8 4347.7 4549.3 3487.2 3600.6 3601.4 3604.4 3610.9 650.2 661.5 666.9 676.2 677.6 605.6 609.8 610.7 614.9 622.4 8733.1 8779.6 8872.1 8893.8 9120.2 584.8 590.4 602.9 621.9 624.4 4087.6 4251.3 4269.5 4341.3 4379.9 120126.4 163499.0 167479.0 168294.0 172566.0 136073.0 139684.0 140399.0 141722.0 145350.0 116722.0 120273.0 123047.0 125361.4 127285.0 8158.2 8570.1 8625.0 8647.1 8922.9 39736.6 54480.1 58780.0 60277.7 61272.0 84038.6 94803.0 95782.0 97930.0 98390.6 10388.6 11099.0 11349.1 11440.5 11496.0 10995.0 12014.5 12298.0 12490.3 12686.0 10320.7 11653.7 11989.7 12071.3 12489.6 9774.5 11624.7 11947.1 11960.4 12452.0 11433.6 11585.3 11684.1 11721.4 11754.8 7562.3 9856.0 9968.4 10093.3 10267.3 8282.6 8508.9 8787.0 9798.4 9964.9 15:58:16 INFO - PROCESS | 1658 | -------- Summary: end -------- 15:58:16 INFO - PROCESS | 1658 | 15:58:17 INFO - TEST-INFO | 1658: exit 0 15:58:17 INFO - TEST-OK | dromaeo_css | took 1117627ms 15:58:17 INFO - TEST-START | kraken 15:58:17 INFO - operating with platform_type : mac_ 15:58:17 INFO - Initialising browser for kraken test... 15:58:17 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpm4FTST/profile http://localhost:49266/getInfo.html) 15:58:19 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 15:58:19 INFO - PROCESS | 1663 | colorDepth:24 15:58:19 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 15:58:19 INFO - PROCESS | 1663 | __metrics 15:58:19 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:58:19 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:58:20 INFO - TEST-INFO | 1663: exit 0 15:58:20 INFO - Browser initialized. 15:58:20 INFO - Running cycle 1/1 for kraken test... 15:58:20 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpm4FTST/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:58:32 INFO - PROCESS | 1664 | RSS: Main: 232349696 15:58:32 INFO - PROCESS | 1664 | 15:58:34 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 15:58:35 INFO - PROCESS | 1664 | RSS: Main: 291049472 15:58:35 INFO - PROCESS | 1664 | 15:58:38 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 15:58:39 INFO - PROCESS | 1664 | RSS: Main: 287379456 15:58:39 INFO - PROCESS | 1664 | 15:58:42 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 15:58:43 INFO - PROCESS | 1664 | RSS: Main: 377516032 15:58:43 INFO - PROCESS | 1664 | 15:58:45 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 15:58:45 INFO - PROCESS | 1664 | RSS: Main: 278937600 15:58:45 INFO - PROCESS | 1664 | 15:58:47 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:58:48 INFO - PROCESS | 1664 | RSS: Main: 283508736 15:58:48 INFO - PROCESS | 1664 | 15:58:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 15:58:51 INFO - PROCESS | 1664 | RSS: Main: 322334720 15:58:51 INFO - PROCESS | 1664 | 15:58:54 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 15:58:55 INFO - PROCESS | 1664 | RSS: Main: 321011712 15:58:55 INFO - PROCESS | 1664 | 15:58:58 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 15:58:58 INFO - PROCESS | 1664 | RSS: Main: 322797568 15:58:58 INFO - PROCESS | 1664 | 15:59:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:59:00 INFO - PROCESS | 1664 | RSS: Main: 297754624 15:59:00 INFO - PROCESS | 1664 | 15:59:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:59:03 INFO - PROCESS | 1664 | RSS: Main: 303341568 15:59:03 INFO - PROCESS | 1664 | 15:59:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:59:06 INFO - PROCESS | 1664 | RSS: Main: 305152000 15:59:06 INFO - PROCESS | 1664 | 15:59:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:59:10 INFO - PROCESS | 1664 | RSS: Main: 318869504 15:59:10 INFO - PROCESS | 1664 | 15:59:13 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:59:13 INFO - PROCESS | 1664 | RSS: Main: 315015168 15:59:13 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 15:59:15 INFO - PROCESS | 1664 | RSS: Main: 358748160 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | __start_tp_report 15:59:15 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 15:59:15 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 15:59:15 INFO - PROCESS | 1664 | |i|pagename|runs| 15:59:15 INFO - PROCESS | 1664 | |0;ai-astar;114;112;117;113;113;112;111;113;113;125 15:59:15 INFO - PROCESS | 1664 | |1;audio-beat-detection;148;111;132;192;139;135;148;140;176;108 15:59:15 INFO - PROCESS | 1664 | |2;audio-dft;185;181;185;183;183;183;182;180;183;181 15:59:15 INFO - PROCESS | 1664 | |3;audio-fft;77;75;77;155;58;59;58;62;143;57 15:59:15 INFO - PROCESS | 1664 | |4;audio-oscillator;80;83;133;70;70;80;122;99;99;118 15:59:15 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;86;83;86;84;86;89;130;84;85;84 15:59:15 INFO - PROCESS | 1664 | |6;imaging-darkroom;97;102;115;96;96;96;97;105;96;96 15:59:15 INFO - PROCESS | 1664 | |7;imaging-desaturate;87;86;84;84;86;86;86;83;85;85 15:59:15 INFO - PROCESS | 1664 | |8;json-parse-financial;54;55;55;54;54;56;54;54;54;54 15:59:15 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;67;70;70;81;72;71;72;69;70;70 15:59:15 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;87;85;89;85;87;86;85;89;91;87 15:59:15 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;141;142;156;133;148;164;160;152;178;157 15:59:15 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;172;174;175;173;196;247;174;177;174;176 15:59:15 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;69;67;68;68;69;67;68;67;66;65 15:59:15 INFO - PROCESS | 1664 | __end_tp_report 15:59:15 INFO - PROCESS | 1664 | __start_cc_report 15:59:15 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2061 15:59:15 INFO - PROCESS | 1664 | __end_cc_report 15:59:15 INFO - PROCESS | 1664 | __startTimestamp1473202755683__endTimestamp 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | ------- Summary: start ------- 15:59:15 INFO - PROCESS | 1664 | Number of tests: 14 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:114.30 Median:113.00 stddev:4.08 (3.6%) stddev-sans-first:4.33 15:59:15 INFO - PROCESS | 1664 | Values: 114.0 112.0 117.0 113.0 113.0 112.0 111.0 113.0 113.0 125.0 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:144.00 stddev:25.82 (17.9%) stddev-sans-first:27.32 15:59:15 INFO - PROCESS | 1664 | Values: 148.0 111.0 132.0 192.0 139.0 135.0 148.0 140.0 176.0 108.0 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.00 stddev:1.65 (0.9%) stddev-sans-first:1.50 15:59:15 INFO - PROCESS | 1664 | Values: 185.0 181.0 185.0 183.0 183.0 183.0 182.0 180.0 183.0 181.0 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:82.10 Median:60.50 stddev:36.29 (60.0%) stddev-sans-first:38.45 15:59:15 INFO - PROCESS | 1664 | Values: 77.0 75.0 77.0 155.0 58.0 59.0 58.0 62.0 143.0 57.0 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:80.00 stddev:22.55 (28.2%) stddev-sans-first:23.22 15:59:15 INFO - PROCESS | 1664 | Values: 80.0 83.0 133.0 70.0 70.0 80.0 122.0 99.0 99.0 118.0 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:85.50 stddev:14.26 (16.7%) stddev-sans-first:15.06 15:59:15 INFO - PROCESS | 1664 | Values: 86.0 83.0 86.0 84.0 86.0 89.0 130.0 84.0 85.0 84.0 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:99.60 Median:96.00 stddev:6.24 (6.5%) stddev-sans-first:6.55 15:59:15 INFO - PROCESS | 1664 | Values: 97.0 102.0 115.0 96.0 96.0 96.0 97.0 105.0 96.0 96.0 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:86.00 stddev:1.23 (1.4%) stddev-sans-first:1.12 15:59:15 INFO - PROCESS | 1664 | Values: 87.0 86.0 84.0 84.0 86.0 86.0 86.0 83.0 85.0 85.0 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:0.70 (1.3%) stddev-sans-first:0.73 15:59:15 INFO - PROCESS | 1664 | Values: 54.0 55.0 55.0 54.0 54.0 56.0 54.0 54.0 54.0 54.0 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:70.50 stddev:3.74 (5.3%) stddev-sans-first:3.64 15:59:15 INFO - PROCESS | 1664 | Values: 67.0 70.0 70.0 81.0 72.0 71.0 72.0 69.0 70.0 70.0 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:87.00 stddev:2.02 (2.3%) stddev-sans-first:2.15 15:59:15 INFO - PROCESS | 1664 | Values: 87.0 85.0 89.0 85.0 87.0 86.0 85.0 89.0 91.0 87.0 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:153.10 Median:156.50 stddev:12.96 (8.3%) stddev-sans-first:12.98 15:59:15 INFO - PROCESS | 1664 | Values: 141.0 142.0 156.0 133.0 148.0 164.0 160.0 152.0 178.0 157.0 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.80 Median:175.50 stddev:23.27 (13.3%) stddev-sans-first:24.28 15:59:15 INFO - PROCESS | 1664 | Values: 172.0 174.0 175.0 173.0 196.0 247.0 174.0 177.0 174.0 176.0 15:59:15 INFO - PROCESS | 1664 | 15:59:15 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.26 (1.9%) stddev-sans-first:1.20 15:59:15 INFO - PROCESS | 1664 | Values: 69.0 67.0 68.0 68.0 69.0 67.0 68.0 67.0 66.0 65.0 15:59:15 INFO - PROCESS | 1664 | -------- Summary: end -------- 15:59:15 INFO - PROCESS | 1664 | 15:59:16 INFO - TEST-INFO | 1664: exit 0 15:59:16 INFO - TEST-OK | kraken | took 59355ms 15:59:16 INFO - SUITE-END | took 1176s 15:59:16 INFO - Completed test suite (00:19:37) 15:59:16 INFO - javascript benchmark 15:59:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.4532802087656, 3322.1362445764103, 3352.296017836254, 3361.1765117546206, 3401.3877502049636, 5547.611667183299, 5652.293463317085, 5669.546436285098, 5694.914576281359, 5770.971145144274, 5698.914516282259, 5726.455986681269, 5730.255066841315, 5737.082066869301, 5751.367349591542, 5177.042247184274, 5224.869378265555, 5251.975864706384, 5257.106291930381, 5293.497117773824, 5269.657472264291, 5292.0, 5293.788248470076, 5299.894002119955, 5304.230886521452, 5188.818391356303, 5190.143626301656, 5216.8174113906025, 5220.79116835328, 5272.31459910213, 5302.072137375963, 5306.6550674206055, 5326.0, 5332.493413125747, 5359.624826262163, 5102.311187989648, 5117.513836185556, 5169.663971841818, 5220.738963051832, 5257.211418287249, 5625.268715067056, 5693.28833895763, 5719.28508936383, 5748.856278593027, 5765.4811067004175, 6210.0, 6262.5929314594405, 6262.78080267193, 6271.435570798604, 6284.842878928018, 6111.2804801863185, 6215.844603884894, 6274.123592375962, 6274.968625156845, 6325.810225693236, 5795.391483894215, 5822.708864556797, 5825.097109947965, 5838.620489668158, 5845.649261044351, 6258.718357673915, 6298.748050077946, 6314.494840412848, 6321.462675672619, 6325.841853953688, 5867.589268751147, 6100.054491553817, 6106.0, 6151.446369826647, 6164.044573091178, 6244.75020999164, 6259.561830671901, 6291.1192433059405, 6293.96853015732, 6300.779472718433, 5017.473165317662, 5947.434993675594, 6008.218931538872, 6027.969860150759, 6036.5170786336985, 6092.964196086676, 6101.877962440726, 6112.0, 6113.020255720364, 6138.294096178996, 4914.140025495524, 4919.114559379274, 4919.827806026772, 4926.556609905054, 4932.1861892788, 4416.734995900256, 4457.375967364572, 4459.888502787391, 4463.24124898768, 4466.3747075409465, 5642.351129620146, 5680.573956953245, 5693.686847223443, 5704.914426283609, 5705.343885453143, 5278.788848446096, 6178.980468222783, 6180.7527698891545, 6203.379662033851, 6264.310925798159, 5683.204351390808, 5693.231413759172, 5695.40198279183, 5695.829125126253, 5729.054705973551, 5385.7576409061685, 5396.244525766396, 5407.188921661782, 5416.295881535375, 5422.267993820863, 6260.154879091356, 6262.6868656566985, 6263.498920086473, 6308.621482711052, 6322.968385158137, 6296.087067375296, 6307.432331090224, 6310.968445157745, 6323.177986861679, 6334.8733025338315, 6326.6520341381665, 6347.5239357047485, 6354.491640668828, 6364.872702546016, 6366.681665916778, 5975.970120149545, 6026.517878569616, 6094.924532418307, 6126.050462178369, 6192.411720886508, 6257.78097766576, 6303.558750887394, 6315.5263355247625, 6322.810315690536, 6324.6521441321165, 6151.784687535915, 6156.569040167145, 6167.383261673796, 6169.876602468015, 6182.917989351756, 5895.823125306248, 5979.760809567569, 6033.456988871023, 6146.0473626587955, 6149.2620885494025, 5858.121281807763, 5954.642721436728, 5978.252718410199, 5991.610545314541, 5993.730282137227, 5910.70446477683, 6098.298695650057, 6116.17431646722, 6125.938740612537, 6150.292716337678, 5719.5710321726865, 5893.0, 5948.643081415129, 5951.791687290924, 5959.28488581373], "value": 5695.148244442231, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.87793356826876, 469.038471134175, 525.915853463446, 527.986800329992, 552.9806456774008, 30005.0, 36760.81619591902, 41007.0, 41032.58967410325, 41202.381964270484, 42534.0, 42915.14169716604, 42943.57056429435, 43022.35466468005, 43026.7848660757, 8075.878861817077, 9674.854877176847, 9871.605135794596, 9875.0, 9877.407355558653, 8206.548639824807, 8960.462372257653, 11740.882591174064, 11884.0, 11922.225055371417, 11906.880931190708, 11937.34344611046, 11938.283702977806, 11957.42915273216, 11962.043036557097, 12009.339486328248, 12080.939595302056, 12090.214136081215, 12095.939520302341, 12106.455209515592, 11861.229020113751, 11918.0, 11928.04575633956, 11940.104492163122, 11944.28334299936, 39982.20035599272, 40149.59850401488, 40223.59776402228, 40248.396274055915, 40290.791276261756, 3262.5106234064842, 3307.570015897942, 3310.2717402171484, 3328.683775041367, 3340.2150494633743, 30220.395592088254, 30264.8486757567, 30726.84636576825, 31092.689073109206, 31388.529172062437, 3038.255478861961, 3062.3569050499423, 3086.42901063304, 3112.5798017267607, 3116.096332063699, 468985.0, 471173.0, 472538.0, 473317.0, 477142.0, 60401.69799150976, 60822.08766868412, 60828.0, 60832.57095150259, 60961.695191524646, 33961.66038339585, 34753.65246347504, 63960.603939605666, 64087.35912640907, 64135.35864641294, 15985.200739963188, 16544.50366489007, 16552.0, 16580.6683866322, 16605.50183494473, 11475.483603237873, 12646.620601381974, 12794.104412691022, 12804.231746095264, 12809.0, 16184.676306473804, 16746.832531674772, 16761.91619041897, 16785.580360491087, 18395.63208735818, 7617.5048621839405, 7800.687972481151, 7801.336886364609, 7839.33365663925, 7922.603869806487, 856.7643897928119, 860.2086080805674, 921.0237148622348, 1016.207358260558, 1039.9844002339971, 29645.703542964726, 29669.0, 29696.82856619105, 29761.0, 30306.393872122433, 1005.9295849290479, 1064.4730858225073, 1091.1761619976867, 1106.468894930438, 1160.976780464403, 13592.796108058386, 14265.57344265586, 14578.979471436898, 15061.322240499203, 15367.0, 687983.0, 695810.0, 696868.0, 697960.0, 699205.0, 49409.50590494121, 55790.0, 56882.43117568854, 56932.14601780977, 57248.71375643178, 1352.6212660455087, 1371.9862801372058, 1393.7142885708406, 1393.9512117075853, 1394.0729414939156, 30220.24449388695, 31081.0, 31129.0, 31804.840975794974, 31965.520517191795, 48705.5129448701, 49493.752531238555, 49542.0, 49546.25680614721, 49586.0, 616713.0, 621938.0, 622972.0, 623579.0, 624430.1905280951, 22563.43591410228, 25935.481290374464, 25935.740642593333, 26200.34499137537, 26206.34484137912, 46361.536384637075, 46817.06365872732, 47626.0, 48122.79693007563, 48283.5171648279, 36296.27407451783, 36619.0, 38822.80588597039, 39181.80409097936, 40057.198856022136, 38209.853704388916, 38696.41955370615, 39270.0, 40212.0, 40375.0, 37881.62118378781, 38642.42036369513, 38708.0, 38728.0, 38766.6123338763, 30693.0, 31113.755449781755, 34101.0, 34286.0, 34393.484097739056, 22715.772842271366, 24515.01939922383, 25216.74783252144, 25773.613395798704, 25826.741732583185], "value": 20172.486317332176, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.55879371388903, 371.75092687899104, 374.0965568152909, 384.4425582904788, 404.89675132841137, 14935.551933441988, 15513.922430387847, 19494.512637184078, 20185.798142018575, 20197.99010049499, 19845.900770496126, 20156.0, 20500.69248961267, 20503.897480512613, 20995.47511312218, 5251.159814429692, 5855.355910849792, 6097.847553811146, 6114.69426528674, 6119.3574674659185, 4149.647279981205, 4423.248047831877, 5971.731272092767, 6145.661988590626, 6419.5506314558, 6634.038064480647, 6709.9329006709795, 6724.226713927912, 6732.562383445061, 6741.629210393451, 5509.449055094478, 6383.553151279412, 6651.23510796255, 6651.334866513358, 6653.634050127241, 5512.283403157604, 5724.341700704431, 5747.339056008571, 5752.2234498342605, 5763.366029736726, 25170.993160273567, 25276.873615631805, 25445.236642900556, 25576.48847023049, 25984.220473385827, 2261.434641339665, 2397.436602398435, 2469.0370755405406, 2524.5708229601014, 2819.323362393011, 12111.0, 15879.36482540697, 15982.280797364147, 16052.27764750589, 16271.023738575605, 1820.8634352423621, 1839.3378383781828, 1871.4011516314786, 1876.4370688793308, 1891.782445018813, 111545.88454115436, 115766.0, 116482.8351716489, 117402.0, 117751.0, 10528.420936848393, 10746.408947507805, 10805.056154503549, 10960.616378426717, 11096.833547496793, 13622.659433514242, 13627.931860340834, 13649.044566880222, 13652.24912629815, 13658.180509169482, 7984.640691168911, 8262.462939908886, 8267.875981860278, 8412.537310447984, 8419.663213471515, 8220.0, 8225.906075036371, 8256.25693687559, 8386.87419688705, 8419.915800841913, 6592.670366481752, 6703.0, 6705.6982435790505, 6706.83232919181, 6844.452443804484, 1647.736362182045, 1665.966680666404, 1677.7315629499221, 1733.566608347913, 1794.7128459446426, 892.4154678685471, 923.5013092929743, 930.093159169804, 1026.2816028779885, 1033.715728174758, 21793.782062179493, 22543.774562254497, 22609.547809044056, 22639.434014149778, 22649.094036238366, 946.1437399153776, 965.8164948659733, 967.4147140979746, 969.0938972061145, 1003.6035765872585, 11086.944565277123, 11817.409129543488, 11958.342291173894, 12022.218555793845, 12038.217515861441, 521688.0, 577716.1114194402, 580054.0, 603380.0, 604758.0, 79383.0, 81482.59258703669, 111724.4413777926, 114869.0, 118558.4072079634, 915.56053094515, 977.750673578233, 1007.2697294461603, 1024.4467987286782, 1025.410389026313, 15520.689586208438, 19167.656373713493, 19712.40862774119, 19822.207111715947, 19941.700874487186, 34736.652633474354, 54937.901241974134, 54939.72530137324, 55391.0, 56268.15597766119, 80429.59785201037, 81812.59093704731, 83466.0, 83530.58234709031, 85129.57435212785, 7617.352525035212, 7767.6406628838595, 7963.12405635368, 8229.670813167646, 8243.546604936786, 11708.531658733555, 12358.258504489759, 12980.935095324463, 13189.340532973505, 13333.866661333263, 8813.206811387006, 10126.18990480745, 10260.897391026292, 10566.788664226826, 11403.543858245908, 9153.63385464574, 11167.944160279147, 11355.772884542097, 11415.885841141482, 11482.655520334403, 11041.171912106785, 11247.718807029889, 11252.718682033015, 11747.295162289947, 11836.408179591159, 7960.363170946194, 7985.480943738638, 8073.798155046171, 8149.511029338259, 8180.877286840821, 8905.777355565904, 10679.412632304988, 11417.828732569184, 11548.018418434527, 11627.767444651228], "value": 9704.953942059386, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.6736616895028, 1500.1824005916772, 1692.9915350423246, 1844.6218525202335, 1900.5935607650342, 8296.875546866802, 8830.337724670644, 8838.381313308055, 8840.823183536326, 8842.292616590674, 6683.431908287789, 7742.5354478731315, 8436.578171091436, 8587.871181932276, 8650.783730406743, 2345.495718420539, 2395.688560487143, 2396.4727759892885, 2397.1490121007055, 2398.2205783120467, 2340.3564019894493, 2362.6574146748712, 2386.1171366594385, 2387.259949415678, 2388.6178211486167, 2064.7935206479383, 2148.967765483519, 2331.230693871027, 2403.170906037423, 2406.927792166229, 2374.5013547155118, 2403.735589085199, 2405.3986503374244, 2406.230006397936, 2410.8312418130563, 2348.5537747827857, 2381.499885024147, 2383.2731017039796, 2391.294568102414, 2395.1497218487534, 8685.826283474295, 8709.477431354138, 8774.780630484225, 8785.42894711848, 8809.207171354545, 5182.714950677711, 5336.039512887679, 5342.118550439173, 5344.893102137936, 5347.6256662033675, 2937.9265518362, 3388.96611033889, 3434.1414646338417, 3540.91147721309, 3542.2738338640515, 1077.2513103393067, 1082.355998181081, 1085.45184681736, 1105.3312745788842, 1106.468894930438, 9830.606775728962, 11374.203805733601, 11383.487743051666, 11384.544618215345, 11403.714907127305, 4571.131485017836, 4657.184992626277, 4746.5490778376, 4771.785269662873, 4790.592799612003, 9823.115919567274, 9830.361026533252, 9832.065953734384, 9834.21326293909, 9844.409335439897, 5337.8131765388025, 5339.519443250048, 5341.519263266325, 5344.545713614309, 5347.144456886879, 4710.4347478302825, 4856.8300109495995, 4909.877253068702, 4910.165271903785, 4913.263010548446, 3722.8510859565313, 3765.4163604641326, 3796.7342286040266, 3833.884983450501, 3854.1520865409566, 1173.2256710570982, 1178.4873579992732, 1184.1054313099144, 1187.3113594115393, 1193.4331192683405, 607.5170239659393, 743.4684200796456, 862.6851199312206, 865.3682811547606, 919.6827094652334, 5203.7918483261, 5421.978695837124, 5654.604177707603, 5727.828165155052, 5751.482366587027, 465.19985624725416, 469.2374890802446, 473.67789902865695, 485.38841060263616, 610.664134725899, 3270.721988630993, 3603.8558457661925, 3800.068983099103, 3866.903327416837, 3892.4939757831303, 9593.568289426992, 10621.150307975344, 10636.521356538975, 10637.893621063846, 10643.41461219625, 10218.386896786216, 10579.259451838452, 10579.418132002813, 10582.57669693219, 10594.523246453928, 7965.044194696674, 8171.83446386708, 8173.141820108803, 8175.63209655567, 8179.754607361908, 4825.010872771144, 5470.0, 5559.527440167631, 5584.022796010763, 5678.290213723285, 8844.292456603327, 9647.517624118906, 9713.757156070873, 9889.554970026222, 10008.749781255527, 10024.448655324028, 10037.096661300518, 10037.648682296085, 10105.292629515863, 10171.898281017095, 5950.9404905952115, 6878.828029299308, 6893.517453778188, 6967.581945083311, 6970.581765094111, 7965.442419030613, 8170.836583268182, 8273.627686754231, 8332.750017499484, 8333.916660833314, 7318.3047610476915, 7490.250974902466, 7502.474826762074, 7525.698972041276, 7547.773566793005, 7349.265073492608, 7385.409167266498, 7394.852102958018, 7456.925430745623, 7460.4031677466755, 6779.762708305186, 6867.416269617139, 6923.411510021705, 6948.235694073749, 6955.686994085379, 4766.761661916959, 4815.7351345676325, 4849.80600775965, 4856.247281671346, 4976.975115124401, 4845.927311090407, 4897.2654101885, 4904.975475122602, 4934.7285899275885, 4950.579200767831], "value": 4418.464358653238, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.12797567116183, 302.2232861545828, 307.88300445830583, 313.69884910485933, 316.4825510290677, 5159.226116082585, 5225.320708307915, 5337.626366154371, 5371.435999220084, 5487.451254874511, 4575.611073058785, 4979.651424400294, 4989.226669866168, 5093.567046801017, 5166.9224961625605, 4092.508898932125, 4116.176764647083, 4135.379693046037, 4154.750714957097, 4159.0, 3472.909110891309, 3974.0, 4118.135191609766, 4137.9448240698575, 4160.791960401983, 3297.96702032979, 4089.8773036808943, 4100.733452325604, 4127.111022284398, 4127.637879499758, 3489.214926641498, 4069.4302797608357, 4083.3262511685534, 4084.0, 4090.9386359204636, 3576.5708115026364, 3585.7230658531757, 3590.2281009583066, 3603.1892824114366, 3635.8909232723063, 4885.778555361159, 5012.8997420051755, 5084.491550844923, 5282.550983166436, 5373.7581808818695, 4612.331211974279, 4617.8152873885, 4646.582792248363, 4666.9766651166865, 4685.750839596978, 5308.840734777963, 5575.916361254584, 5662.66024038559, 5676.262085928803, 5707.5148612368, 3266.5263536787033, 3697.796621185834, 3946.7039972002217, 3968.8568173186445, 4014.7992600369867, 4973.681974276819, 5787.363390027093, 5907.409259074101, 5959.374265702126, 5973.685789126385, 3346.464565669481, 4012.3179059559943, 4044.9797751011056, 4075.898102547401, 4109.691773116969, 3235.0456615298544, 3918.294706952719, 4097.672186225095, 4101.487314085739, 4125.689613816273, 4663.836765713184, 4683.578477937002, 4730.47964723887, 4731.077439899255, 4740.762961851894, 5686.289213848269, 5737.569682273846, 5766.913496297559, 5768.740406681625, 5772.0, 1290.7418516296777, 1324.7151862349642, 1381.6269607205998, 1387.5292190278337, 1402.9929850350684, 784.9725259615886, 843.8354520868372, 844.4679851693394, 863.3869952333913, 867.5468590111537, 3990.0822810753107, 4262.978685106555, 4406.603405693503, 4481.574250446202, 4503.54964503547, 3996.6003399659803, 4092.631663150331, 4670.602998745145, 4814.735189564538, 4873.926891096637, 3819.5363990226147, 3878.631530004645, 4186.602272784081, 4327.978360108179, 4405.735655860659, 2165.2677857713766, 2222.244436891465, 2380.416797884495, 2502.974970250274, 2548.0125502108012, 4400.23990403841, 4482.731036137842, 4563.732909729413, 4949.257611358325, 5041.722705251246, 4431.8670439886855, 4777.362711999804, 4871.80512779492, 4923.556879880756, 4938.592501137158, 4307.827686892552, 4470.418845550058, 4871.2206047032305, 4931.5315045070665, 5031.4213865405945, 4288.378185163197, 4609.1703493371515, 4850.927236091391, 4854.344663470386, 4880.536349046977, 4074.5314288855975, 4210.494740631144, 4580.167932826896, 4661.3452224459825, 4672.6495512837355, 4131.97934010328, 4429.759667293163, 4650.000249946278, 4662.860114196579, 4720.834770783006, 3164.7784655073924, 3425.9486107708904, 3495.807730574843, 3499.580050393969, 3511.4908338291316, 4349.173657005159, 4576.0, 4734.958309171977, 4763.928116173834, 4945.4807245238735, 4483.686141970031, 5078.568321692697, 5099.592032637454, 5292.0, 5571.771291483439, 4980.750962451935, 5658.9151162733415, 5667.234923285304, 5684.516816070515, 5707.944030354398, 4183.0, 4970.7514624267915, 5114.820981265638, 5415.864603384947, 5574.804881829116, 4514.600473852985, 5302.9469705304, 5362.302900622894, 5477.890442191054, 5699.287589051369, 5561.3882472927135, 5569.916451253315, 5594.265777608792, 5605.663660180402, 5613.24221230145], "value": 3859.1807175143485, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.268204974528, 773.5165521549036, 776.2935728487078, 806.7660378490239, 816.2531283875254, 15036.548903532885, 15414.922925385385, 15834.920825395886, 15865.84134158658, 16474.75287870683, 14721.631959201026, 15040.0, 15041.548753537412, 15051.849481505209, 15063.39746410145, 677.546044150418, 848.3594885861166, 868.7437206024238, 869.9695510657128, 871.3900269811157, 698.6297262450909, 849.0, 863.1196179896501, 867.6052396159732, 875.2429148786317, 888.3470649072926, 889.1375365895103, 890.1499068389672, 891.9598618062171, 892.78573142446, 716.1585137463512, 883.9127872716531, 885.4510203673699, 885.4775682347446, 887.9334049946248, 663.1478550063146, 664.2062735031639, 669.4477056428417, 677.793273051719, 678.5860625018735, 14873.628159295997, 14914.925425372912, 14942.850571494255, 14943.850561494355, 14944.775828362583, 13774.586762397144, 13987.720245595032, 14146.0, 14199.432022719078, 14478.42086316546, 12628.747425051448, 12711.364431778375, 12722.236665800081, 12763.617091487271, 12980.350982450935, 4541.682082254243, 4645.76771161444, 4656.743879086649, 4669.159551280768, 4698.130845793505, 121436.39281803534, 124537.0, 124541.37729311295, 124553.37723311325, 125025.0, 10991.230613857113, 11098.722531936766, 11139.554417823196, 11236.213465057532, 11501.309921404576, 22574.774252257597, 22888.198913037962, 22925.656115158286, 22956.19653312126, 22966.770332296463, 7678.117016543058, 7810.258025487569, 7860.528368297921, 7902.130765615778, 8044.879326810103, 4188.979055104705, 4328.0, 4341.804618792162, 4347.739135651871, 4549.340345649863, 3487.215376540309, 3600.621934696819, 3601.405768048295, 3604.3692353838496, 3610.945835812465, 650.2099948562519, 661.5435349608755, 666.9366410191024, 676.2290988273372, 677.5629718831326, 605.5549171359047, 609.7835268479714, 610.7099127914205, 614.8985417406167, 622.4180391334118, 8733.126687331216, 8779.604917778715, 8872.05572266583, 8893.822123557493, 9120.224780893699, 584.8420926349862, 590.3565114025732, 602.8523011862122, 621.88371872489, 624.3912185618985, 4087.550369459357, 4251.298535741568, 4269.5090064642345, 4341.3068903127805, 4379.934300985487, 120126.3993680026, 163499.0, 167479.0, 168294.0, 172566.0, 136073.0, 139684.0, 140399.0, 141722.0, 145350.0, 116722.0, 120273.0, 123047.0, 125361.37319313528, 127285.0, 8158.224968627971, 8570.100139485514, 8625.0, 8647.092055334351, 8922.910770892468, 39736.60921867721, 54480.09319673792, 58780.0, 60277.698611508415, 61272.0, 84038.57980710057, 94803.0, 95782.04217957731, 97930.0, 98390.60461070007, 10388.597539332093, 11099.0, 11349.14881383883, 11440.485178166837, 11496.0, 10995.0, 12014.459349329127, 12298.0, 12490.250584964931, 12686.048546358876, 10320.741981450525, 11653.6503904883, 11989.700257493283, 12071.275723456622, 12489.625311240678, 9774.462404567817, 11624.709382265173, 11947.10396720232, 11960.401979901144, 12452.0, 11433.59982400612, 11585.304881707125, 11684.125304843124, 11721.355325456841, 11754.823677644672, 7562.281583249583, 9856.0, 9968.352057116264, 10093.293469457067, 10267.281290309607, 8282.591959366802, 8508.914910850812, 8786.956065219632, 9798.363106398061, 9964.850527241952], "value": 7649.16312463447, "unit": "score"}], "name": "dromaeo_css", "value": 7251.839596012946, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 117.0, 113.0, 113.0, 112.0, 111.0, 113.0, 113.0, 125.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 111.0, 132.0, 192.0, 139.0, 135.0, 148.0, 140.0, 176.0, 108.0], "value": 142.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 181.0, 185.0, 183.0, 183.0, 183.0, 182.0, 180.0, 183.0, 181.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 77.0, 155.0, 58.0, 59.0, 58.0, 62.0, 143.0, 57.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 133.0, 70.0, 70.0, 80.0, 122.0, 99.0, 99.0, 118.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 84.0, 86.0, 89.0, 130.0, 84.0, 85.0, 84.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 102.0, 115.0, 96.0, 96.0, 96.0, 97.0, 105.0, 96.0, 96.0], "value": 99.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 84.0, 84.0, 86.0, 86.0, 86.0, 83.0, 85.0, 85.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 54.0, 54.0, 56.0, 54.0, 54.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 70.0, 81.0, 72.0, 71.0, 72.0, 69.0, 70.0, 70.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 89.0, 85.0, 87.0, 86.0, 85.0, 89.0, 91.0, 87.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 142.0, 156.0, 133.0, 148.0, 164.0, 160.0, 152.0, 178.0, 157.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 175.0, 173.0, 196.0, 247.0, 174.0, 177.0, 174.0, 176.0], "value": 183.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 68.0, 68.0, 69.0, 67.0, 68.0, 67.0, 66.0, 65.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1508.8, "alertThreshold": 2.0}]} 15:59:16 INFO - Return code: 0 15:59:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:59:16 INFO - # TBPL SUCCESS # 15:59:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:59:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:59:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:59:16 INFO - 2016-09-06 15:59:16.992 screenresolution[1668:10223] starting screenresolution argv=screenresolution get 15:59:16 INFO - 2016-09-06 15:59:16.994 screenresolution[1668:10223] Display 0: 1600x1200x32@60 15:59:17 INFO - 2016-09-06 15:59:17.000 screenresolution[1669:10226] starting screenresolution argv=screenresolution list 15:59:17 INFO - Available Modes on Display 0 15:59:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:59:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:59:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:59:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:59:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:59:17 INFO - Intel Iris: 15:59:17 INFO - Chipset Model: Intel Iris 15:59:17 INFO - Type: GPU 15:59:17 INFO - Bus: Built-In 15:59:17 INFO - VRAM (Dynamic, Max): 1536 MB 15:59:17 INFO - Vendor: Intel (0x8086) 15:59:17 INFO - Device ID: 0x0a2e 15:59:17 INFO - Revision ID: 0x0009 15:59:17 INFO - Displays: 15:59:17 INFO - Display: 15:59:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:59:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:59:17 INFO - Display Serial Number: 1600x1200 60 15:59:17 INFO - Main Display: Yes 15:59:17 INFO - Mirror: Off 15:59:17 INFO - Online: Yes 15:59:17 INFO - Rotation: Supported 15:59:17 INFO - Return code: 0 15:59:17 INFO - Running post-action listener: _resource_record_post_action 15:59:17 INFO - [mozharness: 2016-09-06 22:59:17.240154Z] Finished run-tests step (success) 15:59:17 INFO - Running post-run listener: _resource_record_post_run 15:59:17 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31370240; Write bytes: 701024256; Read time: 317; Write time: 1852 15:59:17 INFO - TinderboxPrint: CPU usage
53.6% 15:59:17 INFO - TinderboxPrint: I/O read bytes / time
31,370,240 / 317 15:59:17 INFO - TinderboxPrint: I/O write bytes / time
701,024,256 / 1,852 15:59:17 INFO - TinderboxPrint: CPU idle
2,247.0 (46.4%) 15:59:17 INFO - TinderboxPrint: CPU system
966.1 (19.9%) 15:59:17 INFO - TinderboxPrint: CPU user
1,632.6 (33.7%) 15:59:17 INFO - TinderboxPrint: Swap in / out
432,676,864 / 0 15:59:17 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 406635008; Write bytes: 408508416; Read time: 29884; Write time: 699 15:59:17 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29142016; Write bytes: 291201024; Read time: 292; Write time: 1148 15:59:17 INFO - Running post-run listener: _upload_blobber_files 15:59:17 INFO - Blob upload gear active. 15:59:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:59:17 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 15:59:17 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'] 15:59:17 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 15:59:18 INFO - (blobuploader) - INFO - Open directory for files ... 15:59:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:59:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:59:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:19 INFO - (blobuploader) - INFO - Done attempting. 15:59:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:59:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:59:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:20 INFO - (blobuploader) - INFO - Done attempting. 15:59:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:59:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:59:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:22 INFO - (blobuploader) - INFO - Done attempting. 15:59:22 INFO - (blobuploader) - INFO - Iteration through files over. 15:59:22 INFO - Return code: 0 15:59:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:59:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:59:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5f1b397da57005c182d4e23c4112d1e1f7d687777e3d6f915ab812823676bd9b261786f49bf8ea55e223de763bcd61b919d7ed6b55b0b41984c17c8495433da3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5dd89a632032783d039ad7d14807e9a7027d86ed2d502787aebff71312212559a5f84c230a8fc3ac3d5118352f05d17250bee254d3abbe59a41d578d5d2cdaba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/330e2c45bdc12973307cd0b04112ea2088a46c18164282ba417c0f2338ee2991140d38a716eea1a825906e9bdf8f275c45515851e720c586d2e7da61e3b5deb0"} 15:59:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:59:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:59:22 INFO - Contents: 15:59:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5f1b397da57005c182d4e23c4112d1e1f7d687777e3d6f915ab812823676bd9b261786f49bf8ea55e223de763bcd61b919d7ed6b55b0b41984c17c8495433da3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5dd89a632032783d039ad7d14807e9a7027d86ed2d502787aebff71312212559a5f84c230a8fc3ac3d5118352f05d17250bee254d3abbe59a41d578d5d2cdaba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/330e2c45bdc12973307cd0b04112ea2088a46c18164282ba417c0f2338ee2991140d38a716eea1a825906e9bdf8f275c45515851e720c586d2e7da61e3b5deb0"} 15:59:22 INFO - Running post-run listener: copy_logs_to_upload_dir 15:59:22 INFO - Copying logs to upload dir... 15:59:22 INFO - mkdir: /builds/slave/test/build/upload/logs 15:59:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.399906 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-06 15:59:22.981736) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 15:59:22.986468) ========= 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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5f1b397da57005c182d4e23c4112d1e1f7d687777e3d6f915ab812823676bd9b261786f49bf8ea55e223de763bcd61b919d7ed6b55b0b41984c17c8495433da3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5dd89a632032783d039ad7d14807e9a7027d86ed2d502787aebff71312212559a5f84c230a8fc3ac3d5118352f05d17250bee254d3abbe59a41d578d5d2cdaba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/330e2c45bdc12973307cd0b04112ea2088a46c18164282ba417c0f2338ee2991140d38a716eea1a825906e9bdf8f275c45515851e720c586d2e7da61e3b5deb0"} build_url:https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011006 build_url: 'https://queue.taskcluster.net/v1/task/VZ2gyVAlT1KttTSXXtBfcg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5f1b397da57005c182d4e23c4112d1e1f7d687777e3d6f915ab812823676bd9b261786f49bf8ea55e223de763bcd61b919d7ed6b55b0b41984c17c8495433da3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5dd89a632032783d039ad7d14807e9a7027d86ed2d502787aebff71312212559a5f84c230a8fc3ac3d5118352f05d17250bee254d3abbe59a41d578d5d2cdaba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/330e2c45bdc12973307cd0b04112ea2088a46c18164282ba417c0f2338ee2991140d38a716eea1a825906e9bdf8f275c45515851e720c586d2e7da61e3b5deb0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 15:59:23.022755) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 15:59:23.023099) ========= 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.TvZKSOnpI2/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.LnoCUeu4FI/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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.005095 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 15:59:23.071879) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 15:59:23.072207) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 15:59:23.072522) ========= ========= Total master_lag: 0.20 =========