builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0142 starttime: 1473069631.12 results: success (0) buildid: 20160905014120 builduid: 6e3494377bbe452ca976c37cdc73324d revision: 8515bb6df598578d036cd14422f5ab4e9d65980e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:31.123660) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:31.124141) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:31.124430) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:31.206191) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:31.206603) ========= 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.WByNccnXgl/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.akZ61rclUa/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-05 03:00:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.0M=0.001s 2016-09-05 03:00:31 (15.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.445436 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:31.677464) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:31.677771) ========= 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.WByNccnXgl/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.akZ61rclUa/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.020525 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:31.726284) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-05 03:00:31.726679) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 8515bb6df598578d036cd14422f5ab4e9d65980e --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 8515bb6df598578d036cd14422f5ab4e9d65980e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WByNccnXgl/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.akZ61rclUa/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-05 03:00:31,804 truncating revision to first 12 chars 2016-09-05 03:00:31,804 Setting DEBUG logging. 2016-09-05 03:00:31,804 attempt 1/10 2016-09-05 03:00:31,804 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/8515bb6df598?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-05 03:00:32,574 unpacking tar archive at: autoland-8515bb6df598/testing/mozharness/ program finished with exit code 0 elapsedTime=1.259413 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-05 03:00:33.012857) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:33.013407) ========= script_repo_revision: 8515bb6df598578d036cd14422f5ab4e9d65980e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:33.014127) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:33.014606) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:33.035203) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-05 03:00:33.035852) ========= /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.WByNccnXgl/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.akZ61rclUa/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 03:00:33 INFO - MultiFileLogger online at 20160905 03:00:33 in /builds/slave/test 03:00:33 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 03:00:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:00:33 INFO - {'append_to_log': False, 03:00:33 INFO - 'base_work_dir': '/builds/slave/test', 03:00:33 INFO - 'blob_upload_branch': 'Autoland', 03:00:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:00:33 INFO - 'branch': 'Autoland', 03:00:33 INFO - 'buildbot_json_path': 'buildprops.json', 03:00:33 INFO - 'config_files': ('talos/mac_config.py',), 03:00:33 INFO - 'default_actions': ('clobber', 03:00:33 INFO - 'read-buildbot-config', 03:00:33 INFO - 'download-and-extract', 03:00:33 INFO - 'populate-webroot', 03:00:33 INFO - 'create-virtualenv', 03:00:33 INFO - 'install', 03:00:33 INFO - 'run-tests'), 03:00:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:00:33 INFO - 'download_minidump_stackwalk': True, 03:00:33 INFO - 'download_symbols': 'ondemand', 03:00:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:00:33 INFO - 'tooltool.py': '/tools/tooltool.py', 03:00:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:00:33 INFO - '/tools/misc-python/virtualenv.py')}, 03:00:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:00:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:00:33 INFO - 'installer_path': 'installer.exe', 03:00:33 INFO - 'log_level': 'info', 03:00:33 INFO - 'log_name': 'talos', 03:00:33 INFO - 'log_to_console': True, 03:00:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:00:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:00:33 INFO - 'opt_config_files': (), 03:00:33 INFO - 'pip_index': False, 03:00:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:00:33 INFO - 'cmd': ('bash', 03:00:33 INFO - '-c', 03:00:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:00:33 INFO - 'enabled': True, 03:00:33 INFO - 'halt_on_failure': False, 03:00:33 INFO - 'name': 'check_screen_resolution'},), 03:00:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:00:33 INFO - 'cmd': ('bash', 03:00:33 INFO - '-c', 03:00:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:00:33 INFO - 'enabled': True, 03:00:33 INFO - 'halt_on_failure': False, 03:00:33 INFO - 'name': 'check_screen_resolution'},), 03:00:33 INFO - 'run_cmd_checks_enabled': True, 03:00:33 INFO - 'sps_profile': False, 03:00:33 INFO - 'sps_profile_interval': 0, 03:00:33 INFO - 'suite': 'dromaeojs', 03:00:33 INFO - 'system_bits': '32', 03:00:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:00:33 INFO - 'title': 't-yosemite-r7-0142', 03:00:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:00:33 INFO - 'use_talos_json': True, 03:00:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:00:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:00:33 INFO - 'work_dir': 'build'} 03:00:33 INFO - [mozharness: 2016-09-05 10:00:33.233586Z] Running clobber step. 03:00:33 INFO - Running pre-action listener: _resource_record_pre_action 03:00:33 INFO - Running main action method: clobber 03:00:33 INFO - rmtree: /builds/slave/test/build 03:00:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:00:34 INFO - Running post-action listener: _resource_record_post_action 03:00:34 INFO - [mozharness: 2016-09-05 10:00:34.633535Z] Finished clobber step (success) 03:00:34 INFO - [mozharness: 2016-09-05 10:00:34.633850Z] Running read-buildbot-config step. 03:00:34 INFO - Running pre-action listener: _resource_record_pre_action 03:00:34 INFO - Running main action method: read_buildbot_config 03:00:34 INFO - Using buildbot properties: 03:00:34 INFO - { 03:00:34 INFO - "project": "", 03:00:34 INFO - "product": "firefox", 03:00:34 INFO - "script_repo_revision": "production", 03:00:34 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 03:00:34 INFO - "repository": "", 03:00:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 03:00:34 INFO - "buildid": "20160905014120", 03:00:34 INFO - "pgo_build": "False", 03:00:34 INFO - "basedir": "/builds/slave/test", 03:00:34 INFO - "buildnumber": 151, 03:00:34 INFO - "slavename": "t-yosemite-r7-0142", 03:00:34 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 03:00:34 INFO - "platform": "yosemite_r7", 03:00:34 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 03:00:34 INFO - "branch": "autoland", 03:00:34 INFO - "repo_path": "integration/autoland", 03:00:34 INFO - "revision": "8515bb6df598578d036cd14422f5ab4e9d65980e", 03:00:34 INFO - "stage_platform": "macosx64", 03:00:34 INFO - "builduid": "6e3494377bbe452ca976c37cdc73324d", 03:00:34 INFO - "slavebuilddir": "test" 03:00:34 INFO - } 03:00:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 03:00:34 INFO - Running post-action listener: _resource_record_post_action 03:00:34 INFO - [mozharness: 2016-09-05 10:00:34.639841Z] Finished read-buildbot-config step (success) 03:00:34 INFO - [mozharness: 2016-09-05 10:00:34.640081Z] Running download-and-extract step. 03:00:34 INFO - Running pre-action listener: _resource_record_pre_action 03:00:34 INFO - Running main action method: download_and_extract 03:00:34 INFO - mkdir: /builds/slave/test/build/tests 03:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:34 INFO - trying https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 03:00:34 INFO - Downloading https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/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 03:00:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/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 03:00:36 INFO - Downloaded 1199 bytes. 03:00:36 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 03:00:36 INFO - Using the following test package requirements: 03:00:36 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:00:36 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:36 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 03:00:36 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:36 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 03:00:36 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:36 INFO - u'jsshell-mac.zip'], 03:00:36 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:36 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 03:00:36 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:00:36 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:36 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 03:00:36 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:36 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 03:00:36 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:36 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 03:00:36 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:36 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 03:00:36 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:00:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:00:36 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 03:00:43 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 03:00:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:00:43 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 03:00:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 03:00:51 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 03:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:52 INFO - trying https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:00:52 INFO - Downloading https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 03:00:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/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 03:00:55 INFO - Downloaded 111496107 bytes. 03:00:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:00:55 INFO - mkdir: /builds/slave/test/properties 03:00:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:00:55 INFO - Writing to file /builds/slave/test/properties/build_url 03:00:55 INFO - Contents: 03:00:55 INFO - build_url:https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:00:55 INFO - Running post-action listener: _resource_record_post_action 03:00:55 INFO - Running post-action listener: set_extra_try_arguments 03:00:55 INFO - [mozharness: 2016-09-05 10:00:55.932624Z] Finished download-and-extract step (success) 03:00:55 INFO - [mozharness: 2016-09-05 10:00:55.932746Z] Running populate-webroot step. 03:00:55 INFO - Running pre-action listener: _resource_record_pre_action 03:00:55 INFO - Running main action method: populate_webroot 03:00:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:00:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:00:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:00:55 INFO - '--tppagecycles', 03:00:55 INFO - '7'], 03:00:55 INFO - 'tests': ['tsvgm']}}, 03:00:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:00:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:00:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:00:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:00:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:00:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:00:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'talos_options': ['--disable-e10s'], 03:00:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'talos_options': ['--disable-e10s'], 03:00:55 INFO - 'tests': ['damp', 'tps']}, 03:00:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'tests': ['damp', 'tps']}, 03:00:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:00:55 INFO - 'tests': ['dromaeo_dom']}, 03:00:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:00:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:00:55 INFO - 'tests': ['basic_compositor_video']}, 03:00:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:00:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:00:55 INFO - 'tests': ['a11yr', 03:00:55 INFO - 'ts_paint', 03:00:55 INFO - 'tpaint', 03:00:55 INFO - 'sessionrestore', 03:00:55 INFO - 'sessionrestore_no_auto_restore', 03:00:55 INFO - 'tabpaint']}, 03:00:55 INFO - 'other-e10s': {'tests': ['a11yr', 03:00:55 INFO - 'ts_paint', 03:00:55 INFO - 'tpaint', 03:00:55 INFO - 'sessionrestore', 03:00:55 INFO - 'sessionrestore_no_auto_restore', 03:00:55 INFO - 'tabpaint']}, 03:00:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:00:55 INFO - 'ts_paint', 03:00:55 INFO - 'tpaint', 03:00:55 INFO - 'sessionrestore', 03:00:55 INFO - 'sessionrestore_no_auto_restore', 03:00:55 INFO - 'tabpaint']}, 03:00:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:00:55 INFO - 'ts_paint', 03:00:55 INFO - 'tpaint', 03:00:55 INFO - 'sessionrestore', 03:00:55 INFO - 'sessionrestore_no_auto_restore', 03:00:55 INFO - 'tabpaint']}, 03:00:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:00:55 INFO - 'tests': ['a11yr', 03:00:55 INFO - 'ts_paint', 03:00:55 INFO - 'tpaint', 03:00:55 INFO - 'sessionrestore', 03:00:55 INFO - 'sessionrestore_no_auto_restore', 03:00:55 INFO - 'tabpaint']}, 03:00:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:00:55 INFO - 'tests': ['a11yr', 03:00:55 INFO - 'ts_paint', 03:00:55 INFO - 'tpaint', 03:00:55 INFO - 'sessionrestore', 03:00:55 INFO - 'sessionrestore_no_auto_restore', 03:00:55 INFO - 'tabpaint']}, 03:00:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:00:55 INFO - 'tests': ['tsvgx', 03:00:55 INFO - 'tsvgr_opacity', 03:00:55 INFO - 'tart', 03:00:55 INFO - 'tscrollx', 03:00:55 INFO - 'cart']}, 03:00:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:00:55 INFO - 'tsvgr_opacity', 03:00:55 INFO - 'tart', 03:00:55 INFO - 'tscrollx', 03:00:55 INFO - 'cart']}, 03:00:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'talos_options': ['--disable-e10s'], 03:00:55 INFO - 'tests': ['tp5o']}, 03:00:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'tests': ['tp5o']}, 03:00:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'talos_options': ['--disable-e10s', 03:00:55 INFO - '--xperf_path', 03:00:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:55 INFO - 'tests': ['tp5n']}, 03:00:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'talos_options': ['--xperf_path', 03:00:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:55 INFO - 'tests': ['tp5n']}}, 03:00:55 INFO - 'talos.zip': {'path': '', 03:00:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:00:55 INFO - Running post-action listener: _resource_record_post_action 03:00:55 INFO - [mozharness: 2016-09-05 10:00:55.952053Z] Finished populate-webroot step (success) 03:00:55 INFO - [mozharness: 2016-09-05 10:00:55.952187Z] Running create-virtualenv step. 03:00:55 INFO - Running pre-action listener: _resource_record_pre_action 03:00:55 INFO - Running main action method: create_virtualenv 03:00:55 INFO - Creating virtualenv /builds/slave/test/build/venv 03:00:55 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 03:00:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:00:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:00:56 INFO - Using real prefix '/tools/python27' 03:00:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:00:56 INFO - Installing distribute.............................................................................................................................................................................................done. 03:00:59 INFO - Installing pip.................done. 03:00:59 INFO - Return code: 0 03:00:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:00: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')]} 03:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00: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')]} 03:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10144ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcb05805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013b2d30>, '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 0x101117db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013aab20>, '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.WByNccnXgl/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.akZ61rclUa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 03:00:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:00:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WByNccnXgl/Render', 03:00:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:59 INFO - 'HOME': '/Users/cltbld', 03:00:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:59 INFO - 'LOGNAME': 'cltbld', 03:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:59 INFO - 'MOZ_NO_REMOTE': '1', 03:00:59 INFO - 'NO_EM_RESTART': '1', 03:00:59 INFO - 'PAGER': '/bin/cat', 03:00:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:59 INFO - 'PWD': '/builds/slave/test', 03:00:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:59 INFO - 'SHELL': '/bin/bash', 03:00:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.akZ61rclUa/Listeners', 03:00:59 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 03:00:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:59 INFO - 'USER': 'cltbld', 03:00:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:59 INFO - 'XPC_FLAGS': '0x0', 03:00:59 INFO - 'XPC_SERVICE_NAME': '0', 03:00:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:59 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 03:00:59 INFO - Cleaning up... 03:00:59 INFO - Return code: 0 03:00:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:00: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')]} 03:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00: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')]} 03:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10144ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcb05805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013b2d30>, '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 0x101117db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013aab20>, '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.WByNccnXgl/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.akZ61rclUa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 03:00:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:00:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WByNccnXgl/Render', 03:00:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:59 INFO - 'HOME': '/Users/cltbld', 03:00:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:59 INFO - 'LOGNAME': 'cltbld', 03:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:59 INFO - 'MOZ_NO_REMOTE': '1', 03:00:59 INFO - 'NO_EM_RESTART': '1', 03:00:59 INFO - 'PAGER': '/bin/cat', 03:00:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:59 INFO - 'PWD': '/builds/slave/test', 03:00:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:59 INFO - 'SHELL': '/bin/bash', 03:00:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.akZ61rclUa/Listeners', 03:00:59 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 03:00:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:59 INFO - 'USER': 'cltbld', 03:00:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:59 INFO - 'XPC_FLAGS': '0x0', 03:00:59 INFO - 'XPC_SERVICE_NAME': '0', 03:00:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:00 INFO - Downloading/unpacking psutil>=3.1.1 03:01:00 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 03:01:00 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 03:01:00 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 03:01:00 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 03:01:00 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 03:01:00 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 03:01:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:01:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:04 INFO - Installing collected packages: psutil 03:01:04 INFO - Running setup.py install for psutil 03:01:04 INFO - building 'psutil._psutil_osx' extension 03:01:04 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 03:01:04 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 03:01:04 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 03:01:04 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 03:01:04 INFO - building 'psutil._psutil_posix' extension 03:01:04 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 03:01:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:01:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:01:04 INFO - ^ 03:01:04 INFO - 1 warning generated. 03:01:04 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 03:01:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:04 INFO - Successfully installed psutil 03:01:04 INFO - Cleaning up... 03:01:04 INFO - Return code: 0 03:01:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:01: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')]} 03:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01: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')]} 03:01:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10144ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcb05805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013b2d30>, '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 0x101117db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013aab20>, '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.WByNccnXgl/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.akZ61rclUa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 03:01: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:01: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 mozsystemmonitor==0.3 03:01:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WByNccnXgl/Render', 03:01:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:04 INFO - 'HOME': '/Users/cltbld', 03:01:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:04 INFO - 'LOGNAME': 'cltbld', 03:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:04 INFO - 'MOZ_NO_REMOTE': '1', 03:01:04 INFO - 'NO_EM_RESTART': '1', 03:01:04 INFO - 'PAGER': '/bin/cat', 03:01:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:04 INFO - 'PWD': '/builds/slave/test', 03:01:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:04 INFO - 'SHELL': '/bin/bash', 03:01:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.akZ61rclUa/Listeners', 03:01:04 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 03:01:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:04 INFO - 'USER': 'cltbld', 03:01:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:04 INFO - 'XPC_FLAGS': '0x0', 03:01:04 INFO - 'XPC_SERVICE_NAME': '0', 03:01:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:01:05 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 03:01:05 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 03:01:05 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 03:01:05 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 03:01:05 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 03:01:05 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 03:01:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:01:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:01:07 INFO - Installing collected packages: mozsystemmonitor 03:01:07 INFO - Running setup.py install for mozsystemmonitor 03:01:07 INFO - Successfully installed mozsystemmonitor 03:01:07 INFO - Cleaning up... 03:01:07 INFO - Return code: 0 03:01:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:07 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 0x10144ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcb05805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013b2d30>, '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 0x101117db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013aab20>, '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.WByNccnXgl/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.akZ61rclUa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 03:01:07 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 03:01:07 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 03:01:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WByNccnXgl/Render', 03:01:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:07 INFO - 'HOME': '/Users/cltbld', 03:01:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:07 INFO - 'LOGNAME': 'cltbld', 03:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:07 INFO - 'MOZ_NO_REMOTE': '1', 03:01:07 INFO - 'NO_EM_RESTART': '1', 03:01:07 INFO - 'PAGER': '/bin/cat', 03:01:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:07 INFO - 'PWD': '/builds/slave/test', 03:01:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:07 INFO - 'SHELL': '/bin/bash', 03:01:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.akZ61rclUa/Listeners', 03:01:07 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 03:01:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:07 INFO - 'USER': 'cltbld', 03:01:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:07 INFO - 'XPC_FLAGS': '0x0', 03:01:07 INFO - 'XPC_SERVICE_NAME': '0', 03:01:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:08 INFO - Downloading/unpacking jsonschema==2.5.1 03:01:08 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 03:01:08 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 03:01:08 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 03:01:08 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 03:01:08 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 03:01:08 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 03:01:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:01:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:01:10 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 03:01:10 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 03:01:10 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 03:01:10 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 03:01:10 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 03:01:10 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 03:01:11 INFO - Downloading functools32-3.2.3-2.tar.gz 03:01:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:01:11 INFO - warning: no files found matching '*.txt' 03:01:11 INFO - no previously-included directories found matching 'build' 03:01:11 INFO - no previously-included directories found matching 'dist' 03:01:11 INFO - no previously-included directories found matching '.git*' 03:01:11 INFO - Installing collected packages: jsonschema, functools32 03:01:11 INFO - Running setup.py install for functools32 03:01:11 INFO - warning: no files found matching '*.txt' 03:01:11 INFO - no previously-included directories found matching 'build' 03:01:11 INFO - no previously-included directories found matching 'dist' 03:01:11 INFO - no previously-included directories found matching '.git*' 03:01:11 INFO - Successfully installed jsonschema functools32 03:01:11 INFO - Cleaning up... 03:01:11 INFO - Return code: 0 03:01:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:01:11 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')]} 03:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:11 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')]} 03:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:11 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 0x10144ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcb05805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013b2d30>, '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 0x101117db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013aab20>, '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.WByNccnXgl/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.akZ61rclUa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 03:01:11 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 03:01:11 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 03:01:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WByNccnXgl/Render', 03:01:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:11 INFO - 'HOME': '/Users/cltbld', 03:01:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:11 INFO - 'LOGNAME': 'cltbld', 03:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:11 INFO - 'MOZ_NO_REMOTE': '1', 03:01:11 INFO - 'NO_EM_RESTART': '1', 03:01:11 INFO - 'PAGER': '/bin/cat', 03:01:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:11 INFO - 'PWD': '/builds/slave/test', 03:01:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:11 INFO - 'SHELL': '/bin/bash', 03:01:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.akZ61rclUa/Listeners', 03:01:11 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 03:01:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:11 INFO - 'USER': 'cltbld', 03:01:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:11 INFO - 'XPC_FLAGS': '0x0', 03:01:11 INFO - 'XPC_SERVICE_NAME': '0', 03:01:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:01:11 INFO - Cleaning up... 03:01:11 INFO - Return code: 0 03:01:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:01:11 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')]} 03:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:11 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')]} 03:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:11 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 0x10144ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcb05805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013b2d30>, '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 0x101117db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013aab20>, '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.WByNccnXgl/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.akZ61rclUa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 03:01:11 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 03:01:11 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 03:01:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WByNccnXgl/Render', 03:01:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:11 INFO - 'HOME': '/Users/cltbld', 03:01:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:11 INFO - 'LOGNAME': 'cltbld', 03:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:11 INFO - 'MOZ_NO_REMOTE': '1', 03:01:11 INFO - 'NO_EM_RESTART': '1', 03:01:11 INFO - 'PAGER': '/bin/cat', 03:01:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:11 INFO - 'PWD': '/builds/slave/test', 03:01:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:11 INFO - 'SHELL': '/bin/bash', 03:01:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.akZ61rclUa/Listeners', 03:01:11 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 03:01:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:11 INFO - 'USER': 'cltbld', 03:01:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:11 INFO - 'XPC_FLAGS': '0x0', 03:01:11 INFO - 'XPC_SERVICE_NAME': '0', 03:01:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:12 INFO - Downloading/unpacking blobuploader==1.2.4 03:01:12 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 03:01:12 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 03:01:12 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 03:01:12 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 03:01:12 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 03:01:12 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 03:01:14 INFO - Downloading blobuploader-1.2.4.tar.gz 03:01:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:01:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:01:14 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 03:01:14 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 03:01:14 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 03:01:14 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 03:01:14 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 03:01:14 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 03:01:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:01:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:01:15 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 03:01:15 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 03:01:15 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 03:01:15 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 03:01:15 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 03:01:15 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 03:01:15 INFO - Downloading docopt-0.6.1.tar.gz 03:01:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:01:15 INFO - Installing collected packages: blobuploader, requests, docopt 03:01:15 INFO - Running setup.py install for blobuploader 03:01:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:01:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:01:15 INFO - Running setup.py install for requests 03:01:16 INFO - Running setup.py install for docopt 03:01:16 INFO - Successfully installed blobuploader requests docopt 03:01:16 INFO - Cleaning up... 03:01:16 INFO - Return code: 0 03:01:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:16 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 0x10144ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcb05805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013b2d30>, '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 0x101117db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013aab20>, '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.WByNccnXgl/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.akZ61rclUa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 03:01:16 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 03:01:16 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 03:01:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WByNccnXgl/Render', 03:01:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:16 INFO - 'HOME': '/Users/cltbld', 03:01:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:16 INFO - 'LOGNAME': 'cltbld', 03:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:16 INFO - 'MOZ_NO_REMOTE': '1', 03:01:16 INFO - 'NO_EM_RESTART': '1', 03:01:16 INFO - 'PAGER': '/bin/cat', 03:01:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:16 INFO - 'PWD': '/builds/slave/test', 03:01:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:16 INFO - 'SHELL': '/bin/bash', 03:01:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.akZ61rclUa/Listeners', 03:01:16 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 03:01:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:16 INFO - 'USER': 'cltbld', 03:01:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:16 INFO - 'XPC_FLAGS': '0x0', 03:01:16 INFO - 'XPC_SERVICE_NAME': '0', 03:01:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:16 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-40DKmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:17 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-qfrZnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:17 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-P8BVUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:17 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-g9PCpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:17 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-4tfKoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:17 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-MRie80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:17 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-h5uf0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:17 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Jxjz3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:17 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-rGSdZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:17 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-JwjjUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:18 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-hcp99m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:18 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-y6xCRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:18 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-sMc3oR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:18 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-hbP_A1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:18 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-0Gu733-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:18 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-s_TU0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:18 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-ZuoTAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:01:18 INFO - Running setup.py install for manifestparser 03:01:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:01:18 INFO - Running setup.py install for mozcrash 03:01:19 INFO - Running setup.py install for mozdebug 03:01:19 INFO - Running setup.py install for mozdevice 03:01:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:01:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:01:19 INFO - Running setup.py install for mozfile 03:01:19 INFO - Running setup.py install for mozhttpd 03:01:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:01:19 INFO - Running setup.py install for mozinfo 03:01:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:01:19 INFO - Running setup.py install for mozInstall 03:01:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:01:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:01:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:01:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:01:19 INFO - Running setup.py install for mozleak 03:01:20 INFO - Running setup.py install for mozlog 03:01:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:01:20 INFO - Running setup.py install for moznetwork 03:01:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:01:20 INFO - Running setup.py install for mozprocess 03:01:20 INFO - Running setup.py install for mozprofile 03:01:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:01:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:01:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:01:20 INFO - Running setup.py install for mozrunner 03:01:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:01:20 INFO - Running setup.py install for mozscreenshot 03:01:21 INFO - Running setup.py install for moztest 03:01:21 INFO - Running setup.py install for mozversion 03:01:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:01:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:01:21 INFO - Cleaning up... 03:01:21 INFO - Return code: 0 03:01:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:21 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 0x10144ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcb05805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013b2d30>, '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 0x101117db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013aab20>, '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.WByNccnXgl/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.akZ61rclUa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 03:01:21 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 03:01:21 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 03:01:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WByNccnXgl/Render', 03:01:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:21 INFO - 'HOME': '/Users/cltbld', 03:01:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:21 INFO - 'LOGNAME': 'cltbld', 03:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:21 INFO - 'MOZ_NO_REMOTE': '1', 03:01:21 INFO - 'NO_EM_RESTART': '1', 03:01:21 INFO - 'PAGER': '/bin/cat', 03:01:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:21 INFO - 'PWD': '/builds/slave/test', 03:01:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:21 INFO - 'SHELL': '/bin/bash', 03:01:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.akZ61rclUa/Listeners', 03:01:21 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 03:01:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:21 INFO - 'USER': 'cltbld', 03:01:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:21 INFO - 'XPC_FLAGS': '0x0', 03:01:21 INFO - 'XPC_SERVICE_NAME': '0', 03:01:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:21 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-X6xg0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:21 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)) 03:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:21 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-uKoRPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:21 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)) 03:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:21 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-YnDGuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:21 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)) 03:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:21 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-AgkimU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:22 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)) 03:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-BKISDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:22 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)) 03:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-dBBSiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:22 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)) 03:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-bGpFxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:22 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)) 03:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-wKmX6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:22 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)) 03:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-TRqjMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:22 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)) 03:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-n5i_ja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:22 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)) 03:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-OnHxs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:22 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)) 03:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-XuIs8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:22 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)) 03:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-GhYaCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:22 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)) 03:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:22 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-_xcupn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:23 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)) 03:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:23 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-bIqV3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:23 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)) 03:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:23 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-rqhdf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:23 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)) 03:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:23 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-meiq3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:23 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)) 03:01:23 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)) 03:01:23 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)) 03:01:23 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)) 03:01:23 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)) 03:01:23 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)) 03:01:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:01:23 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 03:01:23 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 03:01:23 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 03:01:23 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 03:01:23 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 03:01:23 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 03:01:25 INFO - Downloading blessings-1.6.tar.gz 03:01:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:01:25 INFO - Installing collected packages: blessings 03:01:25 INFO - Running setup.py install for blessings 03:01:26 INFO - Successfully installed blessings 03:01:26 INFO - Cleaning up... 03:01:26 INFO - Return code: 0 03:01:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:01:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:26 INFO - Reading from file tmpfile_stdout 03:01:26 INFO - Current package versions: 03:01:26 INFO - blessings == 1.6 03:01:26 INFO - blobuploader == 1.2.4 03:01:26 INFO - docopt == 0.6.1 03:01:26 INFO - functools32 == 3.2.3-2 03:01:26 INFO - jsonschema == 2.5.1 03:01:26 INFO - manifestparser == 1.1 03:01:26 INFO - mozInstall == 1.12 03:01:26 INFO - mozcrash == 0.17 03:01:26 INFO - mozdebug == 0.1 03:01:26 INFO - mozdevice == 0.48 03:01:26 INFO - mozfile == 1.2 03:01:26 INFO - mozhttpd == 0.7 03:01:26 INFO - mozinfo == 0.9 03:01:26 INFO - mozleak == 0.1 03:01:26 INFO - mozlog == 3.3 03:01:26 INFO - moznetwork == 0.27 03:01:26 INFO - mozprocess == 0.23 03:01:26 INFO - mozprofile == 0.28 03:01:26 INFO - mozrunner == 6.12 03:01:26 INFO - mozscreenshot == 0.1 03:01:26 INFO - mozsystemmonitor == 0.3 03:01:26 INFO - moztest == 0.7 03:01:26 INFO - mozversion == 1.4 03:01:26 INFO - psutil == 3.1.1 03:01:26 INFO - requests == 1.2.3 03:01:26 INFO - wsgiref == 0.1.2 03:01:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:26 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 0x10144ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcb05805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013b2d30>, '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 0x101117db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013aab20>, '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.WByNccnXgl/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.akZ61rclUa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 03:01:26 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 03:01:26 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 03:01:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WByNccnXgl/Render', 03:01:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:26 INFO - 'HOME': '/Users/cltbld', 03:01:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:26 INFO - 'LOGNAME': 'cltbld', 03:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:26 INFO - 'MOZ_NO_REMOTE': '1', 03:01:26 INFO - 'NO_EM_RESTART': '1', 03:01:26 INFO - 'PAGER': '/bin/cat', 03:01:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:26 INFO - 'PWD': '/builds/slave/test', 03:01:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:26 INFO - 'SHELL': '/bin/bash', 03:01:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.akZ61rclUa/Listeners', 03:01:26 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 03:01:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:26 INFO - 'USER': 'cltbld', 03:01:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:26 INFO - 'XPC_FLAGS': '0x0', 03:01:26 INFO - 'XPC_SERVICE_NAME': '0', 03:01:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:26 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)) 03:01:26 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)) 03:01:26 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)) 03:01:26 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)) 03:01:26 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)) 03:01:26 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)) 03:01:26 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)) 03:01:26 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)) 03:01:26 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)) 03:01:26 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)) 03:01:26 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)) 03:01:26 INFO - Cleaning up... 03:01:26 INFO - Return code: 0 03:01:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10144ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcb05805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013b2d30>, '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 0x101117db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013aab20>, '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.WByNccnXgl/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.akZ61rclUa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 03:01:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:01:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:01:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WByNccnXgl/Render', 03:01:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:26 INFO - 'HOME': '/Users/cltbld', 03:01:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:26 INFO - 'LOGNAME': 'cltbld', 03:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:26 INFO - 'MOZ_NO_REMOTE': '1', 03:01:26 INFO - 'NO_EM_RESTART': '1', 03:01:26 INFO - 'PAGER': '/bin/cat', 03:01:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:26 INFO - 'PWD': '/builds/slave/test', 03:01:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:26 INFO - 'SHELL': '/bin/bash', 03:01:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.akZ61rclUa/Listeners', 03:01:26 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 03:01:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:26 INFO - 'USER': 'cltbld', 03:01:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:26 INFO - 'XPC_FLAGS': '0x0', 03:01:26 INFO - 'XPC_SERVICE_NAME': '0', 03:01:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:01:27 INFO - Cleaning up... 03:01:27 INFO - Return code: 0 03:01:27 INFO - Running post-action listener: _resource_record_post_action 03:01:27 INFO - Running post-action listener: _start_resource_monitoring 03:01:27 INFO - Starting resource monitoring. 03:01:27 INFO - [mozharness: 2016-09-05 10:01:27.077366Z] Finished create-virtualenv step (success) 03:01:27 INFO - [mozharness: 2016-09-05 10:01:27.078403Z] Running install step. 03:01:27 INFO - Running pre-action listener: _resource_record_pre_action 03:01:27 INFO - Running main action method: install 03:01:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:27 INFO - Reading from file tmpfile_stdout 03:01:27 INFO - Detecting whether we're running mozinstall >=1.0... 03:01:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:01:27 INFO - Reading from file tmpfile_stdout 03:01:27 INFO - Output received: 03:01:27 INFO - Usage: mozinstall [options] installer 03:01:27 INFO - Options: 03:01:27 INFO - -h, --help show this help message and exit 03:01:27 INFO - -d DEST, --destination=DEST 03:01:27 INFO - Directory to install application into. [default: 03:01:27 INFO - "/builds/slave/test"] 03:01:27 INFO - --app=APP Application being installed. [default: firefox] 03:01:27 INFO - mkdir: /builds/slave/test/build/application 03:01:27 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'] 03:01:27 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 03:02:01 INFO - Reading from file tmpfile_stdout 03:02:01 INFO - Output received: 03:02:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:02:01 INFO - Running post-action listener: _resource_record_post_action 03:02:01 INFO - [mozharness: 2016-09-05 10:02:01.842911Z] Finished install step (success) 03:02:01 INFO - [mozharness: 2016-09-05 10:02:01.843014Z] Running run-tests step. 03:02:01 INFO - Running pre-action listener: _resource_record_pre_action 03:02:01 INFO - Running main action method: run_tests 03:02:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:02:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:02:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:02:01 INFO - 2016-09-05 03:02:01.857 screenresolution[1928:10141] starting screenresolution argv=screenresolution get 03:02:01 INFO - 2016-09-05 03:02:01.860 screenresolution[1928:10141] Display 0: 1600x1200x32@60 03:02:01 INFO - 2016-09-05 03:02:01.866 screenresolution[1929:10143] starting screenresolution argv=screenresolution list 03:02:01 INFO - Available Modes on Display 0 03:02:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:02:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:02:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:02:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:02:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:02:02 INFO - Intel Iris: 03:02:02 INFO - Chipset Model: Intel Iris 03:02:02 INFO - Type: GPU 03:02:02 INFO - Bus: Built-In 03:02:02 INFO - VRAM (Dynamic, Max): 1536 MB 03:02:02 INFO - Vendor: Intel (0x8086) 03:02:02 INFO - Device ID: 0x0a2e 03:02:02 INFO - Revision ID: 0x0009 03:02:02 INFO - Displays: 03:02:02 INFO - Display: 03:02:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:02:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:02:02 INFO - Display Serial Number: 1600x1200 60 03:02:02 INFO - Main Display: Yes 03:02:02 INFO - Mirror: Off 03:02:02 INFO - Online: Yes 03:02:02 INFO - Rotation: Supported 03:02:02 INFO - Return code: 0 03:02:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:02:02 INFO - Python 2.7.3 03:02:02 INFO - Return code: 0 03:02:02 INFO - grabbing minidump binary from tooltool 03:02:02 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')]} 03:02:02 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 0x1013b2d30>, '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 0x101117db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1013aab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:02:02 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 03:02:02 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 03:02:02 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 03:02:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:02:02 INFO - Return code: 0 03:02:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:02:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:02:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:02:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:02:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:02:02 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/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--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 03:02:02 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/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0142 --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 03:02:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WByNccnXgl/Render', 03:02:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:02 INFO - 'HOME': '/Users/cltbld', 03:02:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:02 INFO - 'LOGNAME': 'cltbld', 03:02:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:02:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:02:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:02 INFO - 'MOZ_NO_REMOTE': '1', 03:02:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:02:02 INFO - 'NO_EM_RESTART': '1', 03:02:02 INFO - 'PAGER': '/bin/cat', 03:02:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:02 INFO - 'PWD': '/builds/slave/test', 03:02:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:02:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:02 INFO - 'SHELL': '/bin/bash', 03:02:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.akZ61rclUa/Listeners', 03:02:02 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 03:02:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:02 INFO - 'USER': 'cltbld', 03:02:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:02 INFO - 'XPC_FLAGS': '0x0', 03:02:02 INFO - 'XPC_SERVICE_NAME': '0', 03:02:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:02 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/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--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 03:02:02 INFO - mozversion application_buildid: 20160905014120 03:02:02 INFO - mozversion application_changeset: 8515bb6df598578d036cd14422f5ab4e9d65980e 03:02:02 INFO - mozversion application_display_name: Nightly 03:02:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:02:02 INFO - mozversion application_name: Firefox 03:02:02 INFO - mozversion application_remotingname: firefox 03:02:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:02:02 INFO - mozversion application_vendor: Mozilla 03:02:02 INFO - mozversion application_version: 51.0a1 03:02:02 INFO - mozversion platform_buildid: 20160905014120 03:02:02 INFO - mozversion platform_changeset: 8515bb6df598578d036cd14422f5ab4e9d65980e 03:02:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:02:02 INFO - mozversion platform_version: 51.0a1 03:02:02 INFO - using testdate: 1473069722 03:02:02 INFO - actual date: 1473069722 03:02:02 INFO - starting webserver on 'localhost:49710' 03:02:02 INFO - SUITE-START | Running 2 tests 03:02:02 INFO - TEST-START | dromaeo_css 03:02:02 INFO - operating with platform_type : mac_ 03:02:02 INFO - Initialising browser for dromaeo_css test... 03:02:02 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpVvc7Xs/profile http://localhost:49710/getInfo.html) 03:02:04 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 03:02:04 INFO - PROCESS | 1939 | colorDepth:24 03:02:04 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 03:02:04 INFO - PROCESS | 1939 | __metrics 03:02:04 INFO - PROCESS | 1939 | 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" 03:02:04 INFO - PROCESS | 1939 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:02:05 INFO - TEST-INFO | 1939: exit 0 03:02:05 INFO - Browser initialized. 03:02:05 INFO - Running cycle 1/1 for dromaeo_css test... 03:02:05 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpVvc7Xs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:02:17 INFO - PROCESS | 1941 | RSS: Main: 232439808 03:02:17 INFO - PROCESS | 1941 | 03:03:08 INFO - PROCESS | 1941 | 1473069788932 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] 03:05:20 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49710/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49710/tests/dromaeo/cssquery-ext.html) 03:05:21 INFO - PROCESS | 1941 | RSS: Main: 373334016 03:05:21 INFO - PROCESS | 1941 | 03:08:24 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49710/tests/dromaeo/cssquery-ext.html (next: http://localhost:49710/tests/dromaeo/cssquery-jquery.html) 03:08:25 INFO - PROCESS | 1941 | RSS: Main: 367419392 03:08:25 INFO - PROCESS | 1941 | 03:11:27 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49710/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49710/tests/dromaeo/cssquery-mootools.html) 03:11:28 INFO - PROCESS | 1941 | RSS: Main: 344125440 03:11:28 INFO - PROCESS | 1941 | 03:14:30 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49710/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49710/tests/dromaeo/cssquery-prototype.html) 03:14:31 INFO - PROCESS | 1941 | RSS: Main: 349184000 03:14:31 INFO - PROCESS | 1941 | 03:17:34 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49710/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49710/tests/dromaeo/cssquery-yui.html) 03:17:35 INFO - PROCESS | 1941 | RSS: Main: 325644288 03:17:35 INFO - PROCESS | 1941 | 03:20:39 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:49710/tests/dromaeo/cssquery-yui.html (next: http://localhost:49710/tests/dromaeo/cssquery-dojo.html) 03:20:39 INFO - PROCESS | 1941 | RSS: Main: 404099072 03:20:39 INFO - PROCESS | 1941 | 03:20:39 INFO - PROCESS | 1941 | __start_tp_report 03:20:39 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 03:20:39 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 03:20:39 INFO - PROCESS | 1941 | |i|pagename|runs| 03:20:39 INFO - PROCESS | 1941 | |0;dojo.html;2801.3556881917157;3303.9008829735108;3307.3550657621768;3382.509536117265;3410.7612467127296;5513.7794488220425;5631.774729010835;5682.6306290091115;5790.739416726252;5813.5349172066235;5664.320281566218;5687.232223649806;5781.075027995522;5795.420457954203;5872.383399743029;5109.233614957768;5149.227615857614;5183.948160518385;5208.47915208478;5263.131583288753;5316.9734151329185;5316.9734151329185;5324.600654950875;5327.840164795043;5368.033753924292;4955.1080805455;5135.922961155586;5154.123798954187;5179.663321884103;5231.555317797973;5186.818461353872;5231.3199284092825;5231.633785635007;5233.816816411408;5234.1887007513515;5064.696118232918;5131;5194.4026436959475;5241.737913104368;5297.205419187114;4701.95298047022;5673.404292549306;5701.372848986608;5706.657600543939;5725.914111288334;6244.157038799749;6247.344028876993;6255.874882502325;6261.968690156566;6262.87474250517;6075.9696201519155;6122.785702500436;6209.565330426872;6276.184096067527;6277.121203031579;5746.109353050242;5808.651480911159;5823.5341172706085;5827.3007239131575;5873.353931067579;6233.470155036873;6246.812595622138;6264.718087686065;6281.026440901667;6293.496520278367;6010.699465026732;6029.3970602940235;6098.237720284965;6115.544587952024;6143.447089761943;6241.469475094577;6250.4374606285655;6267.780627678009;6277.780277690259;6281.654509002049;6160.9383906160365;6162.815115546541;6237.906431403532;6245.469135123565;6255.530835187379;5606.55147588192;5805.303363596396;6117.227785852181;6144.910730351702;6209.06863970399;4870.439899411542;4925.21196608541;4938.308636790852;4981.676191047571;4986.227134794112;4403.581659742319;4407.867763967086;4412.360207769855;4412.514623391425;4453.933191002137;5609.915851262234;5638.943610563924;5708.771649134072;5724.341700704473;5745.8563535911935;6178.196834411498;6242.250929888442;6265.091561723524;6270;6276.403741644536;5682.488576028095;5743.0811070228565;5759.539236861041;5760.337561180434;5785.30576330843;5299.68201907879;5389.730513474389;5440.727963601883;5462.071447853875;5476.04169270384;6190.350013248544;6230.283517395557;6244.375562443719;6261.217347831521;6305.211848518935;6235.875282494416;6239.937600624117;6242.812715618538;6251.156093927261;6253.187085678833;6279.30927597973;6290.654014029089;6300.905486417799;6317.526185536011;6318.399752023551;6004.309504407049;6103.580118178587;6145.555794388226;6145.692715364304;6238.313785483502;6199.938000619936;6257.530685198537;6263.56155069159;6275.215598050244;6283.905741413791;6099.756009759737;6105.603135796159;6108.08378743192;6111.84720381994;6123.969380153071;4929.359183306147;5943.762249509971;6018.578699490994;6021.518278537794;6104.125697713769;5962.880742385041;5968.313643931004;5970.910436343372;5972.552058595709;6017.127516510169;6071.848203794763;6119.969400152971;6123.112148738497;6124.173236613;6127.050307202391;5740.741666624928;5899.351071382059;5926.555508336805;5964.284285885721;6036.517078633787 03:20:39 INFO - PROCESS | 1941 | |1;ext.html;467.50911542879976;520.3547600974788;532.0980937311259;544.6378158524581;551.4127454261209;30320.393592128145;33446.99999999997;39661.603383966154;40963;43419;42459.78770106153;43030;43036.13927721444;43068.78465607676;43078.78460607692;9624.85562716558;9809;9814.312998090136;9828.508574571242;9835.11483966443;8722.171393717586;8851.026387097414;11903.58337458189;12151.210171338835;12187.598426181014;10697.358158510475;12013.699657508545;12146.17807328899;12152.39238038099;12156.331401772899;12166.90497855193;12249.755004899896;12249.755004899942;12270.693232669255;12297.815532767016;9978.451185184811;11922.105842061794;11997.460114294878;12000.97991670709;12011.978981786559;39727;40015.39976900349;40057;40096.79951600223;40102;2938.2801213702564;3469.2887957968555;3751.043483911585;3777.4145007523603;4023.0143614814265;30155;31148.844255778804;31192.064238072893;31223;31459.056228313188;3145.465270903952;3241.205904553376;3281.606207255121;3311;3313.6355000949875;348021;348862;349079;350388;353022;56795.14807277894;56851.71574142103;56903.14645280324;57140;57232.71383643055;58385;59331.813363732486;59358.70320648369;59407;59476.70261648664;13858.861411385958;16288.348466061225;16299.266533006043;16308.347666093223;16328;11420.771584568261;11686.824697629543;12755.362231888368;12859.42132604035;12876.22742635445;12459.377031148406;14737.778933316009;15094.867884908675;15146.9397142199;15461.613459663598;6577.210734711865;6600.86798264032;6605.966970165118;6640.701168447432;6645.468362530986;834.929031032369;955.2358113509165;959.2949182350967;975.9560819763128;1047.8218702820539;28511;29641;29725.83838262645;30341.848290758404;30452.543211851396;867.444835305405;997.9820583005439;1005.050227534987;1036.3988886445843;1134.2627292260097;13672.65818354549;15408.460703875535;15532.068075915482;15676.608084797972;15825;671846;677111;678258;679262;679986.2001379923;56899.71550142223;57130.428695713344;57198.570035748606;57208.71395642995;57584.424155758745;1180.5985964771846;1200.8378868852767;1306.888914442283;1334.7130366971148;1349.426493740137;29872.13797204894;30549.93075242356;30710;30796;31321.686783131878;46579.53420465752;46935;46967.76516117398;47079;47125;530215;533206.3415877955;534371;535133;536263;25265.49469010646;25937.573433460588;25961.014483334657;26742.866285669224;38504;46015;46136.53863461322;46429.767851160526;46736.82105631569;47438.52561474341;33747;37864;38294.42558361682;38980.6101938977;39598;33230.33539329248;35738.92783216508;37658.2468350626;38428.42357364698;38846.834594962194;36506.26987460289;37005.25989480249;37120.6287937128;37555;37805;26909.327266818487;32161.356772863943;33328.50007249942;33505.827296044525;33556.83221583877;22297.219597314015;24854.254372368134;25035.87338569805;25234.495310094062;25397.746022539537 03:20:39 INFO - PROCESS | 1941 | |2;jquery.html;353.4980327934333;371.6933529837884;376.7569917403275;385.83216300909106;408.21215054943946;15636.687266254668;15786.131762753059;20330.796692033076;20598;20753.584928301425;19678.901605491952;19954.401367958984;20500.794992050076;20515.281965131224;20561.869097199684;5369.275147855029;5468.617196796206;5558;5579.023670857604;6032.577719559633;3939.940900886489;6042.395760423967;6110.2973158086725;6130.540209484284;6134.662593557353;6465.773697920574;6771.187457505106;6808.489363297747;6841.9657901710425;6873.243943166248;5974.6713930733795;6013.669248191348;6082.452579267843;6597.703103360336;6809.5233333666665;5942.762289508414;5943.197668314765;5944.7324870380935;5946.70266486674;5950.940490595082;12959.935200324033;13012.674683132902;13139.343032848416;13296.468141274434;13322.267275299855;2218.6228341182036;2250.8762018089;2258.683784270187;2694.3398867277447;2892.7975041747086;9868.851967220497;9869;9875.358101723365;9919.603215871357;9940.90059099407;1627.617509885176;1785.526835388623;1812.18451696737;1820.226403778402;1876.5496280892487;94871;125089;125148.36238919703;125235;127650;11259.662210133709;11379.544818207345;11399.316041037564;11501.309921404743;11602.825957610643;14511.492097776527;14529.128252304896;14530.564083077525;14549.92725036368;14553.27233638314;7160.641967901584;8359.331253499706;8633.39566230358;8669.349798764992;8772.78068048291;8530.10433904431;8535.829283414421;8536.402451828313;8707.47755134694;8708.390412671179;4397.802098905557;4490.046829629056;4494.6852371028945;4576.725396476222;4579.175748365326;1750.5536088297404;1805.4624049659988;1857;1898.8511950270165;1911.2928216559962;678.4402867634221;741.3031750154837;749.5352881213573;939.6852054561662;1022.4734261855151;17266.827331726774;18428.170732317078;20783.168673253203;21130;21452;809.9028116626042;869.799946012408;902.3142411766974;922.9769255768525;951.4909523404945;8252.628631711588;8860;10921.617743379102;11070.225084244186;11189.216754827085;576737;590983;596540;604392;604428;115082.42458787652;134435.65564344233;140514.29742851426;141629;143883;954.3462728031182;963.6579014449769;972.7130496503548;976.5019839881569;991.0535438655922;13833.859178889994;15353.692926141637;19439.902800485906;19500.401294111107;19623.411297661092;51240.487595125065;52330.47669523256;53198;53549.464505354445;53766;74361;74453.62773186316;75384;77428.83856742266;77751.61124194342;6606.8348291293105;7863.882041769492;8086.7169649062;8113.35093192555;8132.878006829784;11087.72280692989;11184.328940263611;12169.9655529281;12689.784906314273;12895.16181448203;10751.784964300827;11139.554417823196;11172.832407514057;11269.943650281695;11280.774384512428;8556.614952327285;10599.523021463898;10753.784924301313;11118.944405277922;11186.216964812711;9425.905740942502;11040.834387484354;11326.490307935997;11354.659360219206;11413.258138220988;6620.205575330991;7289.8177545559665;7870.763877083697;7998.760037198894;8305.584720764058;9338;10499.212559057949;10801.351918884891;11048.171387146156;11104.666859994213 03:20:39 INFO - PROCESS | 1941 | |3;mootools.html;1505.9322330495124;1539.5766164304816;1731.1517356495322;1883.7456943312648;1911.6941289393692;8407.327413806895;8897.955510222457;8910;8912.46525208488;8914.687985920495;7791.454598178116;8013.358931285497;8495.872561911576;8705.956470217656;8715.389922705413;2363.952720945584;2403.483251101013;2405.5910495215767;2405.9518809623796;2407.2176542623715;2365.2667673021333;2385.3798012516695;2413.263954493879;2413.51729654069;2419.6128619420897;2315.7684231576877;2331.347222777625;2346.9295921122393;2351.929442116739;2354.6585745066955;2363.2201373546686;2382.6783384243076;2383.7854593086704;2387.450886296153;2395.952080958371;2353.7410884802657;2368.7512811154925;2375.2043065573052;2375.7743014413604;2376.512814872964;7469.813254668677;8820.8676869847;8919.464832110094;8942.37403381757;8945.15021072997;5233.659812112201;5395.406505284415;5412.431694672042;5418.106012507931;5418.45815418455;3602.35157671619;3625.075605720551;3691.7046636269033;3697.131174174067;3697.3899306614376;934.5747684803466;1073.420353009374;1081.6376513867785;1096.6764804382726;1112.7830073135742;11653.59212427561;11705.882941170565;11734.647960561111;11750.882491175149;11753.764924701458;4547.181507328646;4650.5349465053905;4833.4441539223435;4835.540623640749;4838.104950584153;9881.80236395268;9891.802163956681;9896.010398960047;9912;9947.751306217258;5358.33020872391;5361.919571206435;5362.731863406814;5373.435789242151;5376.220448035091;4260.297050986553;4394.582514661102;4550.522195169526;4922.286268491049;4946.8763280918265;3858.131920317906;3891.2412079644196;4018.4374187613757;4027.013381721497;4037.4953130858644;1082.5236895765838;1297.1309222820732;1305.5626365167775;1313.6715821044738;1317.7298653775954;755.6750597243129;766.8849672549162;832.1636755061246;891.4695756025154;892.4912799704236;5494;5502.147167189092;5705.914411283834;5774.220480235199;5902.527797776168;841.3059226138461;864.4337958637103;865.1780808232205;906.4063038709655;915.7023967210683;3523.594786599574;3628.020434482675;3701.574318953301;3767.453719210699;3865.2462769759627;9830.803383932423;10053.894071652112;10431.426271554985;10456.843147352643;10719.303245289031;10563.207759418074;10703.250772445854;10726.088282495919;10785.514651840685;10797.46012699362;8140.430169888169;8289.544075075808;8293.543855088028;8304.916950830415;8305;5600.383957764561;5660.632058916157;5737.196792448977;5769.826905192851;5783.537317014713;10188.28681992283;10236.948815255875;10249.847532018359;10251.948740256252;10327;9633.421994680342;10051.497425128568;10078.596856125672;10232.283740138417;10234.130098941376;6797.898031529432;7006.229314775269;7270.854582908207;7465.328120469184;7470.4770666052855;7955.562444065632;8154.184581542036;8251.257386835214;8404.369672274726;8415.873761893454;7379.335859772432;7500.474966752275;7513.661885215288;7564.659590318559;7668.693252269847;6176.4441200292185;7314.155851922664;7322.670479828527;7337.156227033959;7377.77866664001;6697;6741.831454213684;6813.557118787263;6845.007473916354;6846.965765171142;4890.315355850113;4912.803487860446;4925.482824303396;4978.327925730125;5060.07717067512;4879.194932836042;4904.754762261801;4992.176290911959;5048.394192696753;5070.797168113234 03:20:39 INFO - PROCESS | 1941 | |4;prototype.html;301.48747129879195;305.3831260853077;313.47805903171223;319.4282234799709;320.5784393522518;5103.336566246383;5258.44786297439;5285.92071118933;5321.547668448182;5406.378266499349;4566.885827854306;5013.548780609744;5207.192885102806;5208.333333333335;5232.947670523294;4001.439438618941;4043.4137050127715;4058.874666506788;4061.289274376987;4097.467329247201;3293.090007795485;3523.401021826283;3879.3405121129335;4035.6973227007943;4080.306347920846;3362.411577973857;3988.521377434712;3998.3402738548107;4029.355303151496;4037.253108174997;4019.5328705022603;4035.899102522431;4044.1102957349335;4055.6958228133008;4063.654589359878;2918.3433727411375;3081.828905016102;3491.3715531204375;3551.2897420516;3579.8210089495683;4730.432348118214;5033.446320904734;5074.99537682617;5167.139829661319;5168.586513078944;3747.7001839852746;3904.3557812960826;4451.217988757026;4501.774911254424;4524.02733412318;5511.4213007634025;5520.751566179491;5543.972280138614;5572.300448363248;5585.709858957808;3181.3319202967405;3254.5118232265113;3463.203463203452;3753.680937120348;3777.97994541475;5190.171356610694;5597.580181486404;5766.336871259817;5959;5969.432903874125;3252.7397808175288;3901.8634347797692;3906.43356713275;3930.8427662893737;3933.724639275223;3335.733141348735;3956.219701134501;3975.5229372475487;3979.343408337592;3987.9661372700134;3960.089179488734;4224.978875105605;4510.977445112819;4556.610233878699;4561.452625684939;5576.386597474275;5607.42058653016;5614.550835933115;5615.4946054854445;5660.179274005246;1084.6745976207105;1122.405125283601;1142.9657110286705;1201.3632774629461;1325.3505782166862;830.5141492226994;836.9245519507404;857.6312185760058;890.944231086165;910.4855756497558;4588.678792484494;5213.393303348325;5214.817481388133;5227.006868694936;5243.030039442715;5006.499350064964;5528.695921724344;5736.5123964462655;5772.5670574707065;5778.451047150514;5136.203888397305;5144.254083157996;5146.202338637517;5178.326817513699;5219.321488206509;2964.7479964202853;2975.375171214026;2993.9192261378134;3029.4849875521213;3041.1788817019724;4860.444657709499;4937.308776771255;5512.310961129859;5632.183333416631;5665.518430933417;4747.169245382044;4818.951810481921;5744.224529688522;5777.88444231113;5809.302883653989;4310.43964284641;4669.089527542164;4819.759012049383;5310.017486884836;5573.854066184467;4846.947519967126;5535.7785688572;5602.131669591057;5631.380548139783;5649.265595472562;5090.905455327123;5308.893822123613;5376.650517716336;5596.888062238651;5643.294588176478;5624.746886390204;5825.2135961644635;5866.592017915755;5886;5904.586678932434;3184.1721152500054;3397.1676939150066;3770.849166033328;3776.8489260429274;3836.597157298555;5088.796448142181;5360.909245394221;5564.360098588714;5628.521575666115;5731.5701322400155;5089.9056702809075;5256.894862102813;5503.449655034625;5529.060059789765;5593.244911936837;4602.562756538124;4749.35883655702;5074.974625126851;5135.717535535581;5451.454854514675;4390.473143222834;4411.985243393973;4675.3688252086895;4829.044237083986;5344.866378340573;5218.791248350023;5303.36359636846;5472.398036216093;5512.66483777309;5535.806246781343;5120.795168193231;5457.808976685797;5458.563314934875;5462.289902312833;5468.316460442445 03:20:39 INFO - PROCESS | 1941 | |5;yui.html;668.7024274197981;750.6622020090961;776.6078130544078;831.1480732249444;844.6663567890683;15000.849991500081;15604.375824966988;15768.763468547966;15774.921125394372;15775.1323677198;13695.383707733152;14073.507427240049;14149.858501414983;14174.43302267908;14455.78316325256;850.336737344872;881.2333270055029;883.6686242659002;884.5046773806657;885.3183049052226;679.259607028339;700.3136925812681;704.8414106825949;867.0852250875324;873.2795443758893;729.5768454296496;730.5580124024968;896.4307664632939;938.3103418987039;949.4920217683541;921.4517362169565;923.6259314977444;924.306769922558;925.1627277314042;926.5274709897933;681.6932380428777;682.1405029662611;682.2324884504932;684.7329541478828;686.3719696477717;11948.761024779456;14641.853581464156;14819;15354.923225383913;15438.614034649223;14255.928720356333;14603.48887788933;14632.634184145376;14751.99203187251;14790.852091479055;11770.646880593597;11780.528778848835;11831.704207394798;12096.939515302367;12224.633261002184;3534.876279330211;3793.7534060285993;3827.119762454623;3840.116773142165;3973;119150.40424797821;119732.40133799275;123086;126178.36910815479;128768.06847897288;11091.611793587186;11239.719007024727;11294.661160165208;11418.086553075735;11543.653690389134;22810;22856.085756569883;22856.771432285797;22858;22860.199893003664;7866.213378662088;8063.193680632007;8141.755747327589;8205.548694821722;8215.137410571924;4285.46431696038;4399.780010999437;4517.593416592523;4543.931841022388;4588.541145885385;3609.5848977367937;3682.1899182179877;3728.794916279631;3736.831842567091;3737.588865224823;576.6684156609966;655.1515787055774;655.4919937048825;663.5388405058535;676.9018492318586;606.6451126091198;635.3297271378648;642.2870613618879;668.19482523559;683.5932620091038;7993.600319983978;8484.325139825693;9313.953430232941;9400.529973501298;9471.422603298419;585.783260193731;635.8537536366662;649.9935000649933;670.51722759613;690.711822451125;4038.333674943601;4314.1371725655;4388.71473354231;4669.743164125938;4699.459562150397;83898.58050709829;95762;133202;143499;152689;132877.33561332326;133552;136668;137229.6125216838;138983;113806;114418;116423.67152657069;116920;117784;9043.909560904307;9133.041030691948;9590;9618.711438656572;9654.71035868925;47849.76075119602;53620;54324;54338.18492722692;64505;91660.62509062502;93654;93908;95386.49405317583;95517.08965820506;10809.67570972872;13217.339133043502;13221.074524783173;13586.93206533961;13817.894568434702;14624.454628049045;14696.265186740406;15033.473828415952;15395.384184632489;15427.61430964235;14028.929855350658;14167.149971001772;14273.64315892111;14326.140431573722;14636.707265854837;13772.10481318711;13869.514566990107;14015.719685606027;14035.087719298213;14271.14373137615;13461.932690336485;13522.121062131324;13945.651358716113;14124.646883827576;14128.717425651224;11231.38227397501;11430.599929002445;11568.710782230175;11673.649790505959;11888.940555297168;8876.932751719409;10657.360558366523;11386.43067846621;11679.357635330138;11698 03:20:39 INFO - PROCESS | 1941 | __end_tp_report 03:20:39 INFO - PROCESS | 1941 | __start_cc_report 03:20:39 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,1247 03:20:39 INFO - PROCESS | 1941 | __end_cc_report 03:20:39 INFO - PROCESS | 1941 | __startTimestamp1473070839377__endTimestamp 03:20:39 INFO - PROCESS | 1941 | 03:20:39 INFO - PROCESS | 1941 | ------- Summary: start ------- 03:20:39 INFO - PROCESS | 1941 | Number of tests: 6 03:20:39 INFO - PROCESS | 1941 | 03:20:39 INFO - PROCESS | 1941 | [#0] dojo.html Cycles:165 Average:5722.64 Median:5962.88 stddev:654.14 (11.0%) stddev-sans-first:614.69 03:20:39 INFO - PROCESS | 1941 | Values: 2801.4 3303.9 3307.4 3382.5 3410.8 5513.8 5631.8 5682.6 5790.7 5813.5 5664.3 5687.2 5781.1 5795.4 5872.4 5109.2 5149.2 5183.9 5208.5 5263.1 5317.0 5317.0 5324.6 5327.8 5368.0 4955.1 5135.9 5154.1 5179.7 5231.6 5186.8 5231.3 5231.6 5233.8 5234.2 5064.7 5131.0 5194.4 5241.7 5297.2 4702.0 5673.4 5701.4 5706.7 5725.9 6244.2 6247.3 6255.9 6262.0 6262.9 6076.0 6122.8 6209.6 6276.2 6277.1 5746.1 5808.7 5823.5 5827.3 5873.4 6233.5 6246.8 6264.7 6281.0 6293.5 6010.7 6029.4 6098.2 6115.5 6143.4 6241.5 6250.4 6267.8 6277.8 6281.7 6160.9 6162.8 6237.9 6245.5 6255.5 5606.6 5805.3 6117.2 6144.9 6209.1 4870.4 4925.2 4938.3 4981.7 4986.2 4403.6 4407.9 4412.4 4412.5 4453.9 5609.9 5638.9 5708.8 5724.3 5745.9 6178.2 6242.3 6265.1 6270.0 6276.4 5682.5 5743.1 5759.5 5760.3 5785.3 5299.7 5389.7 5440.7 5462.1 5476.0 6190.4 6230.3 6244.4 6261.2 6305.2 6235.9 6239.9 6242.8 6251.2 6253.2 6279.3 6290.7 6300.9 6317.5 6318.4 6004.3 6103.6 6145.6 6145.7 6238.3 6199.9 6257.5 6263.6 6275.2 6283.9 6099.8 6105.6 6108.1 6111.8 6124.0 4929.4 5943.8 6018.6 6021.5 6104.1 5962.9 5968.3 5970.9 5972.6 6017.1 6071.8 6120.0 6123.1 6124.2 6127.1 5740.7 5899.4 5926.6 5964.3 6036.5 03:20:39 INFO - PROCESS | 1941 | 03:20:39 INFO - PROCESS | 1941 | [#1] ext.html Cycles:180 Average:65771.59 Median:194380.46 stddev:143835.56 (74.0%) stddev-sans-first:144155.49 03:20:39 INFO - PROCESS | 1941 | Values: 467.5 520.4 532.1 544.6 551.4 30320.4 33447.0 39661.6 40963.0 43419.0 42459.8 43030.0 43036.1 43068.8 43078.8 9624.9 9809.0 9814.3 9828.5 9835.1 8722.2 8851.0 11903.6 12151.2 12187.6 10697.4 12013.7 12146.2 12152.4 12156.3 12166.9 12249.8 12249.8 12270.7 12297.8 9978.5 11922.1 11997.5 12001.0 12012.0 39727.0 40015.4 40057.0 40096.8 40102.0 2938.3 3469.3 3751.0 3777.4 4023.0 30155.0 31148.8 31192.1 31223.0 31459.1 3145.5 3241.2 3281.6 3311.0 3313.6 348021.0 348862.0 349079.0 350388.0 353022.0 56795.1 56851.7 56903.1 57140.0 57232.7 58385.0 59331.8 59358.7 59407.0 59476.7 13858.9 16288.3 16299.3 16308.3 16328.0 11420.8 11686.8 12755.4 12859.4 12876.2 12459.4 14737.8 15094.9 15146.9 15461.6 6577.2 6600.9 6606.0 6640.7 6645.5 834.9 955.2 959.3 976.0 1047.8 28511.0 29641.0 29725.8 30341.8 30452.5 867.4 998.0 1005.1 1036.4 1134.3 13672.7 15408.5 15532.1 15676.6 15825.0 671846.0 677111.0 678258.0 679262.0 679986.2 56899.7 57130.4 57198.6 57208.7 57584.4 1180.6 1200.8 1306.9 1334.7 1349.4 29872.1 30549.9 30710.0 30796.0 31321.7 46579.5 46935.0 46967.8 47079.0 47125.0 530215.0 533206.3 534371.0 535133.0 536263.0 25265.5 25937.6 25961.0 26742.9 38504.0 46015.0 46136.5 46429.8 46736.8 47438.5 33747.0 37864.0 38294.4 38980.6 39598.0 33230.3 35738.9 37658.2 38428.4 38846.8 36506.3 37005.3 37120.6 37555.0 37805.0 26909.3 32161.4 33328.5 33505.8 33556.8 22297.2 24854.3 25035.9 25234.5 25397.7 03:20:39 INFO - PROCESS | 1941 | 03:20:39 INFO - PROCESS | 1941 | [#2] jquery.html Cycles:180 Average:34571.72 Median:2403.01 stddev:99621.99 (4145.7%) stddev-sans-first:99868.32 03:20:39 INFO - PROCESS | 1941 | Values: 353.5 371.7 376.8 385.8 408.2 15636.7 15786.1 20330.8 20598.0 20753.6 19678.9 19954.4 20500.8 20515.3 20561.9 5369.3 5468.6 5558.0 5579.0 6032.6 3939.9 6042.4 6110.3 6130.5 6134.7 6465.8 6771.2 6808.5 6842.0 6873.2 5974.7 6013.7 6082.5 6597.7 6809.5 5942.8 5943.2 5944.7 5946.7 5950.9 12959.9 13012.7 13139.3 13296.5 13322.3 2218.6 2250.9 2258.7 2694.3 2892.8 9868.9 9869.0 9875.4 9919.6 9940.9 1627.6 1785.5 1812.2 1820.2 1876.5 94871.0 125089.0 125148.4 125235.0 127650.0 11259.7 11379.5 11399.3 11501.3 11602.8 14511.5 14529.1 14530.6 14549.9 14553.3 7160.6 8359.3 8633.4 8669.3 8772.8 8530.1 8535.8 8536.4 8707.5 8708.4 4397.8 4490.0 4494.7 4576.7 4579.2 1750.6 1805.5 1857.0 1898.9 1911.3 678.4 741.3 749.5 939.7 1022.5 17266.8 18428.2 20783.2 21130.0 21452.0 809.9 869.8 902.3 923.0 951.5 8252.6 8860.0 10921.6 11070.2 11189.2 576737.0 590983.0 596540.0 604392.0 604428.0 115082.4 134435.7 140514.3 141629.0 143883.0 954.3 963.7 972.7 976.5 991.1 13833.9 15353.7 19439.9 19500.4 19623.4 51240.5 52330.5 53198.0 53549.5 53766.0 74361.0 74453.6 75384.0 77428.8 77751.6 6606.8 7863.9 8086.7 8113.4 8132.9 11087.7 11184.3 12170.0 12689.8 12895.2 10751.8 11139.6 11172.8 11269.9 11280.8 8556.6 10599.5 10753.8 11118.9 11186.2 9425.9 11040.8 11326.5 11354.7 11413.3 6620.2 7289.8 7870.8 7998.8 8305.6 9338.0 10499.2 10801.4 11048.2 11104.7 03:20:39 INFO - PROCESS | 1941 | 03:20:39 INFO - PROCESS | 1941 | [#3] mootools.html Cycles:180 Average:5672.49 Median:4936.18 stddev:3178.92 (64.4%) stddev-sans-first:3172.42 03:20:39 INFO - PROCESS | 1941 | Values: 1505.9 1539.6 1731.2 1883.7 1911.7 8407.3 8898.0 8910.0 8912.5 8914.7 7791.5 8013.4 8495.9 8706.0 8715.4 2364.0 2403.5 2405.6 2406.0 2407.2 2365.3 2385.4 2413.3 2413.5 2419.6 2315.8 2331.3 2346.9 2351.9 2354.7 2363.2 2382.7 2383.8 2387.5 2396.0 2353.7 2368.8 2375.2 2375.8 2376.5 7469.8 8820.9 8919.5 8942.4 8945.2 5233.7 5395.4 5412.4 5418.1 5418.5 3602.4 3625.1 3691.7 3697.1 3697.4 934.6 1073.4 1081.6 1096.7 1112.8 11653.6 11705.9 11734.6 11750.9 11753.8 4547.2 4650.5 4833.4 4835.5 4838.1 9881.8 9891.8 9896.0 9912.0 9947.8 5358.3 5361.9 5362.7 5373.4 5376.2 4260.3 4394.6 4550.5 4922.3 4946.9 3858.1 3891.2 4018.4 4027.0 4037.5 1082.5 1297.1 1305.6 1313.7 1317.7 755.7 766.9 832.2 891.5 892.5 5494.0 5502.1 5705.9 5774.2 5902.5 841.3 864.4 865.2 906.4 915.7 3523.6 3628.0 3701.6 3767.5 3865.2 9830.8 10053.9 10431.4 10456.8 10719.3 10563.2 10703.3 10726.1 10785.5 10797.5 8140.4 8289.5 8293.5 8304.9 8305.0 5600.4 5660.6 5737.2 5769.8 5783.5 10188.3 10236.9 10249.8 10251.9 10327.0 9633.4 10051.5 10078.6 10232.3 10234.1 6797.9 7006.2 7270.9 7465.3 7470.5 7955.6 8154.2 8251.3 8404.4 8415.9 7379.3 7500.5 7513.7 7564.7 7668.7 6176.4 7314.2 7322.7 7337.2 7377.8 6697.0 6741.8 6813.6 6845.0 6847.0 4890.3 4912.8 4925.5 4978.3 5060.1 4879.2 4904.8 4992.2 5048.4 5070.8 03:20:39 INFO - PROCESS | 1941 | 03:20:39 INFO - PROCESS | 1941 | [#4] prototype.html Cycles:180 Average:4430.03 Median:5074.99 stddev:1370.08 (27.0%) stddev-sans-first:1338.42 03:20:39 INFO - PROCESS | 1941 | Values: 301.5 305.4 313.5 319.4 320.6 5103.3 5258.4 5285.9 5321.5 5406.4 4566.9 5013.5 5207.2 5208.3 5232.9 4001.4 4043.4 4058.9 4061.3 4097.5 3293.1 3523.4 3879.3 4035.7 4080.3 3362.4 3988.5 3998.3 4029.4 4037.3 4019.5 4035.9 4044.1 4055.7 4063.7 2918.3 3081.8 3491.4 3551.3 3579.8 4730.4 5033.4 5075.0 5167.1 5168.6 3747.7 3904.4 4451.2 4501.8 4524.0 5511.4 5520.8 5544.0 5572.3 5585.7 3181.3 3254.5 3463.2 3753.7 3778.0 5190.2 5597.6 5766.3 5959.0 5969.4 3252.7 3901.9 3906.4 3930.8 3933.7 3335.7 3956.2 3975.5 3979.3 3988.0 3960.1 4225.0 4511.0 4556.6 4561.5 5576.4 5607.4 5614.6 5615.5 5660.2 1084.7 1122.4 1143.0 1201.4 1325.4 830.5 836.9 857.6 890.9 910.5 4588.7 5213.4 5214.8 5227.0 5243.0 5006.5 5528.7 5736.5 5772.6 5778.5 5136.2 5144.3 5146.2 5178.3 5219.3 2964.7 2975.4 2993.9 3029.5 3041.2 4860.4 4937.3 5512.3 5632.2 5665.5 4747.2 4819.0 5744.2 5777.9 5809.3 4310.4 4669.1 4819.8 5310.0 5573.9 4846.9 5535.8 5602.1 5631.4 5649.3 5090.9 5308.9 5376.7 5596.9 5643.3 5624.7 5825.2 5866.6 5886.0 5904.6 3184.2 3397.2 3770.8 3776.8 3836.6 5088.8 5360.9 5564.4 5628.5 5731.6 5089.9 5256.9 5503.4 5529.1 5593.2 4602.6 4749.4 5075.0 5135.7 5451.5 4390.5 4412.0 4675.4 4829.0 5344.9 5218.8 5303.4 5472.4 5512.7 5535.8 5120.8 5457.8 5458.6 5462.3 5468.3 03:20:39 INFO - PROCESS | 1941 | 03:20:39 INFO - PROCESS | 1941 | [#5] yui.html Cycles:180 Average:24801.68 Median:3732.81 stddev:39426.50 (1056.2%) stddev-sans-first:39495.46 03:20:39 INFO - PROCESS | 1941 | Values: 668.7 750.7 776.6 831.1 844.7 15000.8 15604.4 15768.8 15774.9 15775.1 13695.4 14073.5 14149.9 14174.4 14455.8 850.3 881.2 883.7 884.5 885.3 679.3 700.3 704.8 867.1 873.3 729.6 730.6 896.4 938.3 949.5 921.5 923.6 924.3 925.2 926.5 681.7 682.1 682.2 684.7 686.4 11948.8 14641.9 14819.0 15354.9 15438.6 14255.9 14603.5 14632.6 14752.0 14790.9 11770.6 11780.5 11831.7 12096.9 12224.6 3534.9 3793.8 3827.1 3840.1 3973.0 119150.4 119732.4 123086.0 126178.4 128768.1 11091.6 11239.7 11294.7 11418.1 11543.7 22810.0 22856.1 22856.8 22858.0 22860.2 7866.2 8063.2 8141.8 8205.5 8215.1 4285.5 4399.8 4517.6 4543.9 4588.5 3609.6 3682.2 3728.8 3736.8 3737.6 576.7 655.2 655.5 663.5 676.9 606.6 635.3 642.3 668.2 683.6 7993.6 8484.3 9314.0 9400.5 9471.4 585.8 635.9 650.0 670.5 690.7 4038.3 4314.1 4388.7 4669.7 4699.5 83898.6 95762.0 133202.0 143499.0 152689.0 132877.3 133552.0 136668.0 137229.6 138983.0 113806.0 114418.0 116423.7 116920.0 117784.0 9043.9 9133.0 9590.0 9618.7 9654.7 47849.8 53620.0 54324.0 54338.2 64505.0 91660.6 93654.0 93908.0 95386.5 95517.1 10809.7 13217.3 13221.1 13586.9 13817.9 14624.5 14696.3 15033.5 15395.4 15427.6 14028.9 14167.1 14273.6 14326.1 14636.7 13772.1 13869.5 14015.7 14035.1 14271.1 13461.9 13522.1 13945.7 14124.6 14128.7 11231.4 11430.6 11568.7 11673.6 11888.9 8876.9 10657.4 11386.4 11679.4 11698.0 03:20:39 INFO - PROCESS | 1941 | -------- Summary: end -------- 03:20:39 INFO - PROCESS | 1941 | 03:20:40 INFO - TEST-INFO | 1941: exit 0 03:20:40 INFO - TEST-OK | dromaeo_css | took 1117625ms 03:20:40 INFO - TEST-START | kraken 03:20:40 INFO - operating with platform_type : mac_ 03:20:40 INFO - Initialising browser for kraken test... 03:20:40 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpLJAqGt/profile http://localhost:49710/getInfo.html) 03:20:42 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 03:20:42 INFO - PROCESS | 1947 | colorDepth:24 03:20:42 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 03:20:42 INFO - PROCESS | 1947 | __metrics 03:20:42 INFO - PROCESS | 1947 | 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" 03:20:42 INFO - PROCESS | 1947 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:20:43 INFO - TEST-INFO | 1947: exit 0 03:20:43 INFO - Browser initialized. 03:20:43 INFO - Running cycle 1/1 for kraken test... 03:20:43 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpLJAqGt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:20:55 INFO - PROCESS | 1948 | RSS: Main: 232132608 03:20:55 INFO - PROCESS | 1948 | 03:20:58 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49710/tests/kraken/driver.html?testName=audio-beat-detection) 03:20:59 INFO - PROCESS | 1948 | RSS: Main: 291618816 03:20:59 INFO - PROCESS | 1948 | 03:21:01 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49710/tests/kraken/driver.html?testName=audio-dft) 03:21:02 INFO - PROCESS | 1948 | RSS: Main: 287375360 03:21:02 INFO - PROCESS | 1948 | 03:21:05 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49710/tests/kraken/driver.html?testName=audio-fft) 03:21:06 INFO - PROCESS | 1948 | RSS: Main: 376242176 03:21:06 INFO - PROCESS | 1948 | 03:21:08 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49710/tests/kraken/driver.html?testName=audio-oscillator) 03:21:09 INFO - PROCESS | 1948 | RSS: Main: 279638016 03:21:09 INFO - PROCESS | 1948 | 03:21:11 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49710/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:21:11 INFO - PROCESS | 1948 | RSS: Main: 283201536 03:21:11 INFO - PROCESS | 1948 | 03:21:14 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49710/tests/kraken/driver.html?testName=imaging-darkroom) 03:21:15 INFO - PROCESS | 1948 | RSS: Main: 322797568 03:21:15 INFO - PROCESS | 1948 | 03:21:18 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49710/tests/kraken/driver.html?testName=imaging-desaturate) 03:21:18 INFO - PROCESS | 1948 | RSS: Main: 323354624 03:21:18 INFO - PROCESS | 1948 | 03:21:21 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49710/tests/kraken/driver.html?testName=json-parse-financial) 03:21:22 INFO - PROCESS | 1948 | RSS: Main: 323522560 03:21:22 INFO - PROCESS | 1948 | 03:21:23 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49710/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:21:24 INFO - PROCESS | 1948 | RSS: Main: 298270720 03:21:24 INFO - PROCESS | 1948 | 03:21:26 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:21:26 INFO - PROCESS | 1948 | RSS: Main: 304177152 03:21:26 INFO - PROCESS | 1948 | 03:21:29 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:21:29 INFO - PROCESS | 1948 | RSS: Main: 303771648 03:21:29 INFO - PROCESS | 1948 | 03:21:32 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:21:33 INFO - PROCESS | 1948 | RSS: Main: 319541248 03:21:33 INFO - PROCESS | 1948 | 03:21:36 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:21:37 INFO - PROCESS | 1948 | RSS: Main: 315772928 03:21:37 INFO - PROCESS | 1948 | 03:21:38 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:49710/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49710/tests/kraken/driver.html?testName=ai-astar) 03:21:39 INFO - PROCESS | 1948 | RSS: Main: 360030208 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | __start_tp_report 03:21:39 INFO - PROCESS | 1948 | _x_x_mozilla_page_load 03:21:39 INFO - PROCESS | 1948 | _x_x_mozilla_page_load_details 03:21:39 INFO - PROCESS | 1948 | |i|pagename|runs| 03:21:39 INFO - PROCESS | 1948 | |0;ai-astar;115;114;116;114;119;166;166;113;112;124 03:21:39 INFO - PROCESS | 1948 | |1;audio-beat-detection;145;115;136;195;107;130;146;138;168;137 03:21:39 INFO - PROCESS | 1948 | |2;audio-dft;186;186;186;185;187;183;186;183;183;183 03:21:39 INFO - PROCESS | 1948 | |3;audio-fft;77;74;77;154;78;49;54;69;155;72 03:21:39 INFO - PROCESS | 1948 | |4;audio-oscillator;83;83;134;71;73;73;119;69;70;117 03:21:39 INFO - PROCESS | 1948 | |5;imaging-gaussian-blur;86;83;87;84;86;84;87;83;86;82 03:21:39 INFO - PROCESS | 1948 | |6;imaging-darkroom;142;113;96;167;97;96;97;98;96;96 03:21:39 INFO - PROCESS | 1948 | |7;imaging-desaturate;83;84;88;87;86;87;87;86;85;85 03:21:39 INFO - PROCESS | 1948 | |8;json-parse-financial;55;54;55;51;51;55;56;55;54;52 03:21:39 INFO - PROCESS | 1948 | |9;json-stringify-tinderbox;67;65;71;80;74;71;70;69;71;70 03:21:39 INFO - PROCESS | 1948 | |10;stanford-crypto-aes;87;84;86;84;86;88;86;83;85;86 03:21:39 INFO - PROCESS | 1948 | |11;stanford-crypto-ccm;140;158;127;147;151;156;145;143;193;148 03:21:39 INFO - PROCESS | 1948 | |12;stanford-crypto-pbkdf2;169;168;168;168;169;172;167;169;168;184 03:21:39 INFO - PROCESS | 1948 | |13;stanford-crypto-sha256-iterative;68;68;68;67;68;66;66;69;67;66 03:21:39 INFO - PROCESS | 1948 | __end_tp_report 03:21:39 INFO - PROCESS | 1948 | __start_cc_report 03:21:39 INFO - PROCESS | 1948 | _x_x_mozilla_cycle_collect,2084 03:21:39 INFO - PROCESS | 1948 | __end_cc_report 03:21:39 INFO - PROCESS | 1948 | __startTimestamp1473070899015__endTimestamp 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | ------- Summary: start ------- 03:21:39 INFO - PROCESS | 1948 | Number of tests: 14 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | [#0] ai-astar Cycles:10 Average:125.90 Median:117.50 stddev:21.41 (18.2%) stddev-sans-first:22.35 03:21:39 INFO - PROCESS | 1948 | Values: 115.0 114.0 116.0 114.0 119.0 166.0 166.0 113.0 112.0 124.0 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:141.50 stddev:25.13 (17.8%) stddev-sans-first:26.63 03:21:39 INFO - PROCESS | 1948 | Values: 145.0 115.0 136.0 195.0 107.0 130.0 146.0 138.0 168.0 137.0 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | [#2] audio-dft Cycles:10 Average:184.80 Median:186.00 stddev:1.62 (0.9%) stddev-sans-first:1.66 03:21:39 INFO - PROCESS | 1948 | Values: 186.0 186.0 186.0 185.0 187.0 183.0 186.0 183.0 183.0 183.0 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | [#3] audio-fft Cycles:10 Average:85.90 Median:73.00 stddev:37.47 (51.3%) stddev-sans-first:39.60 03:21:39 INFO - PROCESS | 1948 | Values: 77.0 74.0 77.0 154.0 78.0 49.0 54.0 69.0 155.0 72.0 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:72.00 stddev:24.44 (34.0%) stddev-sans-first:25.82 03:21:39 INFO - PROCESS | 1948 | Values: 83.0 83.0 134.0 71.0 73.0 73.0 119.0 69.0 70.0 117.0 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | [#5] imaging-gaussian-blur Cycles:10 Average:84.80 Median:86.00 stddev:1.81 (2.1%) stddev-sans-first:1.87 03:21:39 INFO - PROCESS | 1948 | Values: 86.0 83.0 87.0 84.0 86.0 84.0 87.0 83.0 86.0 82.0 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | [#6] imaging-darkroom Cycles:10 Average:109.80 Median:96.00 stddev:24.83 (25.9%) stddev-sans-first:23.44 03:21:39 INFO - PROCESS | 1948 | Values: 142.0 113.0 96.0 167.0 97.0 96.0 97.0 98.0 96.0 96.0 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:86.50 stddev:1.55 (1.8%) stddev-sans-first:1.27 03:21:39 INFO - PROCESS | 1948 | Values: 83.0 84.0 88.0 87.0 86.0 87.0 87.0 86.0 85.0 85.0 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:55.00 stddev:1.81 (3.3%) stddev-sans-first:1.87 03:21:39 INFO - PROCESS | 1948 | Values: 55.0 54.0 55.0 51.0 51.0 55.0 56.0 55.0 54.0 52.0 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:4.05 (5.7%) stddev-sans-first:4.06 03:21:39 INFO - PROCESS | 1948 | Values: 67.0 65.0 71.0 80.0 74.0 71.0 70.0 69.0 71.0 70.0 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:86.00 stddev:1.51 (1.8%) stddev-sans-first:1.50 03:21:39 INFO - PROCESS | 1948 | Values: 87.0 84.0 86.0 84.0 86.0 88.0 86.0 83.0 85.0 86.0 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | [#11] stanford-crypto-ccm Cycles:10 Average:150.80 Median:149.50 stddev:17.19 (11.5%) stddev-sans-first:17.78 03:21:39 INFO - PROCESS | 1948 | Values: 140.0 158.0 127.0 147.0 151.0 156.0 145.0 143.0 193.0 148.0 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.20 Median:169.00 stddev:5.03 (3.0%) stddev-sans-first:5.32 03:21:39 INFO - PROCESS | 1948 | Values: 169.0 168.0 168.0 168.0 169.0 172.0 167.0 169.0 168.0 184.0 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - PROCESS | 1948 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:1.06 (1.6%) stddev-sans-first:1.09 03:21:39 INFO - PROCESS | 1948 | Values: 68.0 68.0 68.0 67.0 68.0 66.0 66.0 69.0 67.0 66.0 03:21:39 INFO - PROCESS | 1948 | -------- Summary: end -------- 03:21:39 INFO - PROCESS | 1948 | 03:21:39 INFO - TEST-INFO | 1948: exit 0 03:21:39 INFO - TEST-OK | kraken | took 59768ms 03:21:39 INFO - SUITE-END | took 1177s 03:21:40 INFO - Completed test suite (00:19:37) 03:21:40 INFO - javascript benchmark 03:21:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2801.3556881917157, 3303.9008829735108, 3307.3550657621768, 3382.509536117265, 3410.7612467127296, 5513.7794488220425, 5631.774729010835, 5682.6306290091115, 5790.739416726252, 5813.5349172066235, 5664.320281566218, 5687.232223649806, 5781.075027995522, 5795.420457954203, 5872.383399743029, 5109.233614957768, 5149.227615857614, 5183.948160518385, 5208.47915208478, 5263.131583288753, 5316.9734151329185, 5316.9734151329185, 5324.600654950875, 5327.840164795043, 5368.033753924292, 4955.1080805455, 5135.922961155586, 5154.123798954187, 5179.663321884103, 5231.555317797973, 5186.818461353872, 5231.3199284092825, 5231.633785635007, 5233.816816411408, 5234.1887007513515, 5064.696118232918, 5131.0, 5194.4026436959475, 5241.737913104368, 5297.205419187114, 4701.95298047022, 5673.404292549306, 5701.372848986608, 5706.657600543939, 5725.914111288334, 6244.157038799749, 6247.344028876993, 6255.874882502325, 6261.968690156566, 6262.87474250517, 6075.9696201519155, 6122.785702500436, 6209.565330426872, 6276.184096067527, 6277.121203031579, 5746.109353050242, 5808.651480911159, 5823.5341172706085, 5827.3007239131575, 5873.353931067579, 6233.470155036873, 6246.812595622138, 6264.718087686065, 6281.026440901667, 6293.496520278367, 6010.699465026732, 6029.3970602940235, 6098.237720284965, 6115.544587952024, 6143.447089761943, 6241.469475094577, 6250.4374606285655, 6267.780627678009, 6277.780277690259, 6281.654509002049, 6160.9383906160365, 6162.815115546541, 6237.906431403532, 6245.469135123565, 6255.530835187379, 5606.55147588192, 5805.303363596396, 6117.227785852181, 6144.910730351702, 6209.06863970399, 4870.439899411542, 4925.21196608541, 4938.308636790852, 4981.676191047571, 4986.227134794112, 4403.581659742319, 4407.867763967086, 4412.360207769855, 4412.514623391425, 4453.933191002137, 5609.915851262234, 5638.943610563924, 5708.771649134072, 5724.341700704473, 5745.8563535911935, 6178.196834411498, 6242.250929888442, 6265.091561723524, 6270.0, 6276.403741644536, 5682.488576028095, 5743.0811070228565, 5759.539236861041, 5760.337561180434, 5785.30576330843, 5299.68201907879, 5389.730513474389, 5440.727963601883, 5462.071447853875, 5476.04169270384, 6190.350013248544, 6230.283517395557, 6244.375562443719, 6261.217347831521, 6305.211848518935, 6235.875282494416, 6239.937600624117, 6242.812715618538, 6251.156093927261, 6253.187085678833, 6279.30927597973, 6290.654014029089, 6300.905486417799, 6317.526185536011, 6318.399752023551, 6004.309504407049, 6103.580118178587, 6145.555794388226, 6145.692715364304, 6238.313785483502, 6199.938000619936, 6257.530685198537, 6263.56155069159, 6275.215598050244, 6283.905741413791, 6099.756009759737, 6105.603135796159, 6108.08378743192, 6111.84720381994, 6123.969380153071, 4929.359183306147, 5943.762249509971, 6018.578699490994, 6021.518278537794, 6104.125697713769, 5962.880742385041, 5968.313643931004, 5970.910436343372, 5972.552058595709, 6017.127516510169, 6071.848203794763, 6119.969400152971, 6123.112148738497, 6124.173236613, 6127.050307202391, 5740.741666624928, 5899.351071382059, 5926.555508336805, 5964.284285885721, 6036.517078633787], "value": 5679.258447479341, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.50911542879976, 520.3547600974788, 532.0980937311259, 544.6378158524581, 551.4127454261209, 30320.393592128145, 33446.99999999997, 39661.603383966154, 40963.0, 43419.0, 42459.78770106153, 43030.0, 43036.13927721444, 43068.78465607676, 43078.78460607692, 9624.85562716558, 9809.0, 9814.312998090136, 9828.508574571242, 9835.11483966443, 8722.171393717586, 8851.026387097414, 11903.58337458189, 12151.210171338835, 12187.598426181014, 10697.358158510475, 12013.699657508545, 12146.17807328899, 12152.39238038099, 12156.331401772899, 12166.90497855193, 12249.755004899896, 12249.755004899942, 12270.693232669255, 12297.815532767016, 9978.451185184811, 11922.105842061794, 11997.460114294878, 12000.97991670709, 12011.978981786559, 39727.0, 40015.39976900349, 40057.0, 40096.79951600223, 40102.0, 2938.2801213702564, 3469.2887957968555, 3751.043483911585, 3777.4145007523603, 4023.0143614814265, 30155.0, 31148.844255778804, 31192.064238072893, 31223.0, 31459.056228313188, 3145.465270903952, 3241.205904553376, 3281.606207255121, 3311.0, 3313.6355000949875, 348021.0, 348862.0, 349079.0, 350388.0, 353022.0, 56795.14807277894, 56851.71574142103, 56903.14645280324, 57140.0, 57232.71383643055, 58385.0, 59331.813363732486, 59358.70320648369, 59407.0, 59476.70261648664, 13858.861411385958, 16288.348466061225, 16299.266533006043, 16308.347666093223, 16328.0, 11420.771584568261, 11686.824697629543, 12755.362231888368, 12859.42132604035, 12876.22742635445, 12459.377031148406, 14737.778933316009, 15094.867884908675, 15146.9397142199, 15461.613459663598, 6577.210734711865, 6600.86798264032, 6605.966970165118, 6640.701168447432, 6645.468362530986, 834.929031032369, 955.2358113509165, 959.2949182350967, 975.9560819763128, 1047.8218702820539, 28511.0, 29641.0, 29725.83838262645, 30341.848290758404, 30452.543211851396, 867.444835305405, 997.9820583005439, 1005.050227534987, 1036.3988886445843, 1134.2627292260097, 13672.65818354549, 15408.460703875535, 15532.068075915482, 15676.608084797972, 15825.0, 671846.0, 677111.0, 678258.0, 679262.0, 679986.2001379923, 56899.71550142223, 57130.428695713344, 57198.570035748606, 57208.71395642995, 57584.424155758745, 1180.5985964771846, 1200.8378868852767, 1306.888914442283, 1334.7130366971148, 1349.426493740137, 29872.13797204894, 30549.93075242356, 30710.0, 30796.0, 31321.686783131878, 46579.53420465752, 46935.0, 46967.76516117398, 47079.0, 47125.0, 530215.0, 533206.3415877955, 534371.0, 535133.0, 536263.0, 25265.49469010646, 25937.573433460588, 25961.014483334657, 26742.866285669224, 38504.0, 46015.0, 46136.53863461322, 46429.767851160526, 46736.82105631569, 47438.52561474341, 33747.0, 37864.0, 38294.42558361682, 38980.6101938977, 39598.0, 33230.33539329248, 35738.92783216508, 37658.2468350626, 38428.42357364698, 38846.834594962194, 36506.26987460289, 37005.25989480249, 37120.6287937128, 37555.0, 37805.0, 26909.327266818487, 32161.356772863943, 33328.50007249942, 33505.827296044525, 33556.83221583877, 22297.219597314015, 24854.254372368134, 25035.87338569805, 25234.495310094062, 25397.746022539537], "value": 19737.80622403485, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.4980327934333, 371.6933529837884, 376.7569917403275, 385.83216300909106, 408.21215054943946, 15636.687266254668, 15786.131762753059, 20330.796692033076, 20598.0, 20753.584928301425, 19678.901605491952, 19954.401367958984, 20500.794992050076, 20515.281965131224, 20561.869097199684, 5369.275147855029, 5468.617196796206, 5558.0, 5579.023670857604, 6032.577719559633, 3939.940900886489, 6042.395760423967, 6110.2973158086725, 6130.540209484284, 6134.662593557353, 6465.773697920574, 6771.187457505106, 6808.489363297747, 6841.9657901710425, 6873.243943166248, 5974.6713930733795, 6013.669248191348, 6082.452579267843, 6597.703103360336, 6809.5233333666665, 5942.762289508414, 5943.197668314765, 5944.7324870380935, 5946.70266486674, 5950.940490595082, 12959.935200324033, 13012.674683132902, 13139.343032848416, 13296.468141274434, 13322.267275299855, 2218.6228341182036, 2250.8762018089, 2258.683784270187, 2694.3398867277447, 2892.7975041747086, 9868.851967220497, 9869.0, 9875.358101723365, 9919.603215871357, 9940.90059099407, 1627.617509885176, 1785.526835388623, 1812.18451696737, 1820.226403778402, 1876.5496280892487, 94871.0, 125089.0, 125148.36238919703, 125235.0, 127650.0, 11259.662210133709, 11379.544818207345, 11399.316041037564, 11501.309921404743, 11602.825957610643, 14511.492097776527, 14529.128252304896, 14530.564083077525, 14549.92725036368, 14553.27233638314, 7160.641967901584, 8359.331253499706, 8633.39566230358, 8669.349798764992, 8772.78068048291, 8530.10433904431, 8535.829283414421, 8536.402451828313, 8707.47755134694, 8708.390412671179, 4397.802098905557, 4490.046829629056, 4494.6852371028945, 4576.725396476222, 4579.175748365326, 1750.5536088297404, 1805.4624049659988, 1857.0, 1898.8511950270165, 1911.2928216559962, 678.4402867634221, 741.3031750154837, 749.5352881213573, 939.6852054561662, 1022.4734261855151, 17266.827331726774, 18428.170732317078, 20783.168673253203, 21130.0, 21452.0, 809.9028116626042, 869.799946012408, 902.3142411766974, 922.9769255768525, 951.4909523404945, 8252.628631711588, 8860.0, 10921.617743379102, 11070.225084244186, 11189.216754827085, 576737.0, 590983.0, 596540.0, 604392.0, 604428.0, 115082.42458787652, 134435.65564344233, 140514.29742851426, 141629.0, 143883.0, 954.3462728031182, 963.6579014449769, 972.7130496503548, 976.5019839881569, 991.0535438655922, 13833.859178889994, 15353.692926141637, 19439.902800485906, 19500.401294111107, 19623.411297661092, 51240.487595125065, 52330.47669523256, 53198.0, 53549.464505354445, 53766.0, 74361.0, 74453.62773186316, 75384.0, 77428.83856742266, 77751.61124194342, 6606.8348291293105, 7863.882041769492, 8086.7169649062, 8113.35093192555, 8132.878006829784, 11087.72280692989, 11184.328940263611, 12169.9655529281, 12689.784906314273, 12895.16181448203, 10751.784964300827, 11139.554417823196, 11172.832407514057, 11269.943650281695, 11280.774384512428, 8556.614952327285, 10599.523021463898, 10753.784924301313, 11118.944405277922, 11186.216964812711, 9425.905740942502, 11040.834387484354, 11326.490307935997, 11354.659360219206, 11413.258138220988, 6620.205575330991, 7289.8177545559665, 7870.763877083697, 7998.760037198894, 8305.584720764058, 9338.0, 10499.212559057949, 10801.351918884891, 11048.171387146156, 11104.666859994213], "value": 9247.168712451647, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1505.9322330495124, 1539.5766164304816, 1731.1517356495322, 1883.7456943312648, 1911.6941289393692, 8407.327413806895, 8897.955510222457, 8910.0, 8912.46525208488, 8914.687985920495, 7791.454598178116, 8013.358931285497, 8495.872561911576, 8705.956470217656, 8715.389922705413, 2363.952720945584, 2403.483251101013, 2405.5910495215767, 2405.9518809623796, 2407.2176542623715, 2365.2667673021333, 2385.3798012516695, 2413.263954493879, 2413.51729654069, 2419.6128619420897, 2315.7684231576877, 2331.347222777625, 2346.9295921122393, 2351.929442116739, 2354.6585745066955, 2363.2201373546686, 2382.6783384243076, 2383.7854593086704, 2387.450886296153, 2395.952080958371, 2353.7410884802657, 2368.7512811154925, 2375.2043065573052, 2375.7743014413604, 2376.512814872964, 7469.813254668677, 8820.8676869847, 8919.464832110094, 8942.37403381757, 8945.15021072997, 5233.659812112201, 5395.406505284415, 5412.431694672042, 5418.106012507931, 5418.45815418455, 3602.35157671619, 3625.075605720551, 3691.7046636269033, 3697.131174174067, 3697.3899306614376, 934.5747684803466, 1073.420353009374, 1081.6376513867785, 1096.6764804382726, 1112.7830073135742, 11653.59212427561, 11705.882941170565, 11734.647960561111, 11750.882491175149, 11753.764924701458, 4547.181507328646, 4650.5349465053905, 4833.4441539223435, 4835.540623640749, 4838.104950584153, 9881.80236395268, 9891.802163956681, 9896.010398960047, 9912.0, 9947.751306217258, 5358.33020872391, 5361.919571206435, 5362.731863406814, 5373.435789242151, 5376.220448035091, 4260.297050986553, 4394.582514661102, 4550.522195169526, 4922.286268491049, 4946.8763280918265, 3858.131920317906, 3891.2412079644196, 4018.4374187613757, 4027.013381721497, 4037.4953130858644, 1082.5236895765838, 1297.1309222820732, 1305.5626365167775, 1313.6715821044738, 1317.7298653775954, 755.6750597243129, 766.8849672549162, 832.1636755061246, 891.4695756025154, 892.4912799704236, 5494.0, 5502.147167189092, 5705.914411283834, 5774.220480235199, 5902.527797776168, 841.3059226138461, 864.4337958637103, 865.1780808232205, 906.4063038709655, 915.7023967210683, 3523.594786599574, 3628.020434482675, 3701.574318953301, 3767.453719210699, 3865.2462769759627, 9830.803383932423, 10053.894071652112, 10431.426271554985, 10456.843147352643, 10719.303245289031, 10563.207759418074, 10703.250772445854, 10726.088282495919, 10785.514651840685, 10797.46012699362, 8140.430169888169, 8289.544075075808, 8293.543855088028, 8304.916950830415, 8305.0, 5600.383957764561, 5660.632058916157, 5737.196792448977, 5769.826905192851, 5783.537317014713, 10188.28681992283, 10236.948815255875, 10249.847532018359, 10251.948740256252, 10327.0, 9633.421994680342, 10051.497425128568, 10078.596856125672, 10232.283740138417, 10234.130098941376, 6797.898031529432, 7006.229314775269, 7270.854582908207, 7465.328120469184, 7470.4770666052855, 7955.562444065632, 8154.184581542036, 8251.257386835214, 8404.369672274726, 8415.873761893454, 7379.335859772432, 7500.474966752275, 7513.661885215288, 7564.659590318559, 7668.693252269847, 6176.4441200292185, 7314.155851922664, 7322.670479828527, 7337.156227033959, 7377.77866664001, 6697.0, 6741.831454213684, 6813.557118787263, 6845.007473916354, 6846.965765171142, 4890.315355850113, 4912.803487860446, 4925.482824303396, 4978.327925730125, 5060.07717067512, 4879.194932836042, 4904.754762261801, 4992.176290911959, 5048.394192696753, 5070.797168113234], "value": 4556.349017109387, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.48747129879195, 305.3831260853077, 313.47805903171223, 319.4282234799709, 320.5784393522518, 5103.336566246383, 5258.44786297439, 5285.92071118933, 5321.547668448182, 5406.378266499349, 4566.885827854306, 5013.548780609744, 5207.192885102806, 5208.333333333335, 5232.947670523294, 4001.439438618941, 4043.4137050127715, 4058.874666506788, 4061.289274376987, 4097.467329247201, 3293.090007795485, 3523.401021826283, 3879.3405121129335, 4035.6973227007943, 4080.306347920846, 3362.411577973857, 3988.521377434712, 3998.3402738548107, 4029.355303151496, 4037.253108174997, 4019.5328705022603, 4035.899102522431, 4044.1102957349335, 4055.6958228133008, 4063.654589359878, 2918.3433727411375, 3081.828905016102, 3491.3715531204375, 3551.2897420516, 3579.8210089495683, 4730.432348118214, 5033.446320904734, 5074.99537682617, 5167.139829661319, 5168.586513078944, 3747.7001839852746, 3904.3557812960826, 4451.217988757026, 4501.774911254424, 4524.02733412318, 5511.4213007634025, 5520.751566179491, 5543.972280138614, 5572.300448363248, 5585.709858957808, 3181.3319202967405, 3254.5118232265113, 3463.203463203452, 3753.680937120348, 3777.97994541475, 5190.171356610694, 5597.580181486404, 5766.336871259817, 5959.0, 5969.432903874125, 3252.7397808175288, 3901.8634347797692, 3906.43356713275, 3930.8427662893737, 3933.724639275223, 3335.733141348735, 3956.219701134501, 3975.5229372475487, 3979.343408337592, 3987.9661372700134, 3960.089179488734, 4224.978875105605, 4510.977445112819, 4556.610233878699, 4561.452625684939, 5576.386597474275, 5607.42058653016, 5614.550835933115, 5615.4946054854445, 5660.179274005246, 1084.6745976207105, 1122.405125283601, 1142.9657110286705, 1201.3632774629461, 1325.3505782166862, 830.5141492226994, 836.9245519507404, 857.6312185760058, 890.944231086165, 910.4855756497558, 4588.678792484494, 5213.393303348325, 5214.817481388133, 5227.006868694936, 5243.030039442715, 5006.499350064964, 5528.695921724344, 5736.5123964462655, 5772.5670574707065, 5778.451047150514, 5136.203888397305, 5144.254083157996, 5146.202338637517, 5178.326817513699, 5219.321488206509, 2964.7479964202853, 2975.375171214026, 2993.9192261378134, 3029.4849875521213, 3041.1788817019724, 4860.444657709499, 4937.308776771255, 5512.310961129859, 5632.183333416631, 5665.518430933417, 4747.169245382044, 4818.951810481921, 5744.224529688522, 5777.88444231113, 5809.302883653989, 4310.43964284641, 4669.089527542164, 4819.759012049383, 5310.017486884836, 5573.854066184467, 4846.947519967126, 5535.7785688572, 5602.131669591057, 5631.380548139783, 5649.265595472562, 5090.905455327123, 5308.893822123613, 5376.650517716336, 5596.888062238651, 5643.294588176478, 5624.746886390204, 5825.2135961644635, 5866.592017915755, 5886.0, 5904.586678932434, 3184.1721152500054, 3397.1676939150066, 3770.849166033328, 3776.8489260429274, 3836.597157298555, 5088.796448142181, 5360.909245394221, 5564.360098588714, 5628.521575666115, 5731.5701322400155, 5089.9056702809075, 5256.894862102813, 5503.449655034625, 5529.060059789765, 5593.244911936837, 4602.562756538124, 4749.35883655702, 5074.974625126851, 5135.717535535581, 5451.454854514675, 4390.473143222834, 4411.985243393973, 4675.3688252086895, 4829.044237083986, 5344.866378340573, 5218.791248350023, 5303.36359636846, 5472.398036216093, 5512.66483777309, 5535.806246781343, 5120.795168193231, 5457.808976685797, 5458.563314934875, 5462.289902312833, 5468.316460442445], "value": 3992.239247663103, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.7024274197981, 750.6622020090961, 776.6078130544078, 831.1480732249444, 844.6663567890683, 15000.849991500081, 15604.375824966988, 15768.763468547966, 15774.921125394372, 15775.1323677198, 13695.383707733152, 14073.507427240049, 14149.858501414983, 14174.43302267908, 14455.78316325256, 850.336737344872, 881.2333270055029, 883.6686242659002, 884.5046773806657, 885.3183049052226, 679.259607028339, 700.3136925812681, 704.8414106825949, 867.0852250875324, 873.2795443758893, 729.5768454296496, 730.5580124024968, 896.4307664632939, 938.3103418987039, 949.4920217683541, 921.4517362169565, 923.6259314977444, 924.306769922558, 925.1627277314042, 926.5274709897933, 681.6932380428777, 682.1405029662611, 682.2324884504932, 684.7329541478828, 686.3719696477717, 11948.761024779456, 14641.853581464156, 14819.0, 15354.923225383913, 15438.614034649223, 14255.928720356333, 14603.48887788933, 14632.634184145376, 14751.99203187251, 14790.852091479055, 11770.646880593597, 11780.528778848835, 11831.704207394798, 12096.939515302367, 12224.633261002184, 3534.876279330211, 3793.7534060285993, 3827.119762454623, 3840.116773142165, 3973.0, 119150.40424797821, 119732.40133799275, 123086.0, 126178.36910815479, 128768.06847897288, 11091.611793587186, 11239.719007024727, 11294.661160165208, 11418.086553075735, 11543.653690389134, 22810.0, 22856.085756569883, 22856.771432285797, 22858.0, 22860.199893003664, 7866.213378662088, 8063.193680632007, 8141.755747327589, 8205.548694821722, 8215.137410571924, 4285.46431696038, 4399.780010999437, 4517.593416592523, 4543.931841022388, 4588.541145885385, 3609.5848977367937, 3682.1899182179877, 3728.794916279631, 3736.831842567091, 3737.588865224823, 576.6684156609966, 655.1515787055774, 655.4919937048825, 663.5388405058535, 676.9018492318586, 606.6451126091198, 635.3297271378648, 642.2870613618879, 668.19482523559, 683.5932620091038, 7993.600319983978, 8484.325139825693, 9313.953430232941, 9400.529973501298, 9471.422603298419, 585.783260193731, 635.8537536366662, 649.9935000649933, 670.51722759613, 690.711822451125, 4038.333674943601, 4314.1371725655, 4388.71473354231, 4669.743164125938, 4699.459562150397, 83898.58050709829, 95762.0, 133202.0, 143499.0, 152689.0, 132877.33561332326, 133552.0, 136668.0, 137229.6125216838, 138983.0, 113806.0, 114418.0, 116423.67152657069, 116920.0, 117784.0, 9043.909560904307, 9133.041030691948, 9590.0, 9618.711438656572, 9654.71035868925, 47849.76075119602, 53620.0, 54324.0, 54338.18492722692, 64505.0, 91660.62509062502, 93654.0, 93908.0, 95386.49405317583, 95517.08965820506, 10809.67570972872, 13217.339133043502, 13221.074524783173, 13586.93206533961, 13817.894568434702, 14624.454628049045, 14696.265186740406, 15033.473828415952, 15395.384184632489, 15427.61430964235, 14028.929855350658, 14167.149971001772, 14273.64315892111, 14326.140431573722, 14636.707265854837, 13772.10481318711, 13869.514566990107, 14015.719685606027, 14035.087719298213, 14271.14373137615, 13461.932690336485, 13522.121062131324, 13945.651358716113, 14124.646883827576, 14128.717425651224, 11231.38227397501, 11430.599929002445, 11568.710782230175, 11673.649790505959, 11888.940555297168, 8876.932751719409, 10657.360558366523, 11386.43067846621, 11679.357635330138, 11698.0], "value": 7856.804109543035, "unit": "score"}], "name": "dromaeo_css", "value": 7274.238866942908, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 116.0, 114.0, 119.0, 166.0, 166.0, 113.0, 112.0, 124.0], "value": 125.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 136.0, 195.0, 107.0, 130.0, 146.0, 138.0, 168.0, 137.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 186.0, 185.0, 187.0, 183.0, 186.0, 183.0, 183.0, 183.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 77.0, 154.0, 78.0, 49.0, 54.0, 69.0, 155.0, 72.0], "value": 85.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 134.0, 71.0, 73.0, 73.0, 119.0, 69.0, 70.0, 117.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 87.0, 84.0, 86.0, 84.0, 87.0, 83.0, 86.0, 82.0], "value": 84.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 113.0, 96.0, 167.0, 97.0, 96.0, 97.0, 98.0, 96.0, 96.0], "value": 109.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 88.0, 87.0, 86.0, 87.0, 87.0, 86.0, 85.0, 85.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 51.0, 51.0, 55.0, 56.0, 55.0, 54.0, 52.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 71.0, 80.0, 74.0, 71.0, 70.0, 69.0, 71.0, 70.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 84.0, 86.0, 88.0, 86.0, 83.0, 85.0, 86.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 158.0, 127.0, 147.0, 151.0, 156.0, 145.0, 143.0, 193.0, 148.0], "value": 150.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 168.0, 168.0, 169.0, 172.0, 167.0, 169.0, 168.0, 184.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 67.0, 68.0, 66.0, 66.0, 69.0, 67.0, 66.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1506.3, "alertThreshold": 2.0}]} 03:21:40 INFO - Return code: 0 03:21:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:21:40 INFO - # TBPL SUCCESS # 03:21:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:21:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:21:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:21:40 INFO - 2016-09-05 03:21:40.346 screenresolution[1952:12164] starting screenresolution argv=screenresolution get 03:21:40 INFO - 2016-09-05 03:21:40.349 screenresolution[1952:12164] Display 0: 1600x1200x32@60 03:21:40 INFO - 2016-09-05 03:21:40.355 screenresolution[1953:12167] starting screenresolution argv=screenresolution list 03:21:40 INFO - Available Modes on Display 0 03:21:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:21:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:21:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:21:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:21:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:21:40 INFO - Intel Iris: 03:21:40 INFO - Chipset Model: Intel Iris 03:21:40 INFO - Type: GPU 03:21:40 INFO - Bus: Built-In 03:21:40 INFO - VRAM (Dynamic, Max): 1536 MB 03:21:40 INFO - Vendor: Intel (0x8086) 03:21:40 INFO - Device ID: 0x0a2e 03:21:40 INFO - Revision ID: 0x0009 03:21:40 INFO - Displays: 03:21:40 INFO - Display: 03:21:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:21:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:21:40 INFO - Display Serial Number: 1600x1200 60 03:21:40 INFO - Main Display: Yes 03:21:40 INFO - Mirror: Off 03:21:40 INFO - Online: Yes 03:21:40 INFO - Rotation: Supported 03:21:40 INFO - Return code: 0 03:21:40 INFO - Running post-action listener: _resource_record_post_action 03:21:40 INFO - [mozharness: 2016-09-05 10:21:40.590929Z] Finished run-tests step (success) 03:21:40 INFO - Running post-run listener: _resource_record_post_run 03:21:40 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 1445888; Write bytes: 705710592; Read time: 20; Write time: 2093 03:21:40 INFO - TinderboxPrint: CPU usage
53.6% 03:21:40 INFO - TinderboxPrint: I/O read bytes / time
1,445,888 / 20 03:21:40 INFO - TinderboxPrint: I/O write bytes / time
705,710,592 / 2,093 03:21:40 INFO - TinderboxPrint: CPU idle
2,253.1 (46.4%) 03:21:40 INFO - TinderboxPrint: CPU system
975.7 (20.1%) 03:21:40 INFO - TinderboxPrint: CPU user
1,625.0 (33.5%) 03:21:40 INFO - TinderboxPrint: Swap in / out
407,322,624 / 0 03:21:40 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389689856; Write bytes: 384988672; Read time: 29466; Write time: 750 03:21:40 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1445888; Write bytes: 296547328; Read time: 20; Write time: 1293 03:21:41 INFO - Running post-run listener: _upload_blobber_files 03:21:41 INFO - Blob upload gear active. 03:21:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:21:41 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 03:21:41 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'] 03:21:41 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 03:21:41 INFO - (blobuploader) - INFO - Open directory for files ... 03:21:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:21:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:21:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:42 INFO - (blobuploader) - INFO - Done attempting. 03:21:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:21:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:21:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:43 INFO - (blobuploader) - INFO - Done attempting. 03:21:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:21:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:21:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:45 INFO - (blobuploader) - INFO - Done attempting. 03:21:45 INFO - (blobuploader) - INFO - Iteration through files over. 03:21:45 INFO - Return code: 0 03:21:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:21:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:21:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/288712a52b029e625d9e1655edeba7089536ca6003f667265bd999a79ce4e5d80b39de76c3ac28c240af24c6d573d1dd1a744ca6c5d94095665abf03a9a64385", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b65c40122b388c86ff3a538f70f00a5b9db4d1ec36278aaa09dbea228c71cadb47eb981f6ee177b0d64ab4264b44f176a06fdc75563721e61cdee978086e46bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/764e92f4410b181610191d133efb5b4628ed8b88a285cbc55d0ef506c05f951c3a1095aa95fa4f586bdcd3a1cc8c6d7ad3f7902b09aa1f638c2685f4b48c5076"} 03:21:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:21:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:21:45 INFO - Contents: 03:21:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/288712a52b029e625d9e1655edeba7089536ca6003f667265bd999a79ce4e5d80b39de76c3ac28c240af24c6d573d1dd1a744ca6c5d94095665abf03a9a64385", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b65c40122b388c86ff3a538f70f00a5b9db4d1ec36278aaa09dbea228c71cadb47eb981f6ee177b0d64ab4264b44f176a06fdc75563721e61cdee978086e46bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/764e92f4410b181610191d133efb5b4628ed8b88a285cbc55d0ef506c05f951c3a1095aa95fa4f586bdcd3a1cc8c6d7ad3f7902b09aa1f638c2685f4b48c5076"} 03:21:45 INFO - Running post-run listener: copy_logs_to_upload_dir 03:21:45 INFO - Copying logs to upload dir... 03:21:45 INFO - mkdir: /builds/slave/test/build/upload/logs 03:21:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.716179 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-05 03:21:45.793158) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-05 03:21:45.796312) ========= 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.WByNccnXgl/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.akZ61rclUa/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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/288712a52b029e625d9e1655edeba7089536ca6003f667265bd999a79ce4e5d80b39de76c3ac28c240af24c6d573d1dd1a744ca6c5d94095665abf03a9a64385", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b65c40122b388c86ff3a538f70f00a5b9db4d1ec36278aaa09dbea228c71cadb47eb981f6ee177b0d64ab4264b44f176a06fdc75563721e61cdee978086e46bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/764e92f4410b181610191d133efb5b4628ed8b88a285cbc55d0ef506c05f951c3a1095aa95fa4f586bdcd3a1cc8c6d7ad3f7902b09aa1f638c2685f4b48c5076"} build_url:https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011178 build_url: 'https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/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/288712a52b029e625d9e1655edeba7089536ca6003f667265bd999a79ce4e5d80b39de76c3ac28c240af24c6d573d1dd1a744ca6c5d94095665abf03a9a64385", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b65c40122b388c86ff3a538f70f00a5b9db4d1ec36278aaa09dbea228c71cadb47eb981f6ee177b0d64ab4264b44f176a06fdc75563721e61cdee978086e46bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/764e92f4410b181610191d133efb5b4628ed8b88a285cbc55d0ef506c05f951c3a1095aa95fa4f586bdcd3a1cc8c6d7ad3f7902b09aa1f638c2685f4b48c5076"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-05 03:21:45.830947) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 03:21:45.831310) ========= 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.WByNccnXgl/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.akZ61rclUa/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.005141 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 03:21:45.880547) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-05 03:21:45.880935) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-05 03:21:45.881359) ========= ========= Total master_lag: 0.19 =========