builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0147 starttime: 1473268632.33 results: success (0) buildid: 20160907082720 builduid: 7f01798c745345029321c1f8bab0133e revision: af11902f242f7bdaa7437a497f286e2528af4f31 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 10:17:12.333604) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 10:17:12.334026) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 10:17:12.334302) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 10:17:12.359204) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:17:12.359512) ========= 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.di73EE7CPR/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.jdPSXAAQyp/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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-07 10:17:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 39.4M=0s 2016-09-07 10:17:12 (39.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.548510 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:17:12.931431) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:17:12.931763) ========= 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.di73EE7CPR/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.jdPSXAAQyp/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.030011 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:17:12.983583) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:17:12.983920) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev af11902f242f7bdaa7437a497f286e2528af4f31 --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 af11902f242f7bdaa7437a497f286e2528af4f31 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.di73EE7CPR/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.jdPSXAAQyp/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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-07 10:17:13,059 truncating revision to first 12 chars 2016-09-07 10:17:13,059 Setting DEBUG logging. 2016-09-07 10:17:13,059 attempt 1/10 2016-09-07 10:17:13,059 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/af11902f242f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 10:17:13,390 unpacking tar archive at: autoland-af11902f242f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.615323 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:17:13.623542) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 10:17:13.623857) ========= script_repo_revision: af11902f242f7bdaa7437a497f286e2528af4f31 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 10:17:13.624246) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 10:17:13.624519) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 10:17:13.643934) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-07 10:17:13.644334) ========= /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.di73EE7CPR/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.jdPSXAAQyp/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:17:13 INFO - MultiFileLogger online at 20160907 10:17:13 in /builds/slave/test 10:17:13 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 10:17:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:17:13 INFO - {'append_to_log': False, 10:17:13 INFO - 'base_work_dir': '/builds/slave/test', 10:17:13 INFO - 'blob_upload_branch': 'Autoland', 10:17:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:17:13 INFO - 'branch': 'Autoland', 10:17:13 INFO - 'buildbot_json_path': 'buildprops.json', 10:17:13 INFO - 'config_files': ('talos/mac_config.py',), 10:17:13 INFO - 'default_actions': ('clobber', 10:17:13 INFO - 'read-buildbot-config', 10:17:13 INFO - 'download-and-extract', 10:17:13 INFO - 'populate-webroot', 10:17:13 INFO - 'create-virtualenv', 10:17:13 INFO - 'install', 10:17:13 INFO - 'run-tests'), 10:17:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:17:13 INFO - 'download_minidump_stackwalk': True, 10:17:13 INFO - 'download_symbols': 'ondemand', 10:17:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:17:13 INFO - 'tooltool.py': '/tools/tooltool.py', 10:17:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:17:13 INFO - '/tools/misc-python/virtualenv.py')}, 10:17:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:17:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:17:13 INFO - 'installer_path': 'installer.exe', 10:17:13 INFO - 'log_level': 'info', 10:17:13 INFO - 'log_name': 'talos', 10:17:13 INFO - 'log_to_console': True, 10:17:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:17:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:17:13 INFO - 'opt_config_files': (), 10:17:13 INFO - 'pip_index': False, 10:17:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:17:13 INFO - 'cmd': ('bash', 10:17:13 INFO - '-c', 10:17:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:17:13 INFO - 'enabled': True, 10:17:13 INFO - 'halt_on_failure': False, 10:17:13 INFO - 'name': 'check_screen_resolution'},), 10:17:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:17:13 INFO - 'cmd': ('bash', 10:17:13 INFO - '-c', 10:17:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:17:13 INFO - 'enabled': True, 10:17:13 INFO - 'halt_on_failure': False, 10:17:13 INFO - 'name': 'check_screen_resolution'},), 10:17:13 INFO - 'run_cmd_checks_enabled': True, 10:17:13 INFO - 'sps_profile': False, 10:17:13 INFO - 'sps_profile_interval': 0, 10:17:13 INFO - 'suite': 'dromaeojs', 10:17:13 INFO - 'system_bits': '32', 10:17:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:17:13 INFO - 'title': 't-yosemite-r7-0147', 10:17:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:17:13 INFO - 'use_talos_json': True, 10:17:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:17:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:17:13 INFO - 'work_dir': 'build'} 10:17:13 INFO - [mozharness: 2016-09-07 17:17:13.857834Z] Running clobber step. 10:17:13 INFO - Running pre-action listener: _resource_record_pre_action 10:17:13 INFO - Running main action method: clobber 10:17:13 INFO - rmtree: /builds/slave/test/build 10:17:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:17:16 INFO - Running post-action listener: _resource_record_post_action 10:17:16 INFO - [mozharness: 2016-09-07 17:17:16.101001Z] Finished clobber step (success) 10:17:16 INFO - [mozharness: 2016-09-07 17:17:16.101147Z] Running read-buildbot-config step. 10:17:16 INFO - Running pre-action listener: _resource_record_pre_action 10:17:16 INFO - Running main action method: read_buildbot_config 10:17:16 INFO - Using buildbot properties: 10:17:16 INFO - { 10:17:16 INFO - "project": "", 10:17:16 INFO - "product": "firefox", 10:17:16 INFO - "script_repo_revision": "production", 10:17:16 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 10:17:16 INFO - "repository": "", 10:17:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 10:17:16 INFO - "buildid": "20160907082720", 10:17:16 INFO - "pgo_build": "False", 10:17:16 INFO - "basedir": "/builds/slave/test", 10:17:16 INFO - "buildnumber": 234, 10:17:16 INFO - "slavename": "t-yosemite-r7-0147", 10:17:16 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 10:17:16 INFO - "platform": "yosemite_r7", 10:17:16 INFO - "scheduler": "tests-autoland-macosx64-talos", 10:17:16 INFO - "branch": "autoland", 10:17:16 INFO - "repo_path": "integration/autoland", 10:17:16 INFO - "revision": "af11902f242f7bdaa7437a497f286e2528af4f31", 10:17:16 INFO - "stage_platform": "macosx64", 10:17:16 INFO - "builduid": "7f01798c745345029321c1f8bab0133e", 10:17:16 INFO - "slavebuilddir": "test" 10:17:16 INFO - } 10:17:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/HNrysa5OTaSTNBcVeVm8Bg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:17:16 INFO - Running post-action listener: _resource_record_post_action 10:17:16 INFO - [mozharness: 2016-09-07 17:17:16.104575Z] Finished read-buildbot-config step (success) 10:17:16 INFO - [mozharness: 2016-09-07 17:17:16.104696Z] Running download-and-extract step. 10:17:16 INFO - Running pre-action listener: _resource_record_pre_action 10:17:16 INFO - Running main action method: download_and_extract 10:17:16 INFO - mkdir: /builds/slave/test/build/tests 10:17: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')]} 10:17:16 INFO - trying https://queue.taskcluster.net/v1/task/HNrysa5OTaSTNBcVeVm8Bg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:17:16 INFO - Downloading https://queue.taskcluster.net/v1/task/HNrysa5OTaSTNBcVeVm8Bg/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 10:17:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HNrysa5OTaSTNBcVeVm8Bg/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 10:17:17 INFO - Downloaded 1199 bytes. 10:17:17 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:17:17 INFO - Using the following test package requirements: 10:17:17 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:17:17 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:17:17 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:17:17 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:17:17 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:17:17 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:17:17 INFO - u'jsshell-mac.zip'], 10:17:17 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:17:17 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:17:17 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:17:17 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:17:17 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:17:17 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:17:17 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:17:17 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:17:17 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:17:17 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:17:17 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:17:17 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:17:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HNrysa5OTaSTNBcVeVm8Bg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:17:17 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:17:24 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 10:17:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HNrysa5OTaSTNBcVeVm8Bg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:17:25 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:17:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HNrysa5OTaSTNBcVeVm8Bg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:17:32 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:17:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:33 INFO - trying https://queue.taskcluster.net/v1/task/HNrysa5OTaSTNBcVeVm8Bg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:17:33 INFO - Downloading https://queue.taskcluster.net/v1/task/HNrysa5OTaSTNBcVeVm8Bg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:17:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HNrysa5OTaSTNBcVeVm8Bg/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 10:17:36 INFO - Downloaded 111578685 bytes. 10:17:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HNrysa5OTaSTNBcVeVm8Bg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:17:36 INFO - mkdir: /builds/slave/test/properties 10:17:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:17:36 INFO - Writing to file /builds/slave/test/properties/build_url 10:17:36 INFO - Contents: 10:17:36 INFO - build_url:https://queue.taskcluster.net/v1/task/HNrysa5OTaSTNBcVeVm8Bg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:17:37 INFO - Running post-action listener: _resource_record_post_action 10:17:37 INFO - Running post-action listener: set_extra_try_arguments 10:17:37 INFO - [mozharness: 2016-09-07 17:17:37.221234Z] Finished download-and-extract step (success) 10:17:37 INFO - [mozharness: 2016-09-07 17:17:37.221345Z] Running populate-webroot step. 10:17:37 INFO - Running pre-action listener: _resource_record_pre_action 10:17:37 INFO - Running main action method: populate_webroot 10:17:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:17:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:17:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:17:37 INFO - '--tppagecycles', 10:17:37 INFO - '7'], 10:17:37 INFO - 'tests': ['tsvgm']}}, 10:17:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:17:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:17:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:17:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:17:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:17:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:17:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:17:37 INFO - 'talos_options': ['--disable-e10s'], 10:17:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:17:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:17:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:17:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:17:37 INFO - 'talos_options': ['--disable-e10s'], 10:17:37 INFO - 'tests': ['damp', 'tps']}, 10:17:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:17:37 INFO - 'tests': ['damp', 'tps']}, 10:17:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:17:37 INFO - 'tests': ['dromaeo_dom']}, 10:17:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:17:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:17:37 INFO - 'tests': ['basic_compositor_video']}, 10:17:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:17:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:17:37 INFO - 'tests': ['a11yr', 10:17:37 INFO - 'ts_paint', 10:17:37 INFO - 'tpaint', 10:17:37 INFO - 'sessionrestore', 10:17:37 INFO - 'sessionrestore_no_auto_restore', 10:17:37 INFO - 'tabpaint']}, 10:17:37 INFO - 'other-e10s': {'tests': ['a11yr', 10:17:37 INFO - 'ts_paint', 10:17:37 INFO - 'tpaint', 10:17:37 INFO - 'sessionrestore', 10:17:37 INFO - 'sessionrestore_no_auto_restore', 10:17:37 INFO - 'tabpaint']}, 10:17:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:17:37 INFO - 'ts_paint', 10:17:37 INFO - 'tpaint', 10:17:37 INFO - 'sessionrestore', 10:17:37 INFO - 'sessionrestore_no_auto_restore', 10:17:37 INFO - 'tabpaint']}, 10:17:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:17:37 INFO - 'ts_paint', 10:17:37 INFO - 'tpaint', 10:17:37 INFO - 'sessionrestore', 10:17:37 INFO - 'sessionrestore_no_auto_restore', 10:17:37 INFO - 'tabpaint']}, 10:17:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:17:37 INFO - 'tests': ['a11yr', 10:17:37 INFO - 'ts_paint', 10:17:37 INFO - 'tpaint', 10:17:37 INFO - 'sessionrestore', 10:17:37 INFO - 'sessionrestore_no_auto_restore', 10:17:37 INFO - 'tabpaint']}, 10:17:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:17:37 INFO - 'tests': ['a11yr', 10:17:37 INFO - 'ts_paint', 10:17:37 INFO - 'tpaint', 10:17:37 INFO - 'sessionrestore', 10:17:37 INFO - 'sessionrestore_no_auto_restore', 10:17:37 INFO - 'tabpaint']}, 10:17:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:17:37 INFO - 'tests': ['tsvgx', 10:17:37 INFO - 'tsvgr_opacity', 10:17:37 INFO - 'tart', 10:17:37 INFO - 'tscrollx', 10:17:37 INFO - 'cart']}, 10:17:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:17:37 INFO - 'tsvgr_opacity', 10:17:37 INFO - 'tart', 10:17:37 INFO - 'tscrollx', 10:17:37 INFO - 'cart']}, 10:17:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:17:37 INFO - 'talos_options': ['--disable-e10s'], 10:17:37 INFO - 'tests': ['tp5o']}, 10:17:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:17:37 INFO - 'tests': ['tp5o']}, 10:17:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:17:37 INFO - 'talos_options': ['--disable-e10s', 10:17:37 INFO - '--xperf_path', 10:17:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:17:37 INFO - 'tests': ['tp5n']}, 10:17:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:17:37 INFO - 'talos_options': ['--xperf_path', 10:17:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:17:37 INFO - 'tests': ['tp5n']}}, 10:17:37 INFO - 'talos.zip': {'path': '', 10:17:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:17:37 INFO - Running post-action listener: _resource_record_post_action 10:17:37 INFO - [mozharness: 2016-09-07 17:17:37.239836Z] Finished populate-webroot step (success) 10:17:37 INFO - [mozharness: 2016-09-07 17:17:37.239967Z] Running create-virtualenv step. 10:17:37 INFO - Running pre-action listener: _resource_record_pre_action 10:17:37 INFO - Running main action method: create_virtualenv 10:17:37 INFO - Creating virtualenv /builds/slave/test/build/venv 10:17:37 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 10:17:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:17:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:17:37 INFO - Using real prefix '/tools/python27' 10:17:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:17:38 INFO - Installing distribute.............................................................................................................................................................................................done. 10:17:40 INFO - Installing pip.................done. 10:17:40 INFO - Return code: 0 10:17:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10516aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051ff618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a8599940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ced30>, '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 0x104e33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050c6b20>, '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.di73EE7CPR/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.jdPSXAAQyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:17:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:17:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:17:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.di73EE7CPR/Render', 10:17:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:40 INFO - 'HOME': '/Users/cltbld', 10:17:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:40 INFO - 'LOGNAME': 'cltbld', 10:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:40 INFO - 'MOZ_NO_REMOTE': '1', 10:17:40 INFO - 'NO_EM_RESTART': '1', 10:17:40 INFO - 'PAGER': '/bin/cat', 10:17:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:40 INFO - 'PWD': '/builds/slave/test', 10:17:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:40 INFO - 'SHELL': '/bin/bash', 10:17:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jdPSXAAQyp/Listeners', 10:17:40 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:17:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:40 INFO - 'USER': 'cltbld', 10:17:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:40 INFO - 'XPC_FLAGS': '0x0', 10:17:40 INFO - 'XPC_SERVICE_NAME': '0', 10:17:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:41 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 10:17:41 INFO - Cleaning up... 10:17:41 INFO - Return code: 0 10:17:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:17:41 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')]} 10:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:41 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')]} 10:17:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:41 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 0x10516aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051ff618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a8599940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ced30>, '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 0x104e33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050c6b20>, '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.di73EE7CPR/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.jdPSXAAQyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:17:41 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 10:17:41 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 10:17:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.di73EE7CPR/Render', 10:17:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:41 INFO - 'HOME': '/Users/cltbld', 10:17:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:41 INFO - 'LOGNAME': 'cltbld', 10:17:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:41 INFO - 'MOZ_NO_REMOTE': '1', 10:17:41 INFO - 'NO_EM_RESTART': '1', 10:17:41 INFO - 'PAGER': '/bin/cat', 10:17:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:41 INFO - 'PWD': '/builds/slave/test', 10:17:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:41 INFO - 'SHELL': '/bin/bash', 10:17:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jdPSXAAQyp/Listeners', 10:17:41 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:17:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:41 INFO - 'USER': 'cltbld', 10:17:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:41 INFO - 'XPC_FLAGS': '0x0', 10:17:41 INFO - 'XPC_SERVICE_NAME': '0', 10:17:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:41 INFO - Downloading/unpacking psutil>=3.1.1 10:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:17:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:17:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:17:45 INFO - Installing collected packages: psutil 10:17:45 INFO - Running setup.py install for psutil 10:17:45 INFO - building 'psutil._psutil_osx' extension 10:17:45 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 10:17:45 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 10:17:45 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 10:17:46 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 10:17:46 INFO - building 'psutil._psutil_posix' extension 10:17:46 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 10:17:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:17:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:17:46 INFO - ^ 10:17:46 INFO - 1 warning generated. 10:17:46 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 10:17:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:17:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:17:46 INFO - Successfully installed psutil 10:17:46 INFO - Cleaning up... 10:17:46 INFO - Return code: 0 10:17:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:17:46 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')]} 10:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:46 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')]} 10:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:46 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 0x10516aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051ff618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a8599940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ced30>, '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 0x104e33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050c6b20>, '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.di73EE7CPR/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.jdPSXAAQyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:17:46 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 10:17:46 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 10:17:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.di73EE7CPR/Render', 10:17:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:46 INFO - 'HOME': '/Users/cltbld', 10:17:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:46 INFO - 'LOGNAME': 'cltbld', 10:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:46 INFO - 'MOZ_NO_REMOTE': '1', 10:17:46 INFO - 'NO_EM_RESTART': '1', 10:17:46 INFO - 'PAGER': '/bin/cat', 10:17:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:46 INFO - 'PWD': '/builds/slave/test', 10:17:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:46 INFO - 'SHELL': '/bin/bash', 10:17:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jdPSXAAQyp/Listeners', 10:17:46 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:17:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:46 INFO - 'USER': 'cltbld', 10:17:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:46 INFO - 'XPC_FLAGS': '0x0', 10:17:46 INFO - 'XPC_SERVICE_NAME': '0', 10:17:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:17:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:17:49 INFO - Installing collected packages: mozsystemmonitor 10:17:49 INFO - Running setup.py install for mozsystemmonitor 10:17:49 INFO - Successfully installed mozsystemmonitor 10:17:49 INFO - Cleaning up... 10:17:49 INFO - Return code: 0 10:17:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10516aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051ff618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a8599940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ced30>, '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 0x104e33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050c6b20>, '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.di73EE7CPR/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.jdPSXAAQyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:17:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:17:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:17:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.di73EE7CPR/Render', 10:17:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:49 INFO - 'HOME': '/Users/cltbld', 10:17:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:49 INFO - 'LOGNAME': 'cltbld', 10:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:49 INFO - 'MOZ_NO_REMOTE': '1', 10:17:49 INFO - 'NO_EM_RESTART': '1', 10:17:49 INFO - 'PAGER': '/bin/cat', 10:17:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:49 INFO - 'PWD': '/builds/slave/test', 10:17:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:49 INFO - 'SHELL': '/bin/bash', 10:17:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jdPSXAAQyp/Listeners', 10:17:49 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:17:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:49 INFO - 'USER': 'cltbld', 10:17:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:49 INFO - 'XPC_FLAGS': '0x0', 10:17:49 INFO - 'XPC_SERVICE_NAME': '0', 10:17:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:49 INFO - Downloading/unpacking jsonschema==2.5.1 10:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:17:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:52 INFO - Downloading functools32-3.2.3-2.tar.gz 10:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:17:52 INFO - warning: no files found matching '*.txt' 10:17:52 INFO - no previously-included directories found matching 'build' 10:17:52 INFO - no previously-included directories found matching 'dist' 10:17:52 INFO - no previously-included directories found matching '.git*' 10:17:52 INFO - Installing collected packages: jsonschema, functools32 10:17:52 INFO - Running setup.py install for functools32 10:17:52 INFO - warning: no files found matching '*.txt' 10:17:52 INFO - no previously-included directories found matching 'build' 10:17:52 INFO - no previously-included directories found matching 'dist' 10:17:52 INFO - no previously-included directories found matching '.git*' 10:17:52 INFO - Successfully installed jsonschema functools32 10:17:52 INFO - Cleaning up... 10:17:52 INFO - Return code: 0 10:17:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:17: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')]} 10:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17: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')]} 10:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10516aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051ff618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a8599940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ced30>, '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 0x104e33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050c6b20>, '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.di73EE7CPR/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.jdPSXAAQyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:17:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:17:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.di73EE7CPR/Render', 10:17:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:52 INFO - 'HOME': '/Users/cltbld', 10:17:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:52 INFO - 'LOGNAME': 'cltbld', 10:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:52 INFO - 'MOZ_NO_REMOTE': '1', 10:17:52 INFO - 'NO_EM_RESTART': '1', 10:17:52 INFO - 'PAGER': '/bin/cat', 10:17:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:52 INFO - 'PWD': '/builds/slave/test', 10:17:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:52 INFO - 'SHELL': '/bin/bash', 10:17:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jdPSXAAQyp/Listeners', 10:17:52 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:17:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:52 INFO - 'USER': 'cltbld', 10:17:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:52 INFO - 'XPC_FLAGS': '0x0', 10:17:52 INFO - 'XPC_SERVICE_NAME': '0', 10:17:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:17:53 INFO - Cleaning up... 10:17:53 INFO - Return code: 0 10:17:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:53 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 0x10516aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051ff618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a8599940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ced30>, '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 0x104e33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050c6b20>, '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.di73EE7CPR/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.jdPSXAAQyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:17:53 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 10:17:53 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 10:17:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.di73EE7CPR/Render', 10:17:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:53 INFO - 'HOME': '/Users/cltbld', 10:17:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:53 INFO - 'LOGNAME': 'cltbld', 10:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:53 INFO - 'MOZ_NO_REMOTE': '1', 10:17:53 INFO - 'NO_EM_RESTART': '1', 10:17:53 INFO - 'PAGER': '/bin/cat', 10:17:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:53 INFO - 'PWD': '/builds/slave/test', 10:17:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:53 INFO - 'SHELL': '/bin/bash', 10:17:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jdPSXAAQyp/Listeners', 10:17:53 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:17:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:53 INFO - 'USER': 'cltbld', 10:17:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:53 INFO - 'XPC_FLAGS': '0x0', 10:17:53 INFO - 'XPC_SERVICE_NAME': '0', 10:17:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:53 INFO - Downloading/unpacking blobuploader==1.2.4 10:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:55 INFO - Downloading blobuploader-1.2.4.tar.gz 10:17:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:17:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:17:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:17:56 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 10:17:56 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 10:17:56 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 10:17:56 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 10:17:56 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 10:17:56 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 10:17:56 INFO - Downloading docopt-0.6.1.tar.gz 10:17:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:17:57 INFO - Installing collected packages: blobuploader, requests, docopt 10:17:57 INFO - Running setup.py install for blobuploader 10:17:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:17:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:17:57 INFO - Running setup.py install for requests 10:17:57 INFO - Running setup.py install for docopt 10:17:57 INFO - Successfully installed blobuploader requests docopt 10:17:57 INFO - Cleaning up... 10:17:57 INFO - Return code: 0 10:17:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:17:57 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')]} 10:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:57 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')]} 10:17:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:17:57 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 0x10516aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051ff618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a8599940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ced30>, '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 0x104e33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050c6b20>, '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.di73EE7CPR/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.jdPSXAAQyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:17:57 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 10:17:57 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 10:17:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.di73EE7CPR/Render', 10:17:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:57 INFO - 'HOME': '/Users/cltbld', 10:17:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:57 INFO - 'LOGNAME': 'cltbld', 10:17:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:57 INFO - 'MOZ_NO_REMOTE': '1', 10:17:57 INFO - 'NO_EM_RESTART': '1', 10:17:57 INFO - 'PAGER': '/bin/cat', 10:17:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:57 INFO - 'PWD': '/builds/slave/test', 10:17:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:57 INFO - 'SHELL': '/bin/bash', 10:17:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jdPSXAAQyp/Listeners', 10:17:57 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:17:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:57 INFO - 'USER': 'cltbld', 10:17:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:57 INFO - 'XPC_FLAGS': '0x0', 10:17:57 INFO - 'XPC_SERVICE_NAME': '0', 10:17:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:17:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-_pDXpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:17:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-9eKHFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:17:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-a3FuZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:17:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-27GdQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:17:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-lf54Y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:17:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-iZJbOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:17:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-qWumVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:17:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-YeXRAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:17:59 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-9VS9Ri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:17:59 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-7ueHxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:17:59 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-O82K0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:17:59 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-M9mr1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:17:59 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-AH8bpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:17:59 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-BzoR1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:17:59 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-3eDV6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:17:59 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-64caDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:17:59 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-DKANAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:17:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:17:59 INFO - Running setup.py install for manifestparser 10:18:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:18:00 INFO - Running setup.py install for mozcrash 10:18:00 INFO - Running setup.py install for mozdebug 10:18:00 INFO - Running setup.py install for mozdevice 10:18:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:18:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:18:00 INFO - Running setup.py install for mozfile 10:18:00 INFO - Running setup.py install for mozhttpd 10:18:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:18:00 INFO - Running setup.py install for mozinfo 10:18:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:18:01 INFO - Running setup.py install for mozInstall 10:18:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:18:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:18:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:18:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:18:01 INFO - Running setup.py install for mozleak 10:18:01 INFO - Running setup.py install for mozlog 10:18:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:18:01 INFO - Running setup.py install for moznetwork 10:18:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:18:01 INFO - Running setup.py install for mozprocess 10:18:01 INFO - Running setup.py install for mozprofile 10:18:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:18:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:18:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:18:01 INFO - Running setup.py install for mozrunner 10:18:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:18:02 INFO - Running setup.py install for mozscreenshot 10:18:02 INFO - Running setup.py install for moztest 10:18:02 INFO - Running setup.py install for mozversion 10:18:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:18:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:18:02 INFO - Cleaning up... 10:18:02 INFO - Return code: 0 10:18:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:18: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')]} 10:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18: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')]} 10:18:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:02 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 0x10516aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051ff618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a8599940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ced30>, '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 0x104e33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050c6b20>, '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.di73EE7CPR/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.jdPSXAAQyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:18:02 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 10:18:02 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 10:18:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.di73EE7CPR/Render', 10:18:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:02 INFO - 'HOME': '/Users/cltbld', 10:18:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:02 INFO - 'LOGNAME': 'cltbld', 10:18:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:02 INFO - 'MOZ_NO_REMOTE': '1', 10:18:02 INFO - 'NO_EM_RESTART': '1', 10:18:02 INFO - 'PAGER': '/bin/cat', 10:18:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:02 INFO - 'PWD': '/builds/slave/test', 10:18:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:02 INFO - 'SHELL': '/bin/bash', 10:18:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jdPSXAAQyp/Listeners', 10:18:02 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:18:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:02 INFO - 'USER': 'cltbld', 10:18:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:02 INFO - 'XPC_FLAGS': '0x0', 10:18:02 INFO - 'XPC_SERVICE_NAME': '0', 10:18:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:18:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-fxTfuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:18:02 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)) 10:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:18:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-OoJUvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:18:03 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)) 10:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:18:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-n8euES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:18:03 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)) 10:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:18:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-rdCBZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:18:03 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)) 10:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:18:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-buBfYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:18:03 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)) 10:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:18:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-YsQLvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:18:03 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)) 10:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:18:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-8LY1jh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:18:03 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)) 10:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:18:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-2Zpyqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:18:03 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)) 10:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:18:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-ktwIZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:18:03 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)) 10:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:18:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-4TrlKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:18:03 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)) 10:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:18:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-A1ikhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:18:04 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)) 10:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:18:04 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-hx1IJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:18:04 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)) 10:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:18:04 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-pQ5mAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:18:04 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)) 10:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:18:04 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-4BIwGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:18:04 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)) 10:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:18:04 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-UCoLht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:18:04 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)) 10:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:18:04 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-avlwrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:18:04 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)) 10:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:18:04 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-I4lh7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:18:04 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)) 10:18:04 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)) 10:18:04 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)) 10:18:04 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)) 10:18:04 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)) 10:18:04 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)) 10:18:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:18:04 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 10:18:04 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 10:18:04 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 10:18:04 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 10:18:04 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 10:18:04 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 10:18:07 INFO - Downloading blessings-1.6.tar.gz 10:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:18:07 INFO - Installing collected packages: blessings 10:18:07 INFO - Running setup.py install for blessings 10:18:07 INFO - Successfully installed blessings 10:18:07 INFO - Cleaning up... 10:18:07 INFO - Return code: 0 10:18:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:18:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:18:07 INFO - Reading from file tmpfile_stdout 10:18:07 INFO - Current package versions: 10:18:07 INFO - blessings == 1.6 10:18:07 INFO - blobuploader == 1.2.4 10:18:07 INFO - docopt == 0.6.1 10:18:07 INFO - functools32 == 3.2.3-2 10:18:07 INFO - jsonschema == 2.5.1 10:18:07 INFO - manifestparser == 1.1 10:18:07 INFO - mozInstall == 1.12 10:18:07 INFO - mozcrash == 0.17 10:18:07 INFO - mozdebug == 0.1 10:18:07 INFO - mozdevice == 0.48 10:18:07 INFO - mozfile == 1.2 10:18:07 INFO - mozhttpd == 0.7 10:18:07 INFO - mozinfo == 0.9 10:18:07 INFO - mozleak == 0.1 10:18:07 INFO - mozlog == 3.3 10:18:07 INFO - moznetwork == 0.27 10:18:07 INFO - mozprocess == 0.23 10:18:07 INFO - mozprofile == 0.28 10:18:07 INFO - mozrunner == 6.12 10:18:07 INFO - mozscreenshot == 0.1 10:18:07 INFO - mozsystemmonitor == 0.3 10:18:07 INFO - moztest == 0.7 10:18:07 INFO - mozversion == 1.4 10:18:07 INFO - psutil == 3.1.1 10:18:07 INFO - requests == 1.2.3 10:18:07 INFO - wsgiref == 0.1.2 10:18:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:18: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')]} 10:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18: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')]} 10:18:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:07 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 0x10516aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051ff618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a8599940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ced30>, '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 0x104e33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050c6b20>, '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.di73EE7CPR/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.jdPSXAAQyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:18:07 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 10:18:07 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 10:18:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.di73EE7CPR/Render', 10:18:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:07 INFO - 'HOME': '/Users/cltbld', 10:18:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:07 INFO - 'LOGNAME': 'cltbld', 10:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:07 INFO - 'MOZ_NO_REMOTE': '1', 10:18:07 INFO - 'NO_EM_RESTART': '1', 10:18:07 INFO - 'PAGER': '/bin/cat', 10:18:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:07 INFO - 'PWD': '/builds/slave/test', 10:18:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:07 INFO - 'SHELL': '/bin/bash', 10:18:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jdPSXAAQyp/Listeners', 10:18:07 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:18:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:07 INFO - 'USER': 'cltbld', 10:18:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:07 INFO - 'XPC_FLAGS': '0x0', 10:18:07 INFO - 'XPC_SERVICE_NAME': '0', 10:18:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:08 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)) 10:18:08 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)) 10:18:08 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)) 10:18:08 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)) 10:18:08 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)) 10:18:08 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)) 10:18:08 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)) 10:18:08 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)) 10:18:08 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)) 10:18:08 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)) 10:18:08 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)) 10:18:08 INFO - Cleaning up... 10:18:08 INFO - Return code: 0 10:18:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:18:08 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')]} 10:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:08 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')]} 10:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:08 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 0x10516aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051ff618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6a8599940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050ced30>, '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 0x104e33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050c6b20>, '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.di73EE7CPR/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.jdPSXAAQyp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 10:18:08 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 10:18:08 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 10:18:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.di73EE7CPR/Render', 10:18:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:08 INFO - 'HOME': '/Users/cltbld', 10:18:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:08 INFO - 'LOGNAME': 'cltbld', 10:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:08 INFO - 'MOZ_NO_REMOTE': '1', 10:18:08 INFO - 'NO_EM_RESTART': '1', 10:18:08 INFO - 'PAGER': '/bin/cat', 10:18:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:08 INFO - 'PWD': '/builds/slave/test', 10:18:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:08 INFO - 'SHELL': '/bin/bash', 10:18:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jdPSXAAQyp/Listeners', 10:18:08 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:18:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:08 INFO - 'USER': 'cltbld', 10:18:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:08 INFO - 'XPC_FLAGS': '0x0', 10:18:08 INFO - 'XPC_SERVICE_NAME': '0', 10:18:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:18:08 INFO - Cleaning up... 10:18:08 INFO - Return code: 0 10:18:08 INFO - Running post-action listener: _resource_record_post_action 10:18:08 INFO - Running post-action listener: _start_resource_monitoring 10:18:08 INFO - Starting resource monitoring. 10:18:08 INFO - [mozharness: 2016-09-07 17:18:08.326771Z] Finished create-virtualenv step (success) 10:18:08 INFO - [mozharness: 2016-09-07 17:18:08.327764Z] Running install step. 10:18:08 INFO - Running pre-action listener: _resource_record_pre_action 10:18:08 INFO - Running main action method: install 10:18:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:18:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:18:08 INFO - Reading from file tmpfile_stdout 10:18:08 INFO - Detecting whether we're running mozinstall >=1.0... 10:18:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:18:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:18:08 INFO - Reading from file tmpfile_stdout 10:18:08 INFO - Output received: 10:18:08 INFO - Usage: mozinstall [options] installer 10:18:08 INFO - Options: 10:18:08 INFO - -h, --help show this help message and exit 10:18:08 INFO - -d DEST, --destination=DEST 10:18:08 INFO - Directory to install application into. [default: 10:18:08 INFO - "/builds/slave/test"] 10:18:08 INFO - --app=APP Application being installed. [default: firefox] 10:18:08 INFO - mkdir: /builds/slave/test/build/application 10:18:08 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'] 10:18:08 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 10:18:42 INFO - Reading from file tmpfile_stdout 10:18:42 INFO - Output received: 10:18:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:18:42 INFO - Running post-action listener: _resource_record_post_action 10:18:42 INFO - [mozharness: 2016-09-07 17:18:42.527319Z] Finished install step (success) 10:18:42 INFO - [mozharness: 2016-09-07 17:18:42.527447Z] Running run-tests step. 10:18:42 INFO - Running pre-action listener: _resource_record_pre_action 10:18:42 INFO - Running main action method: run_tests 10:18:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:18:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:18:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:18:42 INFO - 2016-09-07 10:18:42.542 screenresolution[1648:8023] starting screenresolution argv=screenresolution get 10:18:42 INFO - 2016-09-07 10:18:42.545 screenresolution[1648:8023] Display 0: 1600x1200x32@60 10:18:42 INFO - 2016-09-07 10:18:42.551 screenresolution[1649:8025] starting screenresolution argv=screenresolution list 10:18:42 INFO - Available Modes on Display 0 10:18:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:18:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:18:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:18:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:18:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:18:42 INFO - Intel Iris: 10:18:42 INFO - Chipset Model: Intel Iris 10:18:42 INFO - Type: GPU 10:18:42 INFO - Bus: Built-In 10:18:42 INFO - VRAM (Dynamic, Max): 1536 MB 10:18:42 INFO - Vendor: Intel (0x8086) 10:18:42 INFO - Device ID: 0x0a2e 10:18:42 INFO - Revision ID: 0x0009 10:18:42 INFO - Displays: 10:18:42 INFO - Display: 10:18:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:18:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:18:42 INFO - Display Serial Number: 1600x1200 60 10:18:42 INFO - Main Display: Yes 10:18:42 INFO - Mirror: Off 10:18:42 INFO - Online: Yes 10:18:42 INFO - Rotation: Supported 10:18:42 INFO - Return code: 0 10:18:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:18:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:18:42 INFO - Python 2.7.3 10:18:42 INFO - Return code: 0 10:18:42 INFO - grabbing minidump binary from tooltool 10:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:42 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 0x1050ced30>, '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 0x104e33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050c6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:18:42 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 10:18:42 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 10:18:42 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 10:18:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:18:42 INFO - Return code: 0 10:18:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:18:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:18:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:18:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:18:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:18:42 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/HNrysa5OTaSTNBcVeVm8Bg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 10:18:42 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/HNrysa5OTaSTNBcVeVm8Bg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0147 --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 10:18:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.di73EE7CPR/Render', 10:18:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:42 INFO - 'HOME': '/Users/cltbld', 10:18:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:42 INFO - 'LOGNAME': 'cltbld', 10:18:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:18:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:42 INFO - 'MOZ_NO_REMOTE': '1', 10:18:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:18:42 INFO - 'NO_EM_RESTART': '1', 10:18:42 INFO - 'PAGER': '/bin/cat', 10:18:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:42 INFO - 'PWD': '/builds/slave/test', 10:18:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:18:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:42 INFO - 'SHELL': '/bin/bash', 10:18:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jdPSXAAQyp/Listeners', 10:18:42 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:18:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:42 INFO - 'USER': 'cltbld', 10:18:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:42 INFO - 'XPC_FLAGS': '0x0', 10:18:42 INFO - 'XPC_SERVICE_NAME': '0', 10:18:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:42 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/HNrysa5OTaSTNBcVeVm8Bg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 10:18:43 INFO - mozversion application_buildid: 20160907082720 10:18:43 INFO - mozversion application_changeset: af11902f242f7bdaa7437a497f286e2528af4f31 10:18:43 INFO - mozversion application_display_name: Nightly 10:18:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:18:43 INFO - mozversion application_name: Firefox 10:18:43 INFO - mozversion application_remotingname: firefox 10:18:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:18:43 INFO - mozversion application_vendor: Mozilla 10:18:43 INFO - mozversion application_version: 51.0a1 10:18:43 INFO - mozversion platform_buildid: 20160907082720 10:18:43 INFO - mozversion platform_changeset: af11902f242f7bdaa7437a497f286e2528af4f31 10:18:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:18:43 INFO - mozversion platform_version: 51.0a1 10:18:43 INFO - using testdate: 1473268723 10:18:43 INFO - actual date: 1473268723 10:18:43 INFO - starting webserver on 'localhost:49277' 10:18:43 INFO - SUITE-START | Running 2 tests 10:18:43 INFO - TEST-START | dromaeo_css 10:18:43 INFO - operating with platform_type : mac_ 10:18:43 INFO - Initialising browser for dromaeo_css test... 10:18:43 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpFfV3WK/profile http://localhost:49277/getInfo.html) 10:18:45 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 10:18:45 INFO - PROCESS | 1659 | colorDepth:24 10:18:45 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 10:18:45 INFO - PROCESS | 1659 | __metrics 10:18:45 INFO - PROCESS | 1659 | 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" 10:18:45 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:18:46 INFO - TEST-INFO | 1659: exit 0 10:18:46 INFO - Browser initialized. 10:18:46 INFO - Running cycle 1/1 for dromaeo_css test... 10:18:46 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpFfV3WK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:18:58 INFO - PROCESS | 1662 | RSS: Main: 232206336 10:18:58 INFO - PROCESS | 1662 | 10:19:49 INFO - PROCESS | 1662 | 1473268789784 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] 10:22:01 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 10:22:03 INFO - PROCESS | 1662 | RSS: Main: 380841984 10:22:03 INFO - PROCESS | 1662 | 10:25:06 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 10:25:06 INFO - PROCESS | 1662 | RSS: Main: 375042048 10:25:06 INFO - PROCESS | 1662 | 10:28:09 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 10:28:09 INFO - PROCESS | 1662 | RSS: Main: 340299776 10:28:09 INFO - PROCESS | 1662 | 10:31:12 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 10:31:12 INFO - PROCESS | 1662 | RSS: Main: 336334848 10:31:12 INFO - PROCESS | 1662 | 10:34:16 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 10:34:17 INFO - PROCESS | 1662 | RSS: Main: 325369856 10:34:17 INFO - PROCESS | 1662 | 10:37:20 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 10:37:20 INFO - PROCESS | 1662 | RSS: Main: 483917824 10:37:20 INFO - PROCESS | 1662 | 10:37:20 INFO - PROCESS | 1662 | __start_tp_report 10:37:20 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 10:37:20 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 10:37:20 INFO - PROCESS | 1662 | |i|pagename|runs| 10:37:20 INFO - PROCESS | 1662 | |0;dojo.html;3125.5155450905113;3285.6714328567145;3314.701676849083;3371.730261579074;3397.1167496450917;5573.777048918048;5599.608027438081;5658.519025882805;5735.741891614877;5763.279590051244;5670.858228544278;5701.57238207134;5714.257146570951;5717.628354156987;5730.455606717365;5148.5881129509735;5184.196449550307;5204.245384419276;5220.269162317279;5248.973755131238;5252.658577192489;5273.367195936493;5280.049591073605;5280.102382594949;5281.207818827187;5170.896582068356;5185.792568297264;5220.295260139889;5228.398734145584;5279.894402111975;5234.79060837568;5280.339957505312;5289.259503669489;5296.523312901857;5324.520793128599;5095.694258344512;5104.744762761847;5109.770060347293;5191.792328306863;5211.895762084775;5664.433556644344;5701.116326969326;5714.399988001283;5778.479936805707;5798.333191682926;6246.718897649616;6268.15379923709;6280.15217945576;6285.779997700059;6289.811305660792;5374.408815030343;6095.177151084636;6176.166217560617;6233.252009758812;6291.056341548759;4899.975500122512;5762.193292938992;5786.189933409326;5837.299524057098;5838.883222335529;6287.308396076428;6297.748090076438;6301.275353334334;6311.053341998737;6312.495000400048;5962.791302304398;6053.122297266872;6069.944529494787;6102.816915492543;6109.5112391008615;6204.534659900525;6257.217847769029;6284.685765711788;6293.056041593798;6303.6532990685955;6125.264968203844;6152.077188421773;6200.596961197508;6219.720112594944;6233.34549872266;6095.969520152459;6131.14164017038;6142.723577439026;6233.376662333731;6255.593386429868;4522.597994621678;4668.789904454307;4919.7540122993705;4928.162212423897;4929.285253638202;4456.866294011184;4464.174127786305;4493.303537951622;4495.43807024122;4497.955020449819;5694.515966142924;5703.657780533182;5722.37053924068;5724.885502289931;5728.627639203439;6152.507799376039;6301.054841773771;6316.273628532777;6347.016212487072;6353.919833628202;5752;5758.1362795581;5758.942410575924;5759.625624334405;5839.445252700986;5321.893562128813;5435.646682965595;5436.673799572039;5442.564594832404;5450.509454149066;6247;6262.373762623702;6295.307516173218;6320.8419789504715;6342.49260059185;5792.420757924174;6333.1766870306565;6343.016832390987;6351.396617321347;6362.204724409449;5611.691356975242;6319.273283572448;6321.1782468278825;6338.397852204033;6345.206849143857;5135.897282054263;6016.2479690038745;6067.939320606737;6107.037103281039;6145.66198859067;6067.848303792441;6266.717997690206;6294.0558916162945;6299.685015749103;6311.936880631135;6101.633901965896;6120.234970628671;6128.417800308964;6133.44798968095;6135.07973803933;5865.824025279248;5963.731632076654;5967.7016149191495;5994.430529099728;6062.969685151723;5895.911561326498;5896.469317761422;5900.055991041413;5900.852478688067;5926.585139040226;6029.306629737636;6043.244594425697;6047.365026672312;6048.667323297261;6056.3035250946705;5703.942960570341;5801.245838041028;5802.187693722966;5817.680027598353;5891.970540147272 10:37:20 INFO - PROCESS | 1662 | |1;ext.html;487.91217580835445;494.25120941773207;505.62331063357806;523.3092318140054;552.5275889114804;31475.68524314756;40625.39061914074;41701.7914910425;41760.95597610057;41814.16371672572;43053.784731076375;43395.566044339546;43419.565804341946;43572;43782.12435751283;8765.474071555695;9797.216222702185;9956.103950644441;9969.00309969001;9986.800263994715;8733.7816554586;11984.880151198464;12238.20451670643;12298.139130260884;12515.624531264077;12158.027357811354;12225.938870305636;12243.571474998376;12250.877491225063;12272.693182670415;12194.756104877897;12255.57105501312;12284.754304913851;12291.139620226542;12292.139550231488;12017.218880772809;12098.879011209863;12119;12130.696732581668;12155.392230388534;41249;41401.585984140074;41451.1709765806;41531;41538;3232.2565809863627;3348.179695974502;3448.2069124101345;3470.4794280857823;3766.2655782122497;29796.10611681653;30160;30331;32049.359012819612;32052.878149264896;3229.7416206703406;3238.8380580971093;3284.0640417480904;3301.653326400741;3375.71306438953;522260;524361;524799;524824;527376.3631181858;33663;34430.967070987914;61699;62102.378976210566;62205;35712.10719732028;35720.82139589338;35992.64007359893;36044;36056.69007923603;14741.705165896621;14881.181535015461;14891.925540372229;14904.850951490564;14933.850661493247;11288.435578221057;11521.423928803526;11533.76932461346;12465.189762665397;12590.307533085585;7509;7592.468527203043;7764.5729484878875;7940.761777146695;7958.6816527339415;3037.8481075946115;3142.9214269642985;3238.3523295341024;3281.3273278977754;3304.603447586257;909;929.5909799688116;975.2246963663904;1046.68076236747;1061.8194906865851;30171.396572068876;30185.943491977676;30572.082837514467;30829.229269268;31067.067987960396;972;976.3702411944398;1025.887152413249;1039.60495011895;1058.767071244325;15701.450449234222;15900.602484937968;15952.126735033444;15979.360825566848;16006.839931600534;634357;639376;640789;643442;644781;58094.41442162081;58701;58726;58778.41221587815;59067.70466147642;1208.3052244959183;1310.5151094095056;1351.4053816320788;1360.4150215407074;1373.663452454155;30208.244793880327;30879;31151;31522;31701.841490793322;49559.75220123876;50210;50276.24585631292;50339.74830125972;50386.74806625943;490380;580282;580439;580499;583694;22995.770042299362;26566.734332656426;26907;27539.449211015268;28616.85691571529;45628;47764.52235477601;48054.27918581154;48868;48871.022579548924;39014.804925975186;39115.804420977714;39299.607003929596;40724.1855162901;40733;37879.2424151521;38849.6115038846;38930.221395572495;39139.804300978314;40253.79873100616;38718.6128138715;38807.6119238804;39319.213615726956;39356.80321598374;41370.379444308965;31149.377012460078;34618.55618581154;35006.82496587501;35049.474257886664;35301;24886.25341239766;24950.62574061354;24996;25109.242024585823;25259.87370063138 10:37:20 INFO - PROCESS | 1662 | |2;jquery.html;340.61030993546336;359.9442086476596;363.15566308333115;371.6970668904842;391.4871518311013;12117.939410302946;17289.65420691587;20040.398788036346;20192.59614807703;20199.49501262469;18981.95599242041;19589.804101958976;19928.70106948397;20138.49653758657;20629.896850515728;5378.408375078739;5859.1797148399155;6136.294326152482;6143.631382117065;6152.630842149463;5941.28704555454;6126.8468288292925;6130.1111338855835;6143.969280153616;6150.384961503859;5712.6286791358425;6017.849553761147;6080.2399700037495;6088.878222435571;6095.603785753934;6006.639601623895;6050.2134722486235;6055.636661800306;6060.151578778975;6068.757249710006;5156.793728250866;5412.323459567554;5470.343558772973;5495.587830912657;5811.4479124483105;12632.81050784239;12878.742425151444;12891.67770805728;17472;24422.877885610636;2221.8889055547156;2252.8648281103187;2286.1769762885347;2325.93022209334;2337.8714170720605;9682.225421966226;10118.342307749972;10232.948835255776;13287.402066907012;13507.527236546772;1763.8236176382259;1776.165202354891;1776.9822301777076;1783.7948635906907;1829.7987221405635;118606;119091;119561;126245.36877315554;128356.3582182083;11148.219624626197;11224.607138750105;11297.26567773092;11366.590022993234;11574.768504629861;11844.940775296069;13211.537596184087;14156.50452234167;14165.220912849893;14165.291735413188;7980.241877021674;8236.546989915492;8260.669573217005;8318.66725330992;8545.31637469001;8364.498130112213;8364.58177091143;8379.58102094905;8526.531040792815;8562;4315.201687687788;4386.254336762758;4388.71473354231;4454.242778727624;4491.955080449219;1554.8911576189557;1857.749203857462;1861.5346163459135;1861.8417434518217;1885.5474686075243;828.8437629506805;846.5470973029522;849.3460035772545;869.1265278395173;877.1754550722301;15333.6166595836;20061.15540024017;20964.266250681154;21272.149114035576;21375.67936480954;889.4218757807348;919.2508105893676;927.3554879358916;934.8831396075491;951.5765484359393;10319.122874555596;10767.3539587625;10817.675469735921;10903.83644245337;10942.124630029579;550273;583048;586717;597484;600123;86230.70653940196;105205;114224;114348.85651143549;118295;934.6121359635811;940.1209868772719;955.0544960489204;960.3949511807516;963.6097380560744;18427.723584146515;18543.35098271554;19206;19221.615567688288;19297.807021929602;43408.782956085015;55294;56542.434575653715;57148.85702286014;58084.709576451845;80886;81064.59467702624;81278.59360703158;83004;84588.57705711678;6139.72371243304;6644.7009884554345;8022.9197708022175;8176.7955801105445;8230.876536851832;12253.264804111783;12718.329150878419;13001.669315580944;13111.541096061592;13254.204747715168;9802.852957205505;11078.390688512209;11403.258788178742;11549.6535103947;11629.418529073682;11158;11198.216124871507;11340.206185567262;11390.25963312382;11685.649430517098;10799.784004319712;11351.659450216506;11485.827712584485;11744.647660570197;11786.646400607995;7913.604319783873;7963.960180199294;8080.838383232185;8169.836603267782;8202.138775428493;8772.56137193125;10985.560577576807;11003.61487347939;11412.372319522507;11536.307821530734 10:37:20 INFO - PROCESS | 1662 | |3;mootools.html;1426.2583456602565;1518.2788175616581;1681.5543880871571;1830.5423644088978;1859.8140185981395;7899.170587088359;8144.30773384263;8367.2469477747;8936.508492032935;8957.372983891139;8414.747557573282;8688.913110868905;8731.126887311266;8814.559272036404;8835.779105522364;2343.7421883592833;2376.809855211579;2378.8810559472045;2380.988095059531;2381.2975172324204;2083.854130210886;2159.1903036361364;2159.6436587962967;2328.569214695287;2353.682252895854;2127.585120901425;2288.965665515019;2369.360272726363;2372.51363470489;2383.2135395319588;2334.568104900582;2345.2260753951155;2349.6710460535537;2352.2120089769946;2352.9294121176476;2349.565330413879;2350.576896158691;2350.82368822339;2355.3993731598503;2357.0807385119815;8686.522241276727;8858.29133669305;8859.246964008067;8866.468011919242;8870.778230544223;5200.089984252741;5361.98122356751;5366.892662146735;5367.651102678314;5379.731013449312;3394.321135772855;3453.740969427303;3568.6074531801482;3589;3609.205974685565;1096.3969816600838;1109.778044391125;1747.9650406991916;1811.9547011324692;1873.6159087387052;11560.653180404517;11655.999999999915;11662.76674466506;11668.591599293984;11675.76648467026;4274.465691788527;4554.134714404253;4591.816327346869;4595.471520775087;4624.190766615829;9113.270938324918;9139.90860091404;9140.817183656289;9154.130357616015;9172.220361269367;5224.190250511177;5228.790848366023;5229.6339256251695;5231.686098834082;5238.3190185276435;4793.5685788279225;4828.66199366048;4842.5157484251995;4869.63477739171;4889.926651100236;3567.983124809469;3615.776297577024;3692.1877187018813;3701.40777475608;3706.166112624692;1092.355510248941;1143.6912033751007;1145.3242586873619;1155.930644161353;1159.1538177130574;578.3233616668508;787.551095875357;822.042320696382;824.5959479854948;918.9081091890891;5515.448455154453;5749.540036797046;5771.538276937835;5947.256592925884;5996.610220335664;848.1037257764665;902.7427183252742;992.6228033347281;1014.4934771863776;1027.383569858094;3659.688926441229;3835.424686297022;3838.4626152338697;3923.0780766519847;3959;10675.145988320915;10926.125909927187;11026.117910567136;11036.503357348938;11051.889481105247;10669.35983840972;10739.946300268448;10776.515056822462;10961.725956851142;10965.945170274097;8110.0673422557165;8218.21926916942;8348.165183481604;8349.03986041601;8381.916180838236;5031.52200540948;5648.4633959773755;5731.5701322400155;5797.91303130445;5876.884321644119;10001.599936002478;10063.295569310078;10144.543495542866;10146.289759716748;10231.846522302023;9896.653617123366;10338.431386273827;10420.791584168122;10436.16510679159;10467.633632822815;7008.123984501937;7104.076470058859;7105.786826395217;7120.430365570638;7125.216226215009;8088.757337279656;8203.466774659866;8217.630206640835;8297.377696672653;8306.916930830614;5882.588218824641;6790.558613690095;7343.48595598303;7377.0409846719585;7566.394688424803;7033.156021277479;7204.9639751800905;7288.708451662086;7338.706451742084;7406.740764073232;6690.431313338226;6905.274946130584;6983.790486285419;7043;7052.33002864727;4391.385206071216;4921.85234442953;5031.22016087507;5063.696178229171;5234.1102012657175;4915.238138088602;5041.521055499722;5142.020322175915;5202.751339678374;5218.504242096995 10:37:20 INFO - PROCESS | 1662 | |4;prototype.html;302.46010870595984;304.12867135656353;310.4226139380752;314.2583502933079;316.60266365711027;5377.623566350357;5397.91903121453;5412.91880621791;5418.756155972981;5447.237386765856;5356.000639641803;5367.436419175989;5369.771544808899;5396;5416.187571864222;3272.8527216275324;3629.6733294003534;3649.9635003650023;3719.3863012602887;3885.494885664874;4054.9594504055026;4079.4492743479545;4084.568962071859;4107.486564179478;4139.296319625656;4046.5953404659444;4079.224947260011;4085.2850751118585;4090.772768169552;4124.576601420828;3757.0651114675966;3837.34765089934;4024.4444777566455;4066.8576599818866;4127.277726397883;3311.304626028537;3363.814990175539;3523.1720545671747;3544.85820567177;3557.3063252665743;4314.115606300701;4446.822127114911;5057.671251368649;5140.563052140573;5141.71720555373;4456.3538286948215;4630.073985202939;4659.464161621423;4660.370849935283;4695.16888413519;4861.465238823726;5531.502164805187;5535.667859928417;5540.972295138539;5568.162506372887;3655.2089476156757;3678.742488025839;3755.1738617504107;3923.9607603923882;3932.4242621083235;5460.6177567570285;5607;5891.734871930774;5892.587518873681;5931.555133365015;3274.246923207628;3839.9424008639894;3871.477350557695;3875.93411811754;3879.670228030649;3372.325534893031;3913.9217215655526;3925.803709814498;3950.0914789598555;3960.2871483132735;4384.7369157850635;4545.749918772298;4552.635789136861;4582.587567118975;4602.033572949651;4997.675151115166;5365.570754339644;5628.71856407178;5672.773089076473;5677.858053548694;1217.6103646832928;1232.4885172653248;1257.4278703190027;1277.856318594871;1336.8195293635429;763.8281386687951;802.9237222463856;812.9918700813034;819.9631016604267;823.3989187892872;4087.713860029829;4369.759663218553;4850.010237356862;5115.462876397924;5155.200943853709;4566.520515345907;5532.75923251084;5553.972230138824;5697.857553561112;5820.476157145792;4981.9252711209365;5215.6609820361555;5228.581713462914;5235.978984098065;5252.553532949666;2647.933801654974;2892.6239588853314;2950.8901135687656;3036.544518322225;3064.203307140115;5011.596752909191;5049;5991.760329586855;5998.340182579913;6013.969930150409;5561.277033985556;5889.055472263868;5899.259718383088;5964;6016.909746353808;6032.879342413127;6042.909356359658;6043.516518678495;6088.391160883876;6091;4437.955620443754;5637.774489020393;5818.679972601548;5892.234009578899;5916.26046744157;4822.71063736177;4844.685095468783;5490.6431081979545;5733.741981610921;5810.169796514085;5710.229119068873;6055.6063855849225;6078.875408049525;6083.448720576224;6090.360512146338;3156.9368612628077;3624.9456258156674;3858.498395208605;3886.417037444406;3919.9020024499614;4844.854654360375;5755.223044889054;5758.98435547555;5773.278340207474;5800.796972105956;5618.4662457066515;5618.747156377891;5619.719014049363;5627.606067575394;5633.802816901389;5377.516023557899;5572.4148964358155;5603.329818968582;5626.230425614766;5628.607841667357;4579.473360563577;4865.245886887538;5499.560035197254;5514.310711161105;5522.3649280333275;4337.809406249824;4835.443923948791;5362.7586758596735;5533.667979921218;5624.521915637217;5493.994691937494;5499.7250137492165;5502.284702988586;5511.807086751945;5516 10:37:20 INFO - PROCESS | 1662 | |5;yui.html;679.0968012543315;736.4403053679202;772.9884051739222;782.2881178127903;836.1847198980994;14745.631359216011;15431.382744690212;15519.53441396757;16194.109323987175;16706.916465417686;14785.630359241024;14808.11151330921;14987;14992;15056.397744090224;855.0893298636963;885.9246964008034;886.2245535156737;886.2688282167205;886.57887503436;700.2927043685863;718.497052063555;852.7058164933119;875.2429148786285;887.5517863478934;886.0563499872648;886.1802832380055;886.4769785826361;887.8401887660218;890.679355432044;888.4092078767612;888.8177923525661;890.0387581412059;890.9643614255422;892.1702816380763;542.1921337207572;666.4035688059159;675.3078094952664;676.7022510095592;681.6625770243761;15137.167455789928;15197;15202;15216.239188040665;15232.390704371812;13941.372638231203;14283.428662853474;14425.134491930412;14627.488037918622;14643.048201866952;12995.870041299655;13163.86836131636;13178.267488582214;13357.532486363029;13750.86249137516;4507.866376093366;4568.497465278817;4697.78859951303;4761.6904901181315;4878.048780487807;121651;122949;129717;129764.35117824351;130256.92816110548;10929.453527323602;11341.829872551918;11464.312141271384;11606.361650109326;11715.589954351557;20214.393568192685;21135.471613209793;21223.78776212218;21472.78527214739;23110.42223944415;7685.07873031742;7924.167962363868;7983.321417679563;7999.640016199169;8129.065157506962;4243.819140247964;4323.351497275434;4330.372096046056;4332.653387729038;4340.131973605228;3567.6432356764117;3721.0511319613865;3751.4935483709896;3752.099496120911;3753.0429740416566;609.856683679335;663.6184194088419;679.932006799326;687.6905392573262;696.1437431958719;605.9454649081604;619.294004834489;652.2987788127782;674.5143496682381;675.3280485916447;8292.958535207285;9157.725268241964;9192.264618830477;9619.759506012266;9771;549.9315058331374;570.7602806821145;610.206731249374;630.8112491356147;632.4940047961612;4306.901740056328;4658.767061646972;4751.976240118777;5014.038511320393;5145.871353216125;102685.48657256666;146799;148939;156881;163152;122170;132234.67765322415;132356.3382183102;134982;136584;119601.40199298947;121833;123176.38411808063;124231;127269.363653183;9931.542551343304;10009.271536383234;10084.042016008467;10127.442990635585;10137.847932281169;60601.787964239586;64510.67744661247;65814;66763;69116.30883691099;95371;98850.51724223999;99565;100011.4990225839;102723;12898;13552.661183470098;13586.592402227554;13883.791743123658;13894.027418080637;14186.858131418967;14523.273836308354;14999.475018374305;15249.23753812327;15988.760168597713;11564.884351156381;13977.650558736113;13982.790258145933;14272.928635357172;14727.410903563738;14069.507567235098;14456.494022709154;14578.562643120724;14583.343749531085;14598;13987.790183147057;14006.369713363127;14044.087134336643;14090.577282681536;14132;11241.269317494336;11281.943590281997;11676.532938682358;11805.586804461802;12219.144659873724;11615.419229038345;11771.764564708828;11773.882261177621;11800.3509806958;11988.76022479563 10:37:20 INFO - PROCESS | 1662 | __end_tp_report 10:37:20 INFO - PROCESS | 1662 | __start_cc_report 10:37:20 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1213 10:37:20 INFO - PROCESS | 1662 | __end_cc_report 10:37:20 INFO - PROCESS | 1662 | __startTimestamp1473269840789__endTimestamp 10:37:20 INFO - PROCESS | 1662 | 10:37:20 INFO - PROCESS | 1662 | ------- Summary: start ------- 10:37:20 INFO - PROCESS | 1662 | Number of tests: 6 10:37:20 INFO - PROCESS | 1662 | 10:37:20 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5715.95 Median:5839.45 stddev:644.45 (11.0%) stddev-sans-first:613.55 10:37:20 INFO - PROCESS | 1662 | Values: 3125.5 3285.7 3314.7 3371.7 3397.1 5573.8 5599.6 5658.5 5735.7 5763.3 5670.9 5701.6 5714.3 5717.6 5730.5 5148.6 5184.2 5204.2 5220.3 5249.0 5252.7 5273.4 5280.0 5280.1 5281.2 5170.9 5185.8 5220.3 5228.4 5279.9 5234.8 5280.3 5289.3 5296.5 5324.5 5095.7 5104.7 5109.8 5191.8 5211.9 5664.4 5701.1 5714.4 5778.5 5798.3 6246.7 6268.2 6280.2 6285.8 6289.8 5374.4 6095.2 6176.2 6233.3 6291.1 4900.0 5762.2 5786.2 5837.3 5838.9 6287.3 6297.7 6301.3 6311.1 6312.5 5962.8 6053.1 6069.9 6102.8 6109.5 6204.5 6257.2 6284.7 6293.1 6303.7 6125.3 6152.1 6200.6 6219.7 6233.3 6096.0 6131.1 6142.7 6233.4 6255.6 4522.6 4668.8 4919.8 4928.2 4929.3 4456.9 4464.2 4493.3 4495.4 4498.0 5694.5 5703.7 5722.4 5724.9 5728.6 6152.5 6301.1 6316.3 6347.0 6353.9 5752.0 5758.1 5758.9 5759.6 5839.4 5321.9 5435.6 5436.7 5442.6 5450.5 6247.0 6262.4 6295.3 6320.8 6342.5 5792.4 6333.2 6343.0 6351.4 6362.2 5611.7 6319.3 6321.2 6338.4 6345.2 5135.9 6016.2 6067.9 6107.0 6145.7 6067.8 6266.7 6294.1 6299.7 6311.9 6101.6 6120.2 6128.4 6133.4 6135.1 5865.8 5963.7 5967.7 5994.4 6063.0 5895.9 5896.5 5900.1 5900.9 5926.6 6029.3 6043.2 6047.4 6048.7 6056.3 5703.9 5801.2 5802.2 5817.7 5892.0 10:37:20 INFO - PROCESS | 1662 | 10:37:20 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:69853.80 Median:35506.55 stddev:154743.84 (435.8%) stddev-sans-first:155090.29 10:37:20 INFO - PROCESS | 1662 | Values: 487.9 494.3 505.6 523.3 552.5 31475.7 40625.4 41701.8 41761.0 41814.2 43053.8 43395.6 43419.6 43572.0 43782.1 8765.5 9797.2 9956.1 9969.0 9986.8 8733.8 11984.9 12238.2 12298.1 12515.6 12158.0 12225.9 12243.6 12250.9 12272.7 12194.8 12255.6 12284.8 12291.1 12292.1 12017.2 12098.9 12119.0 12130.7 12155.4 41249.0 41401.6 41451.2 41531.0 41538.0 3232.3 3348.2 3448.2 3470.5 3766.3 29796.1 30160.0 30331.0 32049.4 32052.9 3229.7 3238.8 3284.1 3301.7 3375.7 522260.0 524361.0 524799.0 524824.0 527376.4 33663.0 34431.0 61699.0 62102.4 62205.0 35712.1 35720.8 35992.6 36044.0 36056.7 14741.7 14881.2 14891.9 14904.9 14933.9 11288.4 11521.4 11533.8 12465.2 12590.3 7509.0 7592.5 7764.6 7940.8 7958.7 3037.8 3142.9 3238.4 3281.3 3304.6 909.0 929.6 975.2 1046.7 1061.8 30171.4 30185.9 30572.1 30829.2 31067.1 972.0 976.4 1025.9 1039.6 1058.8 15701.5 15900.6 15952.1 15979.4 16006.8 634357.0 639376.0 640789.0 643442.0 644781.0 58094.4 58701.0 58726.0 58778.4 59067.7 1208.3 1310.5 1351.4 1360.4 1373.7 30208.2 30879.0 31151.0 31522.0 31701.8 49559.8 50210.0 50276.2 50339.7 50386.7 490380.0 580282.0 580439.0 580499.0 583694.0 22995.8 26566.7 26907.0 27539.4 28616.9 45628.0 47764.5 48054.3 48868.0 48871.0 39014.8 39115.8 39299.6 40724.2 40733.0 37879.2 38849.6 38930.2 39139.8 40253.8 38718.6 38807.6 39319.2 39356.8 41370.4 31149.4 34618.6 35006.8 35049.5 35301.0 24886.3 24950.6 24996.0 25109.2 25259.9 10:37:20 INFO - PROCESS | 1662 | 10:37:20 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:33945.13 Median:12381.74 stddev:97342.07 (786.2%) stddev-sans-first:97582.44 10:37:20 INFO - PROCESS | 1662 | Values: 340.6 359.9 363.2 371.7 391.5 12117.9 17289.7 20040.4 20192.6 20199.5 18982.0 19589.8 19928.7 20138.5 20629.9 5378.4 5859.2 6136.3 6143.6 6152.6 5941.3 6126.8 6130.1 6144.0 6150.4 5712.6 6017.8 6080.2 6088.9 6095.6 6006.6 6050.2 6055.6 6060.2 6068.8 5156.8 5412.3 5470.3 5495.6 5811.4 12632.8 12878.7 12891.7 17472.0 24422.9 2221.9 2252.9 2286.2 2325.9 2337.9 9682.2 10118.3 10232.9 13287.4 13507.5 1763.8 1776.2 1777.0 1783.8 1829.8 118606.0 119091.0 119561.0 126245.4 128356.4 11148.2 11224.6 11297.3 11366.6 11574.8 11844.9 13211.5 14156.5 14165.2 14165.3 7980.2 8236.5 8260.7 8318.7 8545.3 8364.5 8364.6 8379.6 8526.5 8562.0 4315.2 4386.3 4388.7 4454.2 4492.0 1554.9 1857.7 1861.5 1861.8 1885.5 828.8 846.5 849.3 869.1 877.2 15333.6 20061.2 20964.3 21272.1 21375.7 889.4 919.3 927.4 934.9 951.6 10319.1 10767.4 10817.7 10903.8 10942.1 550273.0 583048.0 586717.0 597484.0 600123.0 86230.7 105205.0 114224.0 114348.9 118295.0 934.6 940.1 955.1 960.4 963.6 18427.7 18543.4 19206.0 19221.6 19297.8 43408.8 55294.0 56542.4 57148.9 58084.7 80886.0 81064.6 81278.6 83004.0 84588.6 6139.7 6644.7 8022.9 8176.8 8230.9 12253.3 12718.3 13001.7 13111.5 13254.2 9802.9 11078.4 11403.3 11549.7 11629.4 11158.0 11198.2 11340.2 11390.3 11685.6 10799.8 11351.7 11485.8 11744.6 11786.6 7913.6 7964.0 8080.8 8169.8 8202.1 8772.6 10985.6 11003.6 11412.4 11536.3 10:37:20 INFO - PROCESS | 1662 | 10:37:20 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5661.87 Median:4879.78 stddev:3188.19 (65.3%) stddev-sans-first:3181.24 10:37:20 INFO - PROCESS | 1662 | Values: 1426.3 1518.3 1681.6 1830.5 1859.8 7899.2 8144.3 8367.2 8936.5 8957.4 8414.7 8688.9 8731.1 8814.6 8835.8 2343.7 2376.8 2378.9 2381.0 2381.3 2083.9 2159.2 2159.6 2328.6 2353.7 2127.6 2289.0 2369.4 2372.5 2383.2 2334.6 2345.2 2349.7 2352.2 2352.9 2349.6 2350.6 2350.8 2355.4 2357.1 8686.5 8858.3 8859.2 8866.5 8870.8 5200.1 5362.0 5366.9 5367.7 5379.7 3394.3 3453.7 3568.6 3589.0 3609.2 1096.4 1109.8 1748.0 1812.0 1873.6 11560.7 11656.0 11662.8 11668.6 11675.8 4274.5 4554.1 4591.8 4595.5 4624.2 9113.3 9139.9 9140.8 9154.1 9172.2 5224.2 5228.8 5229.6 5231.7 5238.3 4793.6 4828.7 4842.5 4869.6 4889.9 3568.0 3615.8 3692.2 3701.4 3706.2 1092.4 1143.7 1145.3 1155.9 1159.2 578.3 787.6 822.0 824.6 918.9 5515.4 5749.5 5771.5 5947.3 5996.6 848.1 902.7 992.6 1014.5 1027.4 3659.7 3835.4 3838.5 3923.1 3959.0 10675.1 10926.1 11026.1 11036.5 11051.9 10669.4 10739.9 10776.5 10961.7 10965.9 8110.1 8218.2 8348.2 8349.0 8381.9 5031.5 5648.5 5731.6 5797.9 5876.9 10001.6 10063.3 10144.5 10146.3 10231.8 9896.7 10338.4 10420.8 10436.2 10467.6 7008.1 7104.1 7105.8 7120.4 7125.2 8088.8 8203.5 8217.6 8297.4 8306.9 5882.6 6790.6 7343.5 7377.0 7566.4 7033.2 7205.0 7288.7 7338.7 7406.7 6690.4 6905.3 6983.8 7043.0 7052.3 4391.4 4921.9 5031.2 5063.7 5234.1 4915.2 5041.5 5142.0 5202.8 5218.5 10:37:20 INFO - PROCESS | 1662 | 10:37:20 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4537.44 Median:5128.01 stddev:1424.19 (27.8%) stddev-sans-first:1392.26 10:37:20 INFO - PROCESS | 1662 | Values: 302.5 304.1 310.4 314.3 316.6 5377.6 5397.9 5412.9 5418.8 5447.2 5356.0 5367.4 5369.8 5396.0 5416.2 3272.9 3629.7 3650.0 3719.4 3885.5 4055.0 4079.4 4084.6 4107.5 4139.3 4046.6 4079.2 4085.3 4090.8 4124.6 3757.1 3837.3 4024.4 4066.9 4127.3 3311.3 3363.8 3523.2 3544.9 3557.3 4314.1 4446.8 5057.7 5140.6 5141.7 4456.4 4630.1 4659.5 4660.4 4695.2 4861.5 5531.5 5535.7 5541.0 5568.2 3655.2 3678.7 3755.2 3924.0 3932.4 5460.6 5607.0 5891.7 5892.6 5931.6 3274.2 3839.9 3871.5 3875.9 3879.7 3372.3 3913.9 3925.8 3950.1 3960.3 4384.7 4545.7 4552.6 4582.6 4602.0 4997.7 5365.6 5628.7 5672.8 5677.9 1217.6 1232.5 1257.4 1277.9 1336.8 763.8 802.9 813.0 820.0 823.4 4087.7 4369.8 4850.0 5115.5 5155.2 4566.5 5532.8 5554.0 5697.9 5820.5 4981.9 5215.7 5228.6 5236.0 5252.6 2647.9 2892.6 2950.9 3036.5 3064.2 5011.6 5049.0 5991.8 5998.3 6014.0 5561.3 5889.1 5899.3 5964.0 6016.9 6032.9 6042.9 6043.5 6088.4 6091.0 4438.0 5637.8 5818.7 5892.2 5916.3 4822.7 4844.7 5490.6 5733.7 5810.2 5710.2 6055.6 6078.9 6083.4 6090.4 3156.9 3624.9 3858.5 3886.4 3919.9 4844.9 5755.2 5759.0 5773.3 5800.8 5618.5 5618.7 5619.7 5627.6 5633.8 5377.5 5572.4 5603.3 5626.2 5628.6 4579.5 4865.2 5499.6 5514.3 5522.4 4337.8 4835.4 5362.8 5533.7 5624.5 5494.0 5499.7 5502.3 5511.8 5516.0 10:37:20 INFO - PROCESS | 1662 | 10:37:20 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:26170.80 Median:18460.66 stddev:41795.93 (226.4%) stddev-sans-first:41869.35 10:37:20 INFO - PROCESS | 1662 | Values: 679.1 736.4 773.0 782.3 836.2 14745.6 15431.4 15519.5 16194.1 16706.9 14785.6 14808.1 14987.0 14992.0 15056.4 855.1 885.9 886.2 886.3 886.6 700.3 718.5 852.7 875.2 887.6 886.1 886.2 886.5 887.8 890.7 888.4 888.8 890.0 891.0 892.2 542.2 666.4 675.3 676.7 681.7 15137.2 15197.0 15202.0 15216.2 15232.4 13941.4 14283.4 14425.1 14627.5 14643.0 12995.9 13163.9 13178.3 13357.5 13750.9 4507.9 4568.5 4697.8 4761.7 4878.0 121651.0 122949.0 129717.0 129764.4 130256.9 10929.5 11341.8 11464.3 11606.4 11715.6 20214.4 21135.5 21223.8 21472.8 23110.4 7685.1 7924.2 7983.3 7999.6 8129.1 4243.8 4323.4 4330.4 4332.7 4340.1 3567.6 3721.1 3751.5 3752.1 3753.0 609.9 663.6 679.9 687.7 696.1 605.9 619.3 652.3 674.5 675.3 8293.0 9157.7 9192.3 9619.8 9771.0 549.9 570.8 610.2 630.8 632.5 4306.9 4658.8 4752.0 5014.0 5145.9 102685.5 146799.0 148939.0 156881.0 163152.0 122170.0 132234.7 132356.3 134982.0 136584.0 119601.4 121833.0 123176.4 124231.0 127269.4 9931.5 10009.3 10084.0 10127.4 10137.8 60601.8 64510.7 65814.0 66763.0 69116.3 95371.0 98850.5 99565.0 100011.5 102723.0 12898.0 13552.7 13586.6 13883.8 13894.0 14186.9 14523.3 14999.5 15249.2 15988.8 11564.9 13977.7 13982.8 14272.9 14727.4 14069.5 14456.5 14578.6 14583.3 14598.0 13987.8 14006.4 14044.1 14090.6 14132.0 11241.3 11281.9 11676.5 11805.6 12219.1 11615.4 11771.8 11773.9 11800.4 11988.8 10:37:20 INFO - PROCESS | 1662 | -------- Summary: end -------- 10:37:20 INFO - PROCESS | 1662 | 10:37:21 INFO - TEST-INFO | 1662: exit 0 10:37:21 INFO - TEST-OK | dromaeo_css | took 1118265ms 10:37:21 INFO - TEST-START | kraken 10:37:21 INFO - operating with platform_type : mac_ 10:37:21 INFO - Initialising browser for kraken test... 10:37:21 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpYgt40b/profile http://localhost:49277/getInfo.html) 10:37:23 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 10:37:23 INFO - PROCESS | 1668 | colorDepth:24 10:37:23 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 10:37:23 INFO - PROCESS | 1668 | __metrics 10:37:23 INFO - PROCESS | 1668 | 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" 10:37:23 INFO - PROCESS | 1668 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:37:24 INFO - TEST-INFO | 1668: exit 0 10:37:24 INFO - Browser initialized. 10:37:24 INFO - Running cycle 1/1 for kraken test... 10:37:24 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpYgt40b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:37:36 INFO - PROCESS | 1669 | RSS: Main: 232865792 10:37:36 INFO - PROCESS | 1669 | 10:37:39 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 10:37:40 INFO - PROCESS | 1669 | RSS: Main: 292003840 10:37:40 INFO - PROCESS | 1669 | 10:37:42 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 10:37:43 INFO - PROCESS | 1669 | RSS: Main: 287657984 10:37:43 INFO - PROCESS | 1669 | 10:37:47 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 10:37:47 INFO - PROCESS | 1669 | RSS: Main: 379170816 10:37:47 INFO - PROCESS | 1669 | 10:37:49 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 10:37:50 INFO - PROCESS | 1669 | RSS: Main: 280334336 10:37:50 INFO - PROCESS | 1669 | 10:37:52 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:37:53 INFO - PROCESS | 1669 | RSS: Main: 284676096 10:37:53 INFO - PROCESS | 1669 | 10:37:55 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 10:37:56 INFO - PROCESS | 1669 | RSS: Main: 322043904 10:37:56 INFO - PROCESS | 1669 | 10:37:59 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 10:38:00 INFO - PROCESS | 1669 | RSS: Main: 323141632 10:38:00 INFO - PROCESS | 1669 | 10:38:02 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 10:38:03 INFO - PROCESS | 1669 | RSS: Main: 320307200 10:38:03 INFO - PROCESS | 1669 | 10:38:04 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:38:05 INFO - PROCESS | 1669 | RSS: Main: 297238528 10:38:05 INFO - PROCESS | 1669 | 10:38:07 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:38:08 INFO - PROCESS | 1669 | RSS: Main: 304250880 10:38:08 INFO - PROCESS | 1669 | 10:38:10 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:38:11 INFO - PROCESS | 1669 | RSS: Main: 300441600 10:38:11 INFO - PROCESS | 1669 | 10:38:13 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:38:14 INFO - PROCESS | 1669 | RSS: Main: 318177280 10:38:14 INFO - PROCESS | 1669 | 10:38:17 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:38:18 INFO - PROCESS | 1669 | RSS: Main: 314335232 10:38:18 INFO - PROCESS | 1669 | 10:38:19 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 10:38:20 INFO - PROCESS | 1669 | RSS: Main: 358227968 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | __start_tp_report 10:38:20 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 10:38:20 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 10:38:20 INFO - PROCESS | 1669 | |i|pagename|runs| 10:38:20 INFO - PROCESS | 1669 | |0;ai-astar;113;113;117;113;116;159;164;110;112;124 10:38:20 INFO - PROCESS | 1669 | |1;audio-beat-detection;160;114;127;162;109;129;128;103;141;112 10:38:20 INFO - PROCESS | 1669 | |2;audio-dft;185;185;184;183;182;184;184;181;182;183 10:38:20 INFO - PROCESS | 1669 | |3;audio-fft;77;74;76;155;57;58;56;72;139;57 10:38:20 INFO - PROCESS | 1669 | |4;audio-oscillator;82;109;169;99;73;79;117;68;70;118 10:38:20 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;86;84;90;83;85;82;86;83;91;84 10:38:20 INFO - PROCESS | 1669 | |6;imaging-darkroom;91;92;91;92;92;92;92;93;92;103 10:38:20 INFO - PROCESS | 1669 | |7;imaging-desaturate;85;83;86;84;84;84;84;85;84;84 10:38:20 INFO - PROCESS | 1669 | |8;json-parse-financial;52;54;55;53;53;52;52;51;52;53 10:38:20 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;67;66;70;79;73;76;99;102;89;71 10:38:20 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;86;86;84;86;87;84;82;89;86;86 10:38:20 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;139;139;140;139;142;141;153;139;169;141 10:38:20 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;172;169;168;171;169;167;168;170;169;173 10:38:20 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;68;67;67;68;68;68;66;67;68;67 10:38:20 INFO - PROCESS | 1669 | __end_tp_report 10:38:20 INFO - PROCESS | 1669 | __start_cc_report 10:38:20 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,2119 10:38:20 INFO - PROCESS | 1669 | __end_cc_report 10:38:20 INFO - PROCESS | 1669 | __startTimestamp1473269900141__endTimestamp 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | ------- Summary: start ------- 10:38:20 INFO - PROCESS | 1669 | Number of tests: 14 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:124.10 Median:116.50 stddev:20.11 (17.3%) stddev-sans-first:20.93 10:38:20 INFO - PROCESS | 1669 | Values: 113.0 113.0 117.0 113.0 116.0 159.0 164.0 110.0 112.0 124.0 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:128.50 stddev:20.51 (16.0%) stddev-sans-first:18.32 10:38:20 INFO - PROCESS | 1669 | Values: 160.0 114.0 127.0 162.0 109.0 129.0 128.0 103.0 141.0 112.0 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:1.34 (0.7%) stddev-sans-first:1.27 10:38:20 INFO - PROCESS | 1669 | Values: 185.0 185.0 184.0 183.0 182.0 184.0 184.0 181.0 182.0 183.0 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:82.10 Median:65.00 stddev:35.44 (54.5%) stddev-sans-first:37.54 10:38:20 INFO - PROCESS | 1669 | Values: 77.0 74.0 76.0 155.0 57.0 58.0 56.0 72.0 139.0 57.0 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:98.40 Median:71.50 stddev:31.36 (43.9%) stddev-sans-first:32.69 10:38:20 INFO - PROCESS | 1669 | Values: 82.0 109.0 169.0 99.0 73.0 79.0 117.0 68.0 70.0 118.0 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:85.40 Median:85.50 stddev:2.99 (3.5%) stddev-sans-first:3.16 10:38:20 INFO - PROCESS | 1669 | Values: 86.0 84.0 90.0 83.0 85.0 82.0 86.0 83.0 91.0 84.0 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:92.00 stddev:3.56 (3.9%) stddev-sans-first:3.70 10:38:20 INFO - PROCESS | 1669 | Values: 91.0 92.0 91.0 92.0 92.0 92.0 92.0 93.0 92.0 103.0 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.00 stddev:0.82 (1.0%) stddev-sans-first:0.83 10:38:20 INFO - PROCESS | 1669 | Values: 85.0 83.0 86.0 84.0 84.0 84.0 84.0 85.0 84.0 84.0 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:1.16 (2.2%) stddev-sans-first:1.20 10:38:20 INFO - PROCESS | 1669 | Values: 52.0 54.0 55.0 53.0 53.0 52.0 52.0 51.0 52.0 53.0 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:79.20 Median:74.50 stddev:13.05 (17.5%) stddev-sans-first:13.07 10:38:20 INFO - PROCESS | 1669 | Values: 67.0 66.0 70.0 79.0 73.0 76.0 99.0 102.0 89.0 71.0 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:86.00 stddev:1.90 (2.2%) stddev-sans-first:2.01 10:38:20 INFO - PROCESS | 1669 | Values: 86.0 86.0 84.0 86.0 87.0 84.0 82.0 89.0 86.0 86.0 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:144.20 Median:141.00 stddev:9.68 (6.9%) stddev-sans-first:10.08 10:38:20 INFO - PROCESS | 1669 | Values: 139.0 139.0 140.0 139.0 142.0 141.0 153.0 139.0 169.0 141.0 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.60 Median:169.50 stddev:1.90 (1.1%) stddev-sans-first:1.80 10:38:20 INFO - PROCESS | 1669 | Values: 172.0 169.0 168.0 171.0 169.0 167.0 168.0 170.0 169.0 173.0 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:0.70 (1.0%) stddev-sans-first:0.71 10:38:20 INFO - PROCESS | 1669 | Values: 68.0 67.0 67.0 68.0 68.0 68.0 66.0 67.0 68.0 67.0 10:38:20 INFO - PROCESS | 1669 | -------- Summary: end -------- 10:38:20 INFO - PROCESS | 1669 | 10:38:20 INFO - TEST-INFO | 1669: exit 0 10:38:21 INFO - TEST-OK | kraken | took 59590ms 10:38:21 INFO - SUITE-END | took 1177s 10:38:21 INFO - Completed test suite (00:19:38) 10:38:21 INFO - javascript benchmark 10:38:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3125.5155450905113, 3285.6714328567145, 3314.701676849083, 3371.730261579074, 3397.1167496450917, 5573.777048918048, 5599.608027438081, 5658.519025882805, 5735.741891614877, 5763.279590051244, 5670.858228544278, 5701.57238207134, 5714.257146570951, 5717.628354156987, 5730.455606717365, 5148.5881129509735, 5184.196449550307, 5204.245384419276, 5220.269162317279, 5248.973755131238, 5252.658577192489, 5273.367195936493, 5280.049591073605, 5280.102382594949, 5281.207818827187, 5170.896582068356, 5185.792568297264, 5220.295260139889, 5228.398734145584, 5279.894402111975, 5234.79060837568, 5280.339957505312, 5289.259503669489, 5296.523312901857, 5324.520793128599, 5095.694258344512, 5104.744762761847, 5109.770060347293, 5191.792328306863, 5211.895762084775, 5664.433556644344, 5701.116326969326, 5714.399988001283, 5778.479936805707, 5798.333191682926, 6246.718897649616, 6268.15379923709, 6280.15217945576, 6285.779997700059, 6289.811305660792, 5374.408815030343, 6095.177151084636, 6176.166217560617, 6233.252009758812, 6291.056341548759, 4899.975500122512, 5762.193292938992, 5786.189933409326, 5837.299524057098, 5838.883222335529, 6287.308396076428, 6297.748090076438, 6301.275353334334, 6311.053341998737, 6312.495000400048, 5962.791302304398, 6053.122297266872, 6069.944529494787, 6102.816915492543, 6109.5112391008615, 6204.534659900525, 6257.217847769029, 6284.685765711788, 6293.056041593798, 6303.6532990685955, 6125.264968203844, 6152.077188421773, 6200.596961197508, 6219.720112594944, 6233.34549872266, 6095.969520152459, 6131.14164017038, 6142.723577439026, 6233.376662333731, 6255.593386429868, 4522.597994621678, 4668.789904454307, 4919.7540122993705, 4928.162212423897, 4929.285253638202, 4456.866294011184, 4464.174127786305, 4493.303537951622, 4495.43807024122, 4497.955020449819, 5694.515966142924, 5703.657780533182, 5722.37053924068, 5724.885502289931, 5728.627639203439, 6152.507799376039, 6301.054841773771, 6316.273628532777, 6347.016212487072, 6353.919833628202, 5752.0, 5758.1362795581, 5758.942410575924, 5759.625624334405, 5839.445252700986, 5321.893562128813, 5435.646682965595, 5436.673799572039, 5442.564594832404, 5450.509454149066, 6247.0, 6262.373762623702, 6295.307516173218, 6320.8419789504715, 6342.49260059185, 5792.420757924174, 6333.1766870306565, 6343.016832390987, 6351.396617321347, 6362.204724409449, 5611.691356975242, 6319.273283572448, 6321.1782468278825, 6338.397852204033, 6345.206849143857, 5135.897282054263, 6016.2479690038745, 6067.939320606737, 6107.037103281039, 6145.66198859067, 6067.848303792441, 6266.717997690206, 6294.0558916162945, 6299.685015749103, 6311.936880631135, 6101.633901965896, 6120.234970628671, 6128.417800308964, 6133.44798968095, 6135.07973803933, 5865.824025279248, 5963.731632076654, 5967.7016149191495, 5994.430529099728, 6062.969685151723, 5895.911561326498, 5896.469317761422, 5900.055991041413, 5900.852478688067, 5926.585139040226, 6029.306629737636, 6043.244594425697, 6047.365026672312, 6048.667323297261, 6056.3035250946705, 5703.942960570341, 5801.245838041028, 5802.187693722966, 5817.680027598353, 5891.970540147272], "value": 5674.465714372031, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.91217580835445, 494.25120941773207, 505.62331063357806, 523.3092318140054, 552.5275889114804, 31475.68524314756, 40625.39061914074, 41701.7914910425, 41760.95597610057, 41814.16371672572, 43053.784731076375, 43395.566044339546, 43419.565804341946, 43572.0, 43782.12435751283, 8765.474071555695, 9797.216222702185, 9956.103950644441, 9969.00309969001, 9986.800263994715, 8733.7816554586, 11984.880151198464, 12238.20451670643, 12298.139130260884, 12515.624531264077, 12158.027357811354, 12225.938870305636, 12243.571474998376, 12250.877491225063, 12272.693182670415, 12194.756104877897, 12255.57105501312, 12284.754304913851, 12291.139620226542, 12292.139550231488, 12017.218880772809, 12098.879011209863, 12119.0, 12130.696732581668, 12155.392230388534, 41249.0, 41401.585984140074, 41451.1709765806, 41531.0, 41538.0, 3232.2565809863627, 3348.179695974502, 3448.2069124101345, 3470.4794280857823, 3766.2655782122497, 29796.10611681653, 30160.0, 30331.0, 32049.359012819612, 32052.878149264896, 3229.7416206703406, 3238.8380580971093, 3284.0640417480904, 3301.653326400741, 3375.71306438953, 522260.0, 524361.0, 524799.0, 524824.0, 527376.3631181858, 33663.0, 34430.967070987914, 61699.0, 62102.378976210566, 62205.0, 35712.10719732028, 35720.82139589338, 35992.64007359893, 36044.0, 36056.69007923603, 14741.705165896621, 14881.181535015461, 14891.925540372229, 14904.850951490564, 14933.850661493247, 11288.435578221057, 11521.423928803526, 11533.76932461346, 12465.189762665397, 12590.307533085585, 7509.0, 7592.468527203043, 7764.5729484878875, 7940.761777146695, 7958.6816527339415, 3037.8481075946115, 3142.9214269642985, 3238.3523295341024, 3281.3273278977754, 3304.603447586257, 909.0, 929.5909799688116, 975.2246963663904, 1046.68076236747, 1061.8194906865851, 30171.396572068876, 30185.943491977676, 30572.082837514467, 30829.229269268, 31067.067987960396, 972.0, 976.3702411944398, 1025.887152413249, 1039.60495011895, 1058.767071244325, 15701.450449234222, 15900.602484937968, 15952.126735033444, 15979.360825566848, 16006.839931600534, 634357.0, 639376.0, 640789.0, 643442.0, 644781.0, 58094.41442162081, 58701.0, 58726.0, 58778.41221587815, 59067.70466147642, 1208.3052244959183, 1310.5151094095056, 1351.4053816320788, 1360.4150215407074, 1373.663452454155, 30208.244793880327, 30879.0, 31151.0, 31522.0, 31701.841490793322, 49559.75220123876, 50210.0, 50276.24585631292, 50339.74830125972, 50386.74806625943, 490380.0, 580282.0, 580439.0, 580499.0, 583694.0, 22995.770042299362, 26566.734332656426, 26907.0, 27539.449211015268, 28616.85691571529, 45628.0, 47764.52235477601, 48054.27918581154, 48868.0, 48871.022579548924, 39014.804925975186, 39115.804420977714, 39299.607003929596, 40724.1855162901, 40733.0, 37879.2424151521, 38849.6115038846, 38930.221395572495, 39139.804300978314, 40253.79873100616, 38718.6128138715, 38807.6119238804, 39319.213615726956, 39356.80321598374, 41370.379444308965, 31149.377012460078, 34618.55618581154, 35006.82496587501, 35049.474257886664, 35301.0, 24886.25341239766, 24950.62574061354, 24996.0, 25109.242024585823, 25259.87370063138], "value": 19188.102884758227, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.61030993546336, 359.9442086476596, 363.15566308333115, 371.6970668904842, 391.4871518311013, 12117.939410302946, 17289.65420691587, 20040.398788036346, 20192.59614807703, 20199.49501262469, 18981.95599242041, 19589.804101958976, 19928.70106948397, 20138.49653758657, 20629.896850515728, 5378.408375078739, 5859.1797148399155, 6136.294326152482, 6143.631382117065, 6152.630842149463, 5941.28704555454, 6126.8468288292925, 6130.1111338855835, 6143.969280153616, 6150.384961503859, 5712.6286791358425, 6017.849553761147, 6080.2399700037495, 6088.878222435571, 6095.603785753934, 6006.639601623895, 6050.2134722486235, 6055.636661800306, 6060.151578778975, 6068.757249710006, 5156.793728250866, 5412.323459567554, 5470.343558772973, 5495.587830912657, 5811.4479124483105, 12632.81050784239, 12878.742425151444, 12891.67770805728, 17472.0, 24422.877885610636, 2221.8889055547156, 2252.8648281103187, 2286.1769762885347, 2325.93022209334, 2337.8714170720605, 9682.225421966226, 10118.342307749972, 10232.948835255776, 13287.402066907012, 13507.527236546772, 1763.8236176382259, 1776.165202354891, 1776.9822301777076, 1783.7948635906907, 1829.7987221405635, 118606.0, 119091.0, 119561.0, 126245.36877315554, 128356.3582182083, 11148.219624626197, 11224.607138750105, 11297.26567773092, 11366.590022993234, 11574.768504629861, 11844.940775296069, 13211.537596184087, 14156.50452234167, 14165.220912849893, 14165.291735413188, 7980.241877021674, 8236.546989915492, 8260.669573217005, 8318.66725330992, 8545.31637469001, 8364.498130112213, 8364.58177091143, 8379.58102094905, 8526.531040792815, 8562.0, 4315.201687687788, 4386.254336762758, 4388.71473354231, 4454.242778727624, 4491.955080449219, 1554.8911576189557, 1857.749203857462, 1861.5346163459135, 1861.8417434518217, 1885.5474686075243, 828.8437629506805, 846.5470973029522, 849.3460035772545, 869.1265278395173, 877.1754550722301, 15333.6166595836, 20061.15540024017, 20964.266250681154, 21272.149114035576, 21375.67936480954, 889.4218757807348, 919.2508105893676, 927.3554879358916, 934.8831396075491, 951.5765484359393, 10319.122874555596, 10767.3539587625, 10817.675469735921, 10903.83644245337, 10942.124630029579, 550273.0, 583048.0, 586717.0, 597484.0, 600123.0, 86230.70653940196, 105205.0, 114224.0, 114348.85651143549, 118295.0, 934.6121359635811, 940.1209868772719, 955.0544960489204, 960.3949511807516, 963.6097380560744, 18427.723584146515, 18543.35098271554, 19206.0, 19221.615567688288, 19297.807021929602, 43408.782956085015, 55294.0, 56542.434575653715, 57148.85702286014, 58084.709576451845, 80886.0, 81064.59467702624, 81278.59360703158, 83004.0, 84588.57705711678, 6139.72371243304, 6644.7009884554345, 8022.9197708022175, 8176.7955801105445, 8230.876536851832, 12253.264804111783, 12718.329150878419, 13001.669315580944, 13111.541096061592, 13254.204747715168, 9802.852957205505, 11078.390688512209, 11403.258788178742, 11549.6535103947, 11629.418529073682, 11158.0, 11198.216124871507, 11340.206185567262, 11390.25963312382, 11685.649430517098, 10799.784004319712, 11351.659450216506, 11485.827712584485, 11744.647660570197, 11786.646400607995, 7913.604319783873, 7963.960180199294, 8080.838383232185, 8169.836603267782, 8202.138775428493, 8772.56137193125, 10985.560577576807, 11003.61487347939, 11412.372319522507, 11536.307821530734], "value": 9300.964411677292, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.2583456602565, 1518.2788175616581, 1681.5543880871571, 1830.5423644088978, 1859.8140185981395, 7899.170587088359, 8144.30773384263, 8367.2469477747, 8936.508492032935, 8957.372983891139, 8414.747557573282, 8688.913110868905, 8731.126887311266, 8814.559272036404, 8835.779105522364, 2343.7421883592833, 2376.809855211579, 2378.8810559472045, 2380.988095059531, 2381.2975172324204, 2083.854130210886, 2159.1903036361364, 2159.6436587962967, 2328.569214695287, 2353.682252895854, 2127.585120901425, 2288.965665515019, 2369.360272726363, 2372.51363470489, 2383.2135395319588, 2334.568104900582, 2345.2260753951155, 2349.6710460535537, 2352.2120089769946, 2352.9294121176476, 2349.565330413879, 2350.576896158691, 2350.82368822339, 2355.3993731598503, 2357.0807385119815, 8686.522241276727, 8858.29133669305, 8859.246964008067, 8866.468011919242, 8870.778230544223, 5200.089984252741, 5361.98122356751, 5366.892662146735, 5367.651102678314, 5379.731013449312, 3394.321135772855, 3453.740969427303, 3568.6074531801482, 3589.0, 3609.205974685565, 1096.3969816600838, 1109.778044391125, 1747.9650406991916, 1811.9547011324692, 1873.6159087387052, 11560.653180404517, 11655.999999999915, 11662.76674466506, 11668.591599293984, 11675.76648467026, 4274.465691788527, 4554.134714404253, 4591.816327346869, 4595.471520775087, 4624.190766615829, 9113.270938324918, 9139.90860091404, 9140.817183656289, 9154.130357616015, 9172.220361269367, 5224.190250511177, 5228.790848366023, 5229.6339256251695, 5231.686098834082, 5238.3190185276435, 4793.5685788279225, 4828.66199366048, 4842.5157484251995, 4869.63477739171, 4889.926651100236, 3567.983124809469, 3615.776297577024, 3692.1877187018813, 3701.40777475608, 3706.166112624692, 1092.355510248941, 1143.6912033751007, 1145.3242586873619, 1155.930644161353, 1159.1538177130574, 578.3233616668508, 787.551095875357, 822.042320696382, 824.5959479854948, 918.9081091890891, 5515.448455154453, 5749.540036797046, 5771.538276937835, 5947.256592925884, 5996.610220335664, 848.1037257764665, 902.7427183252742, 992.6228033347281, 1014.4934771863776, 1027.383569858094, 3659.688926441229, 3835.424686297022, 3838.4626152338697, 3923.0780766519847, 3959.0, 10675.145988320915, 10926.125909927187, 11026.117910567136, 11036.503357348938, 11051.889481105247, 10669.35983840972, 10739.946300268448, 10776.515056822462, 10961.725956851142, 10965.945170274097, 8110.0673422557165, 8218.21926916942, 8348.165183481604, 8349.03986041601, 8381.916180838236, 5031.52200540948, 5648.4633959773755, 5731.5701322400155, 5797.91303130445, 5876.884321644119, 10001.599936002478, 10063.295569310078, 10144.543495542866, 10146.289759716748, 10231.846522302023, 9896.653617123366, 10338.431386273827, 10420.791584168122, 10436.16510679159, 10467.633632822815, 7008.123984501937, 7104.076470058859, 7105.786826395217, 7120.430365570638, 7125.216226215009, 8088.757337279656, 8203.466774659866, 8217.630206640835, 8297.377696672653, 8306.916930830614, 5882.588218824641, 6790.558613690095, 7343.48595598303, 7377.0409846719585, 7566.394688424803, 7033.156021277479, 7204.9639751800905, 7288.708451662086, 7338.706451742084, 7406.740764073232, 6690.431313338226, 6905.274946130584, 6983.790486285419, 7043.0, 7052.33002864727, 4391.385206071216, 4921.85234442953, 5031.22016087507, 5063.696178229171, 5234.1102012657175, 4915.238138088602, 5041.521055499722, 5142.020322175915, 5202.751339678374, 5218.504242096995], "value": 4559.970113335097, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.46010870595984, 304.12867135656353, 310.4226139380752, 314.2583502933079, 316.60266365711027, 5377.623566350357, 5397.91903121453, 5412.91880621791, 5418.756155972981, 5447.237386765856, 5356.000639641803, 5367.436419175989, 5369.771544808899, 5396.0, 5416.187571864222, 3272.8527216275324, 3629.6733294003534, 3649.9635003650023, 3719.3863012602887, 3885.494885664874, 4054.9594504055026, 4079.4492743479545, 4084.568962071859, 4107.486564179478, 4139.296319625656, 4046.5953404659444, 4079.224947260011, 4085.2850751118585, 4090.772768169552, 4124.576601420828, 3757.0651114675966, 3837.34765089934, 4024.4444777566455, 4066.8576599818866, 4127.277726397883, 3311.304626028537, 3363.814990175539, 3523.1720545671747, 3544.85820567177, 3557.3063252665743, 4314.115606300701, 4446.822127114911, 5057.671251368649, 5140.563052140573, 5141.71720555373, 4456.3538286948215, 4630.073985202939, 4659.464161621423, 4660.370849935283, 4695.16888413519, 4861.465238823726, 5531.502164805187, 5535.667859928417, 5540.972295138539, 5568.162506372887, 3655.2089476156757, 3678.742488025839, 3755.1738617504107, 3923.9607603923882, 3932.4242621083235, 5460.6177567570285, 5607.0, 5891.734871930774, 5892.587518873681, 5931.555133365015, 3274.246923207628, 3839.9424008639894, 3871.477350557695, 3875.93411811754, 3879.670228030649, 3372.325534893031, 3913.9217215655526, 3925.803709814498, 3950.0914789598555, 3960.2871483132735, 4384.7369157850635, 4545.749918772298, 4552.635789136861, 4582.587567118975, 4602.033572949651, 4997.675151115166, 5365.570754339644, 5628.71856407178, 5672.773089076473, 5677.858053548694, 1217.6103646832928, 1232.4885172653248, 1257.4278703190027, 1277.856318594871, 1336.8195293635429, 763.8281386687951, 802.9237222463856, 812.9918700813034, 819.9631016604267, 823.3989187892872, 4087.713860029829, 4369.759663218553, 4850.010237356862, 5115.462876397924, 5155.200943853709, 4566.520515345907, 5532.75923251084, 5553.972230138824, 5697.857553561112, 5820.476157145792, 4981.9252711209365, 5215.6609820361555, 5228.581713462914, 5235.978984098065, 5252.553532949666, 2647.933801654974, 2892.6239588853314, 2950.8901135687656, 3036.544518322225, 3064.203307140115, 5011.596752909191, 5049.0, 5991.760329586855, 5998.340182579913, 6013.969930150409, 5561.277033985556, 5889.055472263868, 5899.259718383088, 5964.0, 6016.909746353808, 6032.879342413127, 6042.909356359658, 6043.516518678495, 6088.391160883876, 6091.0, 4437.955620443754, 5637.774489020393, 5818.679972601548, 5892.234009578899, 5916.26046744157, 4822.71063736177, 4844.685095468783, 5490.6431081979545, 5733.741981610921, 5810.169796514085, 5710.229119068873, 6055.6063855849225, 6078.875408049525, 6083.448720576224, 6090.360512146338, 3156.9368612628077, 3624.9456258156674, 3858.498395208605, 3886.417037444406, 3919.9020024499614, 4844.854654360375, 5755.223044889054, 5758.98435547555, 5773.278340207474, 5800.796972105956, 5618.4662457066515, 5618.747156377891, 5619.719014049363, 5627.606067575394, 5633.802816901389, 5377.516023557899, 5572.4148964358155, 5603.329818968582, 5626.230425614766, 5628.607841667357, 4579.473360563577, 4865.245886887538, 5499.560035197254, 5514.310711161105, 5522.3649280333275, 4337.809406249824, 4835.443923948791, 5362.7586758596735, 5533.667979921218, 5624.521915637217, 5493.994691937494, 5499.7250137492165, 5502.284702988586, 5511.807086751945, 5516.0], "value": 4076.156479506487, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.0968012543315, 736.4403053679202, 772.9884051739222, 782.2881178127903, 836.1847198980994, 14745.631359216011, 15431.382744690212, 15519.53441396757, 16194.109323987175, 16706.916465417686, 14785.630359241024, 14808.11151330921, 14987.0, 14992.0, 15056.397744090224, 855.0893298636963, 885.9246964008034, 886.2245535156737, 886.2688282167205, 886.57887503436, 700.2927043685863, 718.497052063555, 852.7058164933119, 875.2429148786285, 887.5517863478934, 886.0563499872648, 886.1802832380055, 886.4769785826361, 887.8401887660218, 890.679355432044, 888.4092078767612, 888.8177923525661, 890.0387581412059, 890.9643614255422, 892.1702816380763, 542.1921337207572, 666.4035688059159, 675.3078094952664, 676.7022510095592, 681.6625770243761, 15137.167455789928, 15197.0, 15202.0, 15216.239188040665, 15232.390704371812, 13941.372638231203, 14283.428662853474, 14425.134491930412, 14627.488037918622, 14643.048201866952, 12995.870041299655, 13163.86836131636, 13178.267488582214, 13357.532486363029, 13750.86249137516, 4507.866376093366, 4568.497465278817, 4697.78859951303, 4761.6904901181315, 4878.048780487807, 121651.0, 122949.0, 129717.0, 129764.35117824351, 130256.92816110548, 10929.453527323602, 11341.829872551918, 11464.312141271384, 11606.361650109326, 11715.589954351557, 20214.393568192685, 21135.471613209793, 21223.78776212218, 21472.78527214739, 23110.42223944415, 7685.07873031742, 7924.167962363868, 7983.321417679563, 7999.640016199169, 8129.065157506962, 4243.819140247964, 4323.351497275434, 4330.372096046056, 4332.653387729038, 4340.131973605228, 3567.6432356764117, 3721.0511319613865, 3751.4935483709896, 3752.099496120911, 3753.0429740416566, 609.856683679335, 663.6184194088419, 679.932006799326, 687.6905392573262, 696.1437431958719, 605.9454649081604, 619.294004834489, 652.2987788127782, 674.5143496682381, 675.3280485916447, 8292.958535207285, 9157.725268241964, 9192.264618830477, 9619.759506012266, 9771.0, 549.9315058331374, 570.7602806821145, 610.206731249374, 630.8112491356147, 632.4940047961612, 4306.901740056328, 4658.767061646972, 4751.976240118777, 5014.038511320393, 5145.871353216125, 102685.48657256666, 146799.0, 148939.0, 156881.0, 163152.0, 122170.0, 132234.67765322415, 132356.3382183102, 134982.0, 136584.0, 119601.40199298947, 121833.0, 123176.38411808063, 124231.0, 127269.363653183, 9931.542551343304, 10009.271536383234, 10084.042016008467, 10127.442990635585, 10137.847932281169, 60601.787964239586, 64510.67744661247, 65814.0, 66763.0, 69116.30883691099, 95371.0, 98850.51724223999, 99565.0, 100011.4990225839, 102723.0, 12898.0, 13552.661183470098, 13586.592402227554, 13883.791743123658, 13894.027418080637, 14186.858131418967, 14523.273836308354, 14999.475018374305, 15249.23753812327, 15988.760168597713, 11564.884351156381, 13977.650558736113, 13982.790258145933, 14272.928635357172, 14727.410903563738, 14069.507567235098, 14456.494022709154, 14578.562643120724, 14583.343749531085, 14598.0, 13987.790183147057, 14006.369713363127, 14044.087134336643, 14090.577282681536, 14132.0, 11241.269317494336, 11281.943590281997, 11676.532938682358, 11805.586804461802, 12219.144659873724, 11615.419229038345, 11771.764564708828, 11773.882261177621, 11800.3509806958, 11988.76022479563], "value": 8083.881654209973, "unit": "score"}], "name": "dromaeo_css", "value": 7306.7977688458195, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 117.0, 113.0, 116.0, 159.0, 164.0, 110.0, 112.0, 124.0], "value": 124.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 114.0, 127.0, 162.0, 109.0, 129.0, 128.0, 103.0, 141.0, 112.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 184.0, 183.0, 182.0, 184.0, 184.0, 181.0, 182.0, 183.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 76.0, 155.0, 57.0, 58.0, 56.0, 72.0, 139.0, 57.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 109.0, 169.0, 99.0, 73.0, 79.0, 117.0, 68.0, 70.0, 118.0], "value": 98.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 90.0, 83.0, 85.0, 82.0, 86.0, 83.0, 91.0, 84.0], "value": 85.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 91.0, 92.0, 92.0, 92.0, 92.0, 93.0, 92.0, 103.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 86.0, 84.0, 84.0, 84.0, 84.0, 85.0, 84.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 55.0, 53.0, 53.0, 52.0, 52.0, 51.0, 52.0, 53.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 70.0, 79.0, 73.0, 76.0, 99.0, 102.0, 89.0, 71.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 86.0, 87.0, 84.0, 82.0, 89.0, 86.0, 86.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 140.0, 139.0, 142.0, 141.0, 153.0, 139.0, 169.0, 141.0], "value": 144.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 168.0, 171.0, 169.0, 167.0, 168.0, 170.0, 169.0, 173.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 68.0, 68.0, 68.0, 66.0, 67.0, 68.0, 67.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1477.8, "alertThreshold": 2.0}]} 10:38:21 INFO - Return code: 0 10:38:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:38:21 INFO - # TBPL SUCCESS # 10:38:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:38:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:38:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:38:21 INFO - 2016-09-07 10:38:21.477 screenresolution[1673:10167] starting screenresolution argv=screenresolution get 10:38:21 INFO - 2016-09-07 10:38:21.480 screenresolution[1673:10167] Display 0: 1600x1200x32@60 10:38:21 INFO - 2016-09-07 10:38:21.486 screenresolution[1674:10170] starting screenresolution argv=screenresolution list 10:38:21 INFO - Available Modes on Display 0 10:38:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:38:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:38:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:38:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:38:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:38:21 INFO - Intel Iris: 10:38:21 INFO - Chipset Model: Intel Iris 10:38:21 INFO - Type: GPU 10:38:21 INFO - Bus: Built-In 10:38:21 INFO - VRAM (Dynamic, Max): 1536 MB 10:38:21 INFO - Vendor: Intel (0x8086) 10:38:21 INFO - Device ID: 0x0a2e 10:38:21 INFO - Revision ID: 0x0009 10:38:21 INFO - Displays: 10:38:21 INFO - Display: 10:38:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:38:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:38:21 INFO - Display Serial Number: 1600x1200 60 10:38:21 INFO - Main Display: Yes 10:38:21 INFO - Mirror: Off 10:38:21 INFO - Online: Yes 10:38:21 INFO - Rotation: Supported 10:38:21 INFO - Return code: 0 10:38:21 INFO - Running post-action listener: _resource_record_post_action 10:38:21 INFO - [mozharness: 2016-09-07 17:38:21.719425Z] Finished run-tests step (success) 10:38:21 INFO - Running post-run listener: _resource_record_post_run 10:38:21 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 32861184; Write bytes: 704165376; Read time: 317; Write time: 2119 10:38:21 INFO - TinderboxPrint: CPU usage
53.7% 10:38:21 INFO - TinderboxPrint: I/O read bytes / time
32,861,184 / 317 10:38:21 INFO - TinderboxPrint: I/O write bytes / time
704,165,376 / 2,119 10:38:21 INFO - TinderboxPrint: CPU idle
2,243.1 (46.3%) 10:38:21 INFO - TinderboxPrint: CPU system
970.9 (20.0%) 10:38:21 INFO - TinderboxPrint: CPU user
1,635.8 (33.7%) 10:38:21 INFO - TinderboxPrint: Swap in / out
434,421,760 / 0 10:38:21 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 404771328; Write bytes: 400046080; Read time: 29768; Write time: 786 10:38:21 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29182976; Write bytes: 293363200; Read time: 285; Write time: 1305 10:38:22 INFO - Running post-run listener: _upload_blobber_files 10:38:22 INFO - Blob upload gear active. 10:38:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:38:22 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 10:38:22 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'] 10:38:22 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 10:38:22 INFO - (blobuploader) - INFO - Open directory for files ... 10:38:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:38:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:38:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:23 INFO - (blobuploader) - INFO - Done attempting. 10:38:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:38:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:38:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:24 INFO - (blobuploader) - INFO - Done attempting. 10:38:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:38:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:38:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:27 INFO - (blobuploader) - INFO - Done attempting. 10:38:27 INFO - (blobuploader) - INFO - Iteration through files over. 10:38:27 INFO - Return code: 0 10:38:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:38:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:38:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8cf54d53c8b6fc576c233b977d3df4b0ee37d47c0cc16f1416c1bada937df482e8436d2f52cbec796b77d8bb1005a132551e2cf237905a0c2cf35fbdacc2b548", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/da9918bdbd8df8a587c7e6ffac1413091b5a83279b312e2113935bccecbbb69d50ec030a619c878b44c6b41b9d5ffb4775799346e790077a19ade000bbf30493", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d66888478890ec8ed9e2180697a367f4a2491df8b7e505957e95da17c7ce3a0fb3d269c49d0ce1d6cafd9c3b8555cd3b7fdfa1cc5297c87772d3efa45e3af26a"} 10:38:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:38:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:38:27 INFO - Contents: 10:38:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8cf54d53c8b6fc576c233b977d3df4b0ee37d47c0cc16f1416c1bada937df482e8436d2f52cbec796b77d8bb1005a132551e2cf237905a0c2cf35fbdacc2b548", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/da9918bdbd8df8a587c7e6ffac1413091b5a83279b312e2113935bccecbbb69d50ec030a619c878b44c6b41b9d5ffb4775799346e790077a19ade000bbf30493", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d66888478890ec8ed9e2180697a367f4a2491df8b7e505957e95da17c7ce3a0fb3d269c49d0ce1d6cafd9c3b8555cd3b7fdfa1cc5297c87772d3efa45e3af26a"} 10:38:27 INFO - Running post-run listener: copy_logs_to_upload_dir 10:38:27 INFO - Copying logs to upload dir... 10:38:27 INFO - mkdir: /builds/slave/test/build/upload/logs 10:38:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.032504 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-07 10:38:27.712352) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 10:38:27.715661) ========= 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.di73EE7CPR/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.jdPSXAAQyp/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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/8cf54d53c8b6fc576c233b977d3df4b0ee37d47c0cc16f1416c1bada937df482e8436d2f52cbec796b77d8bb1005a132551e2cf237905a0c2cf35fbdacc2b548", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/da9918bdbd8df8a587c7e6ffac1413091b5a83279b312e2113935bccecbbb69d50ec030a619c878b44c6b41b9d5ffb4775799346e790077a19ade000bbf30493", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d66888478890ec8ed9e2180697a367f4a2491df8b7e505957e95da17c7ce3a0fb3d269c49d0ce1d6cafd9c3b8555cd3b7fdfa1cc5297c87772d3efa45e3af26a"} build_url:https://queue.taskcluster.net/v1/task/HNrysa5OTaSTNBcVeVm8Bg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011244 build_url: 'https://queue.taskcluster.net/v1/task/HNrysa5OTaSTNBcVeVm8Bg/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/8cf54d53c8b6fc576c233b977d3df4b0ee37d47c0cc16f1416c1bada937df482e8436d2f52cbec796b77d8bb1005a132551e2cf237905a0c2cf35fbdacc2b548", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/da9918bdbd8df8a587c7e6ffac1413091b5a83279b312e2113935bccecbbb69d50ec030a619c878b44c6b41b9d5ffb4775799346e790077a19ade000bbf30493", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d66888478890ec8ed9e2180697a367f4a2491df8b7e505957e95da17c7ce3a0fb3d269c49d0ce1d6cafd9c3b8555cd3b7fdfa1cc5297c87772d3efa45e3af26a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 10:38:27.748687) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:38:27.748987) ========= 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.di73EE7CPR/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.jdPSXAAQyp/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.005188 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:38:27.796816) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 10:38:27.797111) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 10:38:27.797417) ========= ========= Total master_lag: 0.17 =========