builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0383 starttime: 1473222820.73 results: success (0) buildid: 20160906192717 builduid: 49174d6ab2774806a977beca38202dd4 revision: 5f10d3f47379248797c381742d69af738cf31aad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 21:33:40.727199) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 21:33:40.727647) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 21:33:40.727933) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 21:33:40.755262) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:33:40.755534) ========= 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.FhMv6cwCdT/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.VksNfuKh38/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-06 21:33:40-- 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% 8.58M=0.001s 2016-09-06 21:33:40 (8.58 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142110 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:33:40.919634) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:33:40.919952) ========= 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.FhMv6cwCdT/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.VksNfuKh38/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.020797 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:33:40.968279) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-09-06 21:33:40.968651) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 5f10d3f47379248797c381742d69af738cf31aad --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 5f10d3f47379248797c381742d69af738cf31aad --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FhMv6cwCdT/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.VksNfuKh38/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-06 21:33:41,053 truncating revision to first 12 chars 2016-09-06 21:33:41,053 Setting DEBUG logging. 2016-09-06 21:33:41,053 attempt 1/10 2016-09-06 21:33:41,053 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/5f10d3f47379?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 21:33:41,874 unpacking tar archive at: autoland-5f10d3f47379/testing/mozharness/ program finished with exit code 0 elapsedTime=1.105158 ========= master_lag: 2.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-09-06 21:33:44.089948) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 21:33:44.090367) ========= script_repo_revision: 5f10d3f47379248797c381742d69af738cf31aad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 21:33:44.090893) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 21:33:44.091272) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 21:33:44.669530) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-09-06 21:33:44.669933) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FhMv6cwCdT/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.VksNfuKh38/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 21:33:44 INFO - MultiFileLogger online at 20160906 21:33:44 in /builds/slave/test 21:33:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 21:33:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:33:44 INFO - {'append_to_log': False, 21:33:44 INFO - 'base_work_dir': '/builds/slave/test', 21:33:44 INFO - 'blob_upload_branch': 'Autoland', 21:33:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:33:44 INFO - 'branch': 'Autoland', 21:33:44 INFO - 'buildbot_json_path': 'buildprops.json', 21:33:44 INFO - 'config_files': ('talos/mac_config.py',), 21:33:44 INFO - 'default_actions': ('clobber', 21:33:44 INFO - 'read-buildbot-config', 21:33:44 INFO - 'download-and-extract', 21:33:44 INFO - 'populate-webroot', 21:33:44 INFO - 'create-virtualenv', 21:33:44 INFO - 'install', 21:33:44 INFO - 'run-tests'), 21:33:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:33:44 INFO - 'download_minidump_stackwalk': True, 21:33:44 INFO - 'download_symbols': 'ondemand', 21:33:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:33:44 INFO - 'tooltool.py': '/tools/tooltool.py', 21:33:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:33:44 INFO - '/tools/misc-python/virtualenv.py')}, 21:33:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:33:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:33:44 INFO - 'installer_path': 'installer.exe', 21:33:44 INFO - 'log_level': 'info', 21:33:44 INFO - 'log_name': 'talos', 21:33:44 INFO - 'log_to_console': True, 21:33:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:33:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:33:44 INFO - 'opt_config_files': (), 21:33:44 INFO - 'pip_index': False, 21:33:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:33:44 INFO - 'cmd': ('bash', 21:33:44 INFO - '-c', 21:33:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:33:44 INFO - 'enabled': True, 21:33:44 INFO - 'halt_on_failure': False, 21:33:44 INFO - 'name': 'check_screen_resolution'},), 21:33:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:33:44 INFO - 'cmd': ('bash', 21:33:44 INFO - '-c', 21:33:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:33:44 INFO - 'enabled': True, 21:33:44 INFO - 'halt_on_failure': False, 21:33:44 INFO - 'name': 'check_screen_resolution'},), 21:33:44 INFO - 'run_cmd_checks_enabled': True, 21:33:44 INFO - 'sps_profile': False, 21:33:44 INFO - 'sps_profile_interval': 0, 21:33:44 INFO - 'suite': 'dromaeojs-e10s', 21:33:44 INFO - 'system_bits': '32', 21:33:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:33:44 INFO - 'title': 't-yosemite-r7-0383', 21:33:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:33:44 INFO - 'use_talos_json': True, 21:33:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:33:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:33:44 INFO - 'work_dir': 'build'} 21:33:44 INFO - [mozharness: 2016-09-07 04:33:44.848432Z] Running clobber step. 21:33:44 INFO - Running pre-action listener: _resource_record_pre_action 21:33:44 INFO - Running main action method: clobber 21:33:44 INFO - rmtree: /builds/slave/test/build 21:33:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:33:45 INFO - Running post-action listener: _resource_record_post_action 21:33:45 INFO - [mozharness: 2016-09-07 04:33:45.036403Z] Finished clobber step (success) 21:33:45 INFO - [mozharness: 2016-09-07 04:33:45.036590Z] Running read-buildbot-config step. 21:33:45 INFO - Running pre-action listener: _resource_record_pre_action 21:33:45 INFO - Running main action method: read_buildbot_config 21:33:45 INFO - Using buildbot properties: 21:33:45 INFO - { 21:33:45 INFO - "project": "", 21:33:45 INFO - "product": "firefox", 21:33:45 INFO - "script_repo_revision": "production", 21:33:45 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 21:33:45 INFO - "repository": "", 21:33:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 21:33:45 INFO - "buildid": "20160906192717", 21:33:45 INFO - "pgo_build": "False", 21:33:45 INFO - "basedir": "/builds/slave/test", 21:33:45 INFO - "buildnumber": 169, 21:33:45 INFO - "slavename": "t-yosemite-r7-0383", 21:33:45 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 21:33:45 INFO - "platform": "yosemite_r7", 21:33:45 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 21:33:45 INFO - "branch": "autoland", 21:33:45 INFO - "repo_path": "integration/autoland", 21:33:45 INFO - "revision": "5f10d3f47379248797c381742d69af738cf31aad", 21:33:45 INFO - "stage_platform": "macosx64", 21:33:45 INFO - "builduid": "49174d6ab2774806a977beca38202dd4", 21:33:45 INFO - "slavebuilddir": "test" 21:33:45 INFO - } 21:33:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 21:33:45 INFO - Running post-action listener: _resource_record_post_action 21:33:45 INFO - [mozharness: 2016-09-07 04:33:45.040025Z] Finished read-buildbot-config step (success) 21:33:45 INFO - [mozharness: 2016-09-07 04:33:45.040134Z] Running download-and-extract step. 21:33:45 INFO - Running pre-action listener: _resource_record_pre_action 21:33:45 INFO - Running main action method: download_and_extract 21:33:45 INFO - mkdir: /builds/slave/test/build/tests 21:33:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:45 INFO - trying https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 21:33:45 INFO - Downloading https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/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 21:33:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/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 21:33:46 INFO - Downloaded 1199 bytes. 21:33:46 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 21:33:46 INFO - Using the following test package requirements: 21:33:46 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:33:46 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:33:46 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 21:33:46 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:33:46 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 21:33:46 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:33:46 INFO - u'jsshell-mac.zip'], 21:33:46 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:33:46 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 21:33:46 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:33:46 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:33:46 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 21:33:46 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:33:46 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 21:33:46 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:33:46 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 21:33:46 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:33:46 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 21:33:46 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:33:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:33:46 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 21:33:53 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 21:33:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:33:54 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 21:34:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 21:34:01 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 21:34:01 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')]} 21:34:01 INFO - trying https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:34:01 INFO - Downloading https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 21:34:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/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 21:34:04 INFO - Downloaded 111513314 bytes. 21:34:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:34:04 INFO - mkdir: /builds/slave/test/properties 21:34:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:34:04 INFO - Writing to file /builds/slave/test/properties/build_url 21:34:04 INFO - Contents: 21:34:04 INFO - build_url:https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:34:05 INFO - Running post-action listener: _resource_record_post_action 21:34:05 INFO - Running post-action listener: set_extra_try_arguments 21:34:05 INFO - [mozharness: 2016-09-07 04:34:05.379772Z] Finished download-and-extract step (success) 21:34:05 INFO - [mozharness: 2016-09-07 04:34:05.379873Z] Running populate-webroot step. 21:34:05 INFO - Running pre-action listener: _resource_record_pre_action 21:34:05 INFO - Running main action method: populate_webroot 21:34:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:34:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:34:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:34:05 INFO - '--tppagecycles', 21:34:05 INFO - '7'], 21:34:05 INFO - 'tests': ['tsvgm']}}, 21:34:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:34:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:34:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:34:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:34:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:34:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:34:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:05 INFO - 'talos_options': ['--disable-e10s'], 21:34:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:34:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:34:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:05 INFO - 'talos_options': ['--disable-e10s'], 21:34:05 INFO - 'tests': ['damp', 'tps']}, 21:34:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:05 INFO - 'tests': ['damp', 'tps']}, 21:34:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:34:05 INFO - 'tests': ['dromaeo_dom']}, 21:34:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:34:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:34:05 INFO - 'tests': ['basic_compositor_video']}, 21:34:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:34:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:34:05 INFO - 'tests': ['a11yr', 21:34:05 INFO - 'ts_paint', 21:34:05 INFO - 'tpaint', 21:34:05 INFO - 'sessionrestore', 21:34:05 INFO - 'sessionrestore_no_auto_restore', 21:34:05 INFO - 'tabpaint']}, 21:34:05 INFO - 'other-e10s': {'tests': ['a11yr', 21:34:05 INFO - 'ts_paint', 21:34:05 INFO - 'tpaint', 21:34:05 INFO - 'sessionrestore', 21:34:05 INFO - 'sessionrestore_no_auto_restore', 21:34:05 INFO - 'tabpaint']}, 21:34:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:34:05 INFO - 'ts_paint', 21:34:05 INFO - 'tpaint', 21:34:05 INFO - 'sessionrestore', 21:34:05 INFO - 'sessionrestore_no_auto_restore', 21:34:05 INFO - 'tabpaint']}, 21:34:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:34:05 INFO - 'ts_paint', 21:34:05 INFO - 'tpaint', 21:34:05 INFO - 'sessionrestore', 21:34:05 INFO - 'sessionrestore_no_auto_restore', 21:34:05 INFO - 'tabpaint']}, 21:34:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:34:05 INFO - 'tests': ['a11yr', 21:34:05 INFO - 'ts_paint', 21:34:05 INFO - 'tpaint', 21:34:05 INFO - 'sessionrestore', 21:34:05 INFO - 'sessionrestore_no_auto_restore', 21:34:05 INFO - 'tabpaint']}, 21:34:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:34:05 INFO - 'tests': ['a11yr', 21:34:05 INFO - 'ts_paint', 21:34:05 INFO - 'tpaint', 21:34:05 INFO - 'sessionrestore', 21:34:05 INFO - 'sessionrestore_no_auto_restore', 21:34:05 INFO - 'tabpaint']}, 21:34:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:34:05 INFO - 'tests': ['tsvgx', 21:34:05 INFO - 'tsvgr_opacity', 21:34:05 INFO - 'tart', 21:34:05 INFO - 'tscrollx', 21:34:05 INFO - 'cart']}, 21:34:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:34:05 INFO - 'tsvgr_opacity', 21:34:05 INFO - 'tart', 21:34:05 INFO - 'tscrollx', 21:34:05 INFO - 'cart']}, 21:34:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:05 INFO - 'talos_options': ['--disable-e10s'], 21:34:05 INFO - 'tests': ['tp5o']}, 21:34:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:05 INFO - 'tests': ['tp5o']}, 21:34:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:05 INFO - 'talos_options': ['--disable-e10s', 21:34:05 INFO - '--xperf_path', 21:34:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:34:05 INFO - 'tests': ['tp5n']}, 21:34:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:34:05 INFO - 'talos_options': ['--xperf_path', 21:34:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:34:05 INFO - 'tests': ['tp5n']}}, 21:34:05 INFO - 'talos.zip': {'path': '', 21:34:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:34:05 INFO - Running post-action listener: _resource_record_post_action 21:34:05 INFO - [mozharness: 2016-09-07 04:34:05.398287Z] Finished populate-webroot step (success) 21:34:05 INFO - [mozharness: 2016-09-07 04:34:05.398398Z] Running create-virtualenv step. 21:34:05 INFO - Running pre-action listener: _resource_record_pre_action 21:34:05 INFO - Running main action method: create_virtualenv 21:34:05 INFO - Creating virtualenv /builds/slave/test/build/venv 21:34:05 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 21:34:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:34:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:34:05 INFO - Using real prefix '/tools/python27' 21:34:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:34:06 INFO - Installing distribute.............................................................................................................................................................................................done. 21:34:09 INFO - Installing pip.................done. 21:34:09 INFO - Return code: 0 21:34:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:34:09 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')]} 21:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:09 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')]} 21:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:09 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 0x110097a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab70566720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fffbd30>, '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 0x10fd60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fff3b20>, '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.FhMv6cwCdT/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.VksNfuKh38/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 21:34:09 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 21:34:09 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 21:34:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FhMv6cwCdT/Render', 21:34:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:09 INFO - 'HOME': '/Users/cltbld', 21:34:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:09 INFO - 'LOGNAME': 'cltbld', 21:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:09 INFO - 'MOZ_NO_REMOTE': '1', 21:34:09 INFO - 'NO_EM_RESTART': '1', 21:34:09 INFO - 'PAGER': '/bin/cat', 21:34:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:09 INFO - 'PWD': '/builds/slave/test', 21:34:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:09 INFO - 'SHELL': '/bin/bash', 21:34:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VksNfuKh38/Listeners', 21:34:09 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 21:34:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:09 INFO - 'USER': 'cltbld', 21:34:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:09 INFO - 'XPC_FLAGS': '0x0', 21:34:09 INFO - 'XPC_SERVICE_NAME': '0', 21:34:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:09 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 21:34:09 INFO - Cleaning up... 21:34:09 INFO - Return code: 0 21:34:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:34:09 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')]} 21:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:09 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')]} 21:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:09 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 0x110097a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab70566720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fffbd30>, '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 0x10fd60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fff3b20>, '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.FhMv6cwCdT/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.VksNfuKh38/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 21:34:09 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 21:34:09 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 21:34:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FhMv6cwCdT/Render', 21:34:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:09 INFO - 'HOME': '/Users/cltbld', 21:34:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:09 INFO - 'LOGNAME': 'cltbld', 21:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:09 INFO - 'MOZ_NO_REMOTE': '1', 21:34:09 INFO - 'NO_EM_RESTART': '1', 21:34:09 INFO - 'PAGER': '/bin/cat', 21:34:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:09 INFO - 'PWD': '/builds/slave/test', 21:34:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:09 INFO - 'SHELL': '/bin/bash', 21:34:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VksNfuKh38/Listeners', 21:34:09 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 21:34:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:09 INFO - 'USER': 'cltbld', 21:34:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:09 INFO - 'XPC_FLAGS': '0x0', 21:34:09 INFO - 'XPC_SERVICE_NAME': '0', 21:34:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:09 INFO - Downloading/unpacking psutil>=3.1.1 21:34:09 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 21:34:09 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 21:34:09 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 21:34:09 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 21:34:09 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 21:34:09 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 21:34:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:34:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:34:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:34:12 INFO - Installing collected packages: psutil 21:34:12 INFO - Running setup.py install for psutil 21:34:13 INFO - building 'psutil._psutil_osx' extension 21:34:13 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 21:34:13 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 21:34:13 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 21:34:13 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 21:34:13 INFO - building 'psutil._psutil_posix' extension 21:34:13 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 21:34:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:34:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:34:13 INFO - ^ 21:34:13 INFO - 1 warning generated. 21:34:13 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 21:34:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:34:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:34:13 INFO - Successfully installed psutil 21:34:13 INFO - Cleaning up... 21:34:13 INFO - Return code: 0 21:34:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:34:13 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')]} 21:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:13 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')]} 21:34:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:13 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 0x110097a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab70566720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fffbd30>, '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 0x10fd60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fff3b20>, '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.FhMv6cwCdT/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.VksNfuKh38/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 21:34:13 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 21:34:13 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 21:34:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FhMv6cwCdT/Render', 21:34:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:13 INFO - 'HOME': '/Users/cltbld', 21:34:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:13 INFO - 'LOGNAME': 'cltbld', 21:34:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:13 INFO - 'MOZ_NO_REMOTE': '1', 21:34:13 INFO - 'NO_EM_RESTART': '1', 21:34:13 INFO - 'PAGER': '/bin/cat', 21:34:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:13 INFO - 'PWD': '/builds/slave/test', 21:34:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:13 INFO - 'SHELL': '/bin/bash', 21:34:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VksNfuKh38/Listeners', 21:34:13 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 21:34:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:13 INFO - 'USER': 'cltbld', 21:34:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:13 INFO - 'XPC_FLAGS': '0x0', 21:34:13 INFO - 'XPC_SERVICE_NAME': '0', 21:34:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:34:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:34:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:34:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:34:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:34:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:34:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:34:16 INFO - Installing collected packages: mozsystemmonitor 21:34:16 INFO - Running setup.py install for mozsystemmonitor 21:34:16 INFO - Successfully installed mozsystemmonitor 21:34:16 INFO - Cleaning up... 21:34:16 INFO - Return code: 0 21:34:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:34: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')]} 21:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34: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')]} 21:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:16 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 0x110097a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab70566720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fffbd30>, '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 0x10fd60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fff3b20>, '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.FhMv6cwCdT/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.VksNfuKh38/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 21:34:16 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 21:34:16 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 21:34:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FhMv6cwCdT/Render', 21:34:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:16 INFO - 'HOME': '/Users/cltbld', 21:34:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:16 INFO - 'LOGNAME': 'cltbld', 21:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:16 INFO - 'MOZ_NO_REMOTE': '1', 21:34:16 INFO - 'NO_EM_RESTART': '1', 21:34:16 INFO - 'PAGER': '/bin/cat', 21:34:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:16 INFO - 'PWD': '/builds/slave/test', 21:34:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:16 INFO - 'SHELL': '/bin/bash', 21:34:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VksNfuKh38/Listeners', 21:34:16 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 21:34:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:16 INFO - 'USER': 'cltbld', 21:34:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:16 INFO - 'XPC_FLAGS': '0x0', 21:34:16 INFO - 'XPC_SERVICE_NAME': '0', 21:34:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:17 INFO - Downloading/unpacking jsonschema==2.5.1 21:34:17 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 21:34:17 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 21:34:17 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 21:34:17 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 21:34:17 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 21:34:17 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 21:34:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:34:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:34:19 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 21:34:19 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 21:34:19 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 21:34:19 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 21:34:19 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 21:34:19 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 21:34:20 INFO - Downloading functools32-3.2.3-2.tar.gz 21:34:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:34:20 INFO - warning: no files found matching '*.txt' 21:34:20 INFO - no previously-included directories found matching 'build' 21:34:20 INFO - no previously-included directories found matching 'dist' 21:34:20 INFO - no previously-included directories found matching '.git*' 21:34:20 INFO - Installing collected packages: jsonschema, functools32 21:34:20 INFO - Running setup.py install for functools32 21:34:20 INFO - warning: no files found matching '*.txt' 21:34:20 INFO - no previously-included directories found matching 'build' 21:34:20 INFO - no previously-included directories found matching 'dist' 21:34:20 INFO - no previously-included directories found matching '.git*' 21:34:20 INFO - Successfully installed jsonschema functools32 21:34:20 INFO - Cleaning up... 21:34:20 INFO - Return code: 0 21:34:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:34:20 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')]} 21:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:20 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')]} 21:34:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:20 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 0x110097a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab70566720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fffbd30>, '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 0x10fd60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fff3b20>, '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.FhMv6cwCdT/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.VksNfuKh38/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 21:34:20 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 21:34:20 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 21:34:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FhMv6cwCdT/Render', 21:34:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:20 INFO - 'HOME': '/Users/cltbld', 21:34:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:20 INFO - 'LOGNAME': 'cltbld', 21:34:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:20 INFO - 'MOZ_NO_REMOTE': '1', 21:34:20 INFO - 'NO_EM_RESTART': '1', 21:34:20 INFO - 'PAGER': '/bin/cat', 21:34:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:20 INFO - 'PWD': '/builds/slave/test', 21:34:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:20 INFO - 'SHELL': '/bin/bash', 21:34:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VksNfuKh38/Listeners', 21:34:20 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 21:34:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:20 INFO - 'USER': 'cltbld', 21:34:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:20 INFO - 'XPC_FLAGS': '0x0', 21:34:20 INFO - 'XPC_SERVICE_NAME': '0', 21:34:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:34:20 INFO - Cleaning up... 21:34:20 INFO - Return code: 0 21:34:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:34:20 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')]} 21:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:20 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')]} 21:34:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:20 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 0x110097a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab70566720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fffbd30>, '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 0x10fd60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fff3b20>, '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.FhMv6cwCdT/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.VksNfuKh38/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 21:34:20 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 21:34:20 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 21:34:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FhMv6cwCdT/Render', 21:34:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:20 INFO - 'HOME': '/Users/cltbld', 21:34:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:20 INFO - 'LOGNAME': 'cltbld', 21:34:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:20 INFO - 'MOZ_NO_REMOTE': '1', 21:34:20 INFO - 'NO_EM_RESTART': '1', 21:34:20 INFO - 'PAGER': '/bin/cat', 21:34:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:20 INFO - 'PWD': '/builds/slave/test', 21:34:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:20 INFO - 'SHELL': '/bin/bash', 21:34:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VksNfuKh38/Listeners', 21:34:20 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 21:34:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:20 INFO - 'USER': 'cltbld', 21:34:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:20 INFO - 'XPC_FLAGS': '0x0', 21:34:20 INFO - 'XPC_SERVICE_NAME': '0', 21:34:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:20 INFO - Downloading/unpacking blobuploader==1.2.4 21:34:20 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 21:34:20 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 21:34:20 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 21:34:20 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 21:34:20 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 21:34:20 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 21:34:23 INFO - Downloading blobuploader-1.2.4.tar.gz 21:34:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:34:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:34:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:34:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:34:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:34:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:34:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:34:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:34:24 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 21:34:24 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 21:34:24 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 21:34:24 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 21:34:24 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 21:34:24 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 21:34:24 INFO - Downloading docopt-0.6.1.tar.gz 21:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:34:24 INFO - Installing collected packages: blobuploader, requests, docopt 21:34:24 INFO - Running setup.py install for blobuploader 21:34:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:34:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:34:24 INFO - Running setup.py install for requests 21:34:25 INFO - Running setup.py install for docopt 21:34:25 INFO - Successfully installed blobuploader requests docopt 21:34:25 INFO - Cleaning up... 21:34:25 INFO - Return code: 0 21:34:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:34:25 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')]} 21:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:25 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')]} 21:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:25 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 0x110097a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab70566720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fffbd30>, '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 0x10fd60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fff3b20>, '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.FhMv6cwCdT/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.VksNfuKh38/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 21:34:25 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 21:34:25 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 21:34:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FhMv6cwCdT/Render', 21:34:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:25 INFO - 'HOME': '/Users/cltbld', 21:34:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:25 INFO - 'LOGNAME': 'cltbld', 21:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:25 INFO - 'MOZ_NO_REMOTE': '1', 21:34:25 INFO - 'NO_EM_RESTART': '1', 21:34:25 INFO - 'PAGER': '/bin/cat', 21:34:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:25 INFO - 'PWD': '/builds/slave/test', 21:34:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:25 INFO - 'SHELL': '/bin/bash', 21:34:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VksNfuKh38/Listeners', 21:34:25 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 21:34:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:25 INFO - 'USER': 'cltbld', 21:34:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:25 INFO - 'XPC_FLAGS': '0x0', 21:34:25 INFO - 'XPC_SERVICE_NAME': '0', 21:34:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:34:25 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-eAqltm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:34:25 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-mjFnZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:34:25 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-idLK0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:34:26 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Sfm9Lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:34:26 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-MR3OSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:34:26 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-pZQx4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:34:26 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-FtJzzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:34:26 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-EGk9ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:34:26 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-tEeasz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:34:26 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-LJOv36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:34:26 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-KN2x9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:34:26 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-lmlGeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:34:27 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-WClXaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:34:27 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-S0eh1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:34:27 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-zoM5jv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:34:27 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-oTNMAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:34:27 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-D38qGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:34:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:34:27 INFO - Running setup.py install for manifestparser 21:34:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:34:27 INFO - Running setup.py install for mozcrash 21:34:27 INFO - Running setup.py install for mozdebug 21:34:27 INFO - Running setup.py install for mozdevice 21:34:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:34:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:34:28 INFO - Running setup.py install for mozfile 21:34:28 INFO - Running setup.py install for mozhttpd 21:34:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:34:28 INFO - Running setup.py install for mozinfo 21:34:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:34:28 INFO - Running setup.py install for mozInstall 21:34:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:34:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:34:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:34:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:34:28 INFO - Running setup.py install for mozleak 21:34:28 INFO - Running setup.py install for mozlog 21:34:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:34:29 INFO - Running setup.py install for moznetwork 21:34:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:34:29 INFO - Running setup.py install for mozprocess 21:34:29 INFO - Running setup.py install for mozprofile 21:34:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:34:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:34:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:34:29 INFO - Running setup.py install for mozrunner 21:34:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:34:29 INFO - Running setup.py install for mozscreenshot 21:34:29 INFO - Running setup.py install for moztest 21:34:29 INFO - Running setup.py install for mozversion 21:34:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:34:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:34:30 INFO - Cleaning up... 21:34:30 INFO - Return code: 0 21:34:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:34:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:34:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:30 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 0x110097a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab70566720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fffbd30>, '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 0x10fd60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fff3b20>, '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.FhMv6cwCdT/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.VksNfuKh38/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 21:34:30 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 21:34:30 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 21:34:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FhMv6cwCdT/Render', 21:34:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:30 INFO - 'HOME': '/Users/cltbld', 21:34:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:30 INFO - 'LOGNAME': 'cltbld', 21:34:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:30 INFO - 'MOZ_NO_REMOTE': '1', 21:34:30 INFO - 'NO_EM_RESTART': '1', 21:34:30 INFO - 'PAGER': '/bin/cat', 21:34:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:30 INFO - 'PWD': '/builds/slave/test', 21:34:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:30 INFO - 'SHELL': '/bin/bash', 21:34:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VksNfuKh38/Listeners', 21:34:30 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 21:34:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:30 INFO - 'USER': 'cltbld', 21:34:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:30 INFO - 'XPC_FLAGS': '0x0', 21:34:30 INFO - 'XPC_SERVICE_NAME': '0', 21:34:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:34:30 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-R0EivI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:34:30 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)) 21:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:34:30 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-193Wc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:34:30 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)) 21:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:34:30 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-14CRrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:34:30 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)) 21:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:34:30 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-jStLom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:34:30 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)) 21:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:34:30 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-EflYVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:34:30 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)) 21:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:34:30 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-cu1AJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:34:31 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)) 21:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:34:31 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-aXnU2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:34:31 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)) 21:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:34:31 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-agdxCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:34:31 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)) 21:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:34:31 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-GQXKB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:34:31 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)) 21:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:34:31 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-l3j3f2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:34:31 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)) 21:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:34:31 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-yC2yhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:34:31 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)) 21:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:34:31 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-q80DS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:34:31 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)) 21:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:34:31 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-hqlepG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:34:31 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)) 21:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:34:31 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-AIEJD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:34:31 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)) 21:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:34:31 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-ZEdqSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:34:31 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)) 21:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:34:32 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-ICuGlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:34:32 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)) 21:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:34:32 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-1LYxMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:34:32 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)) 21:34:32 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)) 21:34:32 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)) 21:34:32 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)) 21:34:32 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)) 21:34:32 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)) 21:34:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:34:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:34:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:34:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:34:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:34:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:34:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:34:34 INFO - Downloading blessings-1.6.tar.gz 21:34:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:34:34 INFO - Installing collected packages: blessings 21:34:34 INFO - Running setup.py install for blessings 21:34:34 INFO - Successfully installed blessings 21:34:34 INFO - Cleaning up... 21:34:34 INFO - Return code: 0 21:34:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:34:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:34:35 INFO - Reading from file tmpfile_stdout 21:34:35 INFO - Current package versions: 21:34:35 INFO - blessings == 1.6 21:34:35 INFO - blobuploader == 1.2.4 21:34:35 INFO - docopt == 0.6.1 21:34:35 INFO - functools32 == 3.2.3-2 21:34:35 INFO - jsonschema == 2.5.1 21:34:35 INFO - manifestparser == 1.1 21:34:35 INFO - mozInstall == 1.12 21:34:35 INFO - mozcrash == 0.17 21:34:35 INFO - mozdebug == 0.1 21:34:35 INFO - mozdevice == 0.48 21:34:35 INFO - mozfile == 1.2 21:34:35 INFO - mozhttpd == 0.7 21:34:35 INFO - mozinfo == 0.9 21:34:35 INFO - mozleak == 0.1 21:34:35 INFO - mozlog == 3.3 21:34:35 INFO - moznetwork == 0.27 21:34:35 INFO - mozprocess == 0.23 21:34:35 INFO - mozprofile == 0.28 21:34:35 INFO - mozrunner == 6.12 21:34:35 INFO - mozscreenshot == 0.1 21:34:35 INFO - mozsystemmonitor == 0.3 21:34:35 INFO - moztest == 0.7 21:34:35 INFO - mozversion == 1.4 21:34:35 INFO - psutil == 3.1.1 21:34:35 INFO - requests == 1.2.3 21:34:35 INFO - wsgiref == 0.1.2 21:34:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:34:35 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')]} 21:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:35 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')]} 21:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:35 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 0x110097a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab70566720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fffbd30>, '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 0x10fd60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fff3b20>, '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.FhMv6cwCdT/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.VksNfuKh38/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 21:34:35 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 21:34:35 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 21:34:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FhMv6cwCdT/Render', 21:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:35 INFO - 'HOME': '/Users/cltbld', 21:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:35 INFO - 'LOGNAME': 'cltbld', 21:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:35 INFO - 'MOZ_NO_REMOTE': '1', 21:34:35 INFO - 'NO_EM_RESTART': '1', 21:34:35 INFO - 'PAGER': '/bin/cat', 21:34:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:35 INFO - 'PWD': '/builds/slave/test', 21:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:35 INFO - 'SHELL': '/bin/bash', 21:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VksNfuKh38/Listeners', 21:34:35 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 21:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:35 INFO - 'USER': 'cltbld', 21:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:35 INFO - 'XPC_FLAGS': '0x0', 21:34:35 INFO - 'XPC_SERVICE_NAME': '0', 21:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:35 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)) 21:34:35 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)) 21:34:35 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)) 21:34:35 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)) 21:34:35 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)) 21:34:35 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)) 21:34:35 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)) 21:34:35 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)) 21:34:35 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)) 21:34:35 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)) 21:34:35 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)) 21:34:35 INFO - Cleaning up... 21:34:35 INFO - Return code: 0 21:34:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:34:35 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')]} 21:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:35 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')]} 21:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:34:35 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 0x110097a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11012c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab70566720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fffbd30>, '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 0x10fd60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fff3b20>, '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.FhMv6cwCdT/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.VksNfuKh38/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 21:34:35 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 21:34:35 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 21:34:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FhMv6cwCdT/Render', 21:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:34:35 INFO - 'HOME': '/Users/cltbld', 21:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:34:35 INFO - 'LOGNAME': 'cltbld', 21:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:34:35 INFO - 'MOZ_NO_REMOTE': '1', 21:34:35 INFO - 'NO_EM_RESTART': '1', 21:34:35 INFO - 'PAGER': '/bin/cat', 21:34:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:34:35 INFO - 'PWD': '/builds/slave/test', 21:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:34:35 INFO - 'SHELL': '/bin/bash', 21:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VksNfuKh38/Listeners', 21:34:35 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 21:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:34:35 INFO - 'USER': 'cltbld', 21:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:34:35 INFO - 'XPC_FLAGS': '0x0', 21:34:35 INFO - 'XPC_SERVICE_NAME': '0', 21:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:34:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:34:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:34:36 INFO - Cleaning up... 21:34:36 INFO - Return code: 0 21:34:36 INFO - Running post-action listener: _resource_record_post_action 21:34:36 INFO - Running post-action listener: _start_resource_monitoring 21:34:36 INFO - Starting resource monitoring. 21:34:36 INFO - [mozharness: 2016-09-07 04:34:36.140148Z] Finished create-virtualenv step (success) 21:34:36 INFO - [mozharness: 2016-09-07 04:34:36.141213Z] Running install step. 21:34:36 INFO - Running pre-action listener: _resource_record_pre_action 21:34:36 INFO - Running main action method: install 21:34:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:34:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:34:36 INFO - Reading from file tmpfile_stdout 21:34:36 INFO - Detecting whether we're running mozinstall >=1.0... 21:34:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:34:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:34:36 INFO - Reading from file tmpfile_stdout 21:34:36 INFO - Output received: 21:34:36 INFO - Usage: mozinstall [options] installer 21:34:36 INFO - Options: 21:34:36 INFO - -h, --help show this help message and exit 21:34:36 INFO - -d DEST, --destination=DEST 21:34:36 INFO - Directory to install application into. [default: 21:34:36 INFO - "/builds/slave/test"] 21:34:36 INFO - --app=APP Application being installed. [default: firefox] 21:34:36 INFO - mkdir: /builds/slave/test/build/application 21:34:36 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'] 21:34:36 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 21:35:10 INFO - Reading from file tmpfile_stdout 21:35:10 INFO - Output received: 21:35:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:35:10 INFO - Running post-action listener: _resource_record_post_action 21:35:10 INFO - [mozharness: 2016-09-07 04:35:10.477246Z] Finished install step (success) 21:35:10 INFO - [mozharness: 2016-09-07 04:35:10.477405Z] Running run-tests step. 21:35:10 INFO - Running pre-action listener: _resource_record_pre_action 21:35:10 INFO - Running main action method: run_tests 21:35:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:35:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:35:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:35:10 INFO - 2016-09-06 21:35:10.491 screenresolution[2364:548297] starting screenresolution argv=screenresolution get 21:35:10 INFO - 2016-09-06 21:35:10.494 screenresolution[2364:548297] Display 0: 1600x1200x32@60 21:35:10 INFO - 2016-09-06 21:35:10.499 screenresolution[2365:548299] starting screenresolution argv=screenresolution list 21:35:10 INFO - Available Modes on Display 0 21:35:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:35:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:35:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:35:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:35:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:35:10 INFO - Intel Iris: 21:35:10 INFO - Chipset Model: Intel Iris 21:35:10 INFO - Type: GPU 21:35:10 INFO - Bus: Built-In 21:35:10 INFO - VRAM (Dynamic, Max): 1536 MB 21:35:10 INFO - Vendor: Intel (0x8086) 21:35:10 INFO - Device ID: 0x0a2e 21:35:10 INFO - Revision ID: 0x0009 21:35:10 INFO - Displays: 21:35:10 INFO - Display: 21:35:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:35:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:35:10 INFO - Display Serial Number: 1600x1200 60 21:35:10 INFO - Main Display: Yes 21:35:10 INFO - Mirror: Off 21:35:10 INFO - Online: Yes 21:35:10 INFO - Rotation: Supported 21:35:10 INFO - Return code: 0 21:35:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:35:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:35:10 INFO - Python 2.7.3 21:35:10 INFO - Return code: 0 21:35:10 INFO - grabbing minidump binary from tooltool 21:35:10 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')]} 21:35:10 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 0x10fffbd30>, '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 0x10fd60db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fff3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:35:10 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 21:35:10 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 21:35:10 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 21:35:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:35:10 INFO - Return code: 0 21:35:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:35:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:35:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:35:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:35:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:35:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:35:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0383 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:35:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FhMv6cwCdT/Render', 21:35:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:35:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:35:10 INFO - 'HOME': '/Users/cltbld', 21:35:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:35:10 INFO - 'LOGNAME': 'cltbld', 21:35:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:35:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:35:10 INFO - 'MOZ_NO_REMOTE': '1', 21:35:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:35:10 INFO - 'NO_EM_RESTART': '1', 21:35:10 INFO - 'PAGER': '/bin/cat', 21:35:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:35:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:35:10 INFO - 'PWD': '/builds/slave/test', 21:35:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:35:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:35:10 INFO - 'SHELL': '/bin/bash', 21:35:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VksNfuKh38/Listeners', 21:35:10 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 21:35:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:35:10 INFO - 'USER': 'cltbld', 21:35:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:35:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:35:10 INFO - 'XPC_FLAGS': '0x0', 21:35:10 INFO - 'XPC_SERVICE_NAME': '0', 21:35:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:35:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:35:11 INFO - mozversion application_buildid: 20160906192717 21:35:11 INFO - mozversion application_changeset: 5f10d3f47379248797c381742d69af738cf31aad 21:35:11 INFO - mozversion application_display_name: Nightly 21:35:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:35:11 INFO - mozversion application_name: Firefox 21:35:11 INFO - mozversion application_remotingname: firefox 21:35:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:35:11 INFO - mozversion application_vendor: Mozilla 21:35:11 INFO - mozversion application_version: 51.0a1 21:35:11 INFO - mozversion platform_buildid: 20160906192717 21:35:11 INFO - mozversion platform_changeset: 5f10d3f47379248797c381742d69af738cf31aad 21:35:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:35:11 INFO - mozversion platform_version: 51.0a1 21:35:11 INFO - using testdate: 1473222911 21:35:11 INFO - actual date: 1473222911 21:35:11 INFO - starting webserver on 'localhost:49495' 21:35:11 INFO - SUITE-START | Running 2 tests 21:35:11 INFO - TEST-START | dromaeo_css 21:35:11 INFO - operating with platform_type : mac_ 21:35:11 INFO - Initialising browser for dromaeo_css test... 21:35:11 INFO - TEST-INFO | started process 2375 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpWX7Djy/profile http://localhost:49495/getInfo.html) 21:35:14 INFO - PROCESS | 2375 | __metrics Screen width/height:1600/1200 21:35:14 INFO - PROCESS | 2375 | colorDepth:24 21:35:14 INFO - PROCESS | 2375 | Browser inner width/height: 1024/689 21:35:14 INFO - PROCESS | 2375 | __metrics 21:35:14 ERROR - PROCESS | 2375 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 21:35:14 INFO - TEST-INFO | 2375: exit 0 21:35:14 INFO - Browser initialized. 21:35:14 INFO - Running cycle 1/1 for dromaeo_css test... 21:35:14 INFO - TEST-INFO | started process 2380 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpWX7Djy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:35:26 INFO - PROCESS | 2380 | RSS: Main: 244060160 21:35:26 INFO - PROCESS | 2380 | 21:36:16 INFO - PROCESS | 2380 | 1473222976356 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] 21:38:28 INFO - PROCESS | 2380 | Cycle 1(1): loaded http://localhost:49495/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49495/tests/dromaeo/cssquery-ext.html) 21:38:30 INFO - PROCESS | 2380 | RSS: Main: 266432512 21:38:30 INFO - PROCESS | 2380 | 21:41:32 INFO - PROCESS | 2380 | Cycle 1(1): loaded http://localhost:49495/tests/dromaeo/cssquery-ext.html (next: http://localhost:49495/tests/dromaeo/cssquery-jquery.html) 21:41:33 INFO - PROCESS | 2380 | RSS: Main: 272556032 21:41:33 INFO - PROCESS | 2380 | 21:44:35 INFO - PROCESS | 2380 | Cycle 1(1): loaded http://localhost:49495/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49495/tests/dromaeo/cssquery-mootools.html) 21:44:35 INFO - PROCESS | 2380 | RSS: Main: 272998400 21:44:35 INFO - PROCESS | 2380 | 21:47:37 INFO - PROCESS | 2380 | Cycle 1(1): loaded http://localhost:49495/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49495/tests/dromaeo/cssquery-prototype.html) 21:47:38 INFO - PROCESS | 2380 | RSS: Main: 268386304 21:47:38 INFO - PROCESS | 2380 | 21:50:41 INFO - PROCESS | 2380 | Cycle 1(1): loaded http://localhost:49495/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49495/tests/dromaeo/cssquery-yui.html) 21:50:41 INFO - PROCESS | 2380 | RSS: Main: 274681856 21:50:41 INFO - PROCESS | 2380 | 21:53:44 INFO - PROCESS | 2380 | Cycle 1(1): loaded http://localhost:49495/tests/dromaeo/cssquery-yui.html (next: http://localhost:49495/tests/dromaeo/cssquery-dojo.html) 21:53:44 INFO - PROCESS | 2380 | RSS: Main: 277368832 21:53:44 INFO - PROCESS | 2380 | 21:53:44 INFO - PROCESS | 2380 | __start_tp_report 21:53:44 INFO - PROCESS | 2380 | _x_x_mozilla_page_load 21:53:44 INFO - PROCESS | 2380 | _x_x_mozilla_page_load_details 21:53:44 INFO - PROCESS | 2380 | |i|pagename|runs| 21:53:44 INFO - PROCESS | 2380 | |0;dojo.html;3396.626371099179;3435.7079648229883;3436;3438.9484157737625;3464.2725027744173;5711.628744131628;5855.824325270243;5883.999720047592;5886.5585081118925;5960.761569537219;4561.7491037992895;4655.837045703401;5049.889024414623;5757.68332741699;5876.206712093866;4333.978330108345;4356.978215108936;5238.397584277819;5329.413764485904;5423.132298832187;5388.676679399229;5433.103537916259;5442.428545002777;5480.3971563128025;5494.780208791643;5214.139666954986;5239.292695486137;5269.789208431658;5272.657277277003;5303.151495760679;4372.884914346853;5274.736263186825;5286.07493688603;5311.973440132774;5354.732263386815;5254.65844720092;5297.284866543045;5341.706206158659;5362.088444964326;5394.352677678704;5723.828285151452;5829.242198514166;5913.852153696149;5929.021711417611;5963.791267305667;5206.583473322125;6207.6275423474735;6227.470664993481;6273.811785646438;6282.026285925689;6255.96872015637;6304.432601065926;6318.810435686937;6339;6358.1734374531015;5888.911666325009;6002.339742628307;6018.939810601925;6024.427679370453;6100.511959043266;5508.834734957958;6357.96821015892;6395.840103997437;6397.488200943913;6415.1981002374705;5789.2763404574425;6247;6282.246130464374;6288.182536270256;6312.779052733133;6155.415235552616;6195.070739389128;6219.284782250099;6238.1890354254565;6345.460635846005;6115.179495343419;6154.753809847645;6212.875742485125;6230.6573138476915;6273.466755325849;6206.348333425016;6232.688365581703;6259.874802503924;6267.4045965633195;6271.310155882849;4957.553820156203;5004.299398084271;5023.522765337287;5083.847484575469;5139.049275883974;4509.346144808985;4542.772861356919;4593.908121837611;4606.8157273708675;4641.350210970467;5887.293524776968;5975.073863551156;5981.700914954235;5994.310654274727;6006.579539432285;6180.6291622502795;6187.876242475125;6188.195534580475;6281.811545653638;6310.368963103745;5946.821595352147;5947.37552556984;5979.790707325223;6015.789447369321;6031.9396806032255;5582.720863956786;5590.24531688225;5593.244911936918;5604.187392828017;5608.915866262009;6259.311475737756;6331.2085989251345;6340.651264180515;6455.903161452582;6490.286068532458;4823.758812059453;6032.944234758812;6204.968975155095;6236.937630623635;6256.90504161779;5463.890722185613;6444.065610486547;6451.3548645135115;6469.191351081115;6495.74017039332;5942.1978032965;6146.0473626587955;6192.349803270592;6236.126942228182;6308.842278943063;6290.2766181889665;6313.179286692701;6329.30377658448;6340.334264902119;6340.49276057905;4856.271559266138;6181.96909015452;6196.907046394398;6226.750929962932;6254.343293954069;6115.755369785159;6154.661493617894;6171.506279497719;6239.319635973518;6265.624062556261;5373.462653734595;5924.763009479745;6089.451949324582;6135.601185922901;6144.433169541739;6152.830962117184;6209.720562574786;6224.37756224374;6264.123022776906;6288.088227207121;5733.111367738007;5993.370696077023;6090.756369745161;6130.969345153246;6156.445919867234 21:53:44 INFO - PROCESS | 2380 | |1;ext.html;500.2421331682502;519.6907839835296;526.8067826373265;529.2194013829601;549.8103154411726;37940.620593794054;40499.79750101245;40512.79743601278;40697.59302406975;40819.795901020494;41129.383059254134;41860.372094418606;41878.16243675132;41897.810218978084;41927.58072419283;9620.278479114077;9763.511824408786;9790.412575245507;9799.16707079899;9823.410595364265;9647.324687271894;9651.855222171673;9745.805083898318;9763.560639771193;9784;9757.170640495566;9766.609335626566;9770.902290977107;9775.560099795526;9784.853227201598;9581.329306948446;9645.614175432975;9690.660826871026;9708.417494950325;9708.805823883553;9625.518724063839;9717.46553939533;9720;9746.122848943558;9746.658866939693;38824.61175388238;39487.21025579501;39800.20399592021;39859;39929;3458.792472451661;3752.1745216052695;4007.318755811519;4007.5391329997133;4009.51885773709;30075.548866767014;30420.695793042007;30573.847130764425;30614;30638.84680576605;3164.3671265746943;3206.374756922401;3219.3722224166295;3280.934381312384;3281.146901805524;386785;387025;388929.05535472423;389099;390528.0473597642;32348.514772278435;33341.499877501854;57779.4222057774;58298;58569.41430585639;34713.479297810554;34881.825590871886;34894.65105348965;35177.296454070776;35197.472037919455;14640;16186.919065404833;16441.26014329358;16576.83423165753;16994.915025424794;12718.491260349667;12804.231746095264;12817.743645127046;12835.48658053686;12902.419391127421;18926.148323325484;18934.337298194772;18992.905035474734;19045.523861903563;19111.426657200307;6853.554518956252;8080.39397045224;8085.919140808634;8129.227723366271;8172.42793004484;828.329053466694;910.349100393222;931.6552875435997;995.6515219673056;1005.6178652112296;29596.55605165924;29616.963406280676;30035.849820750755;30396.84801575978;30499.69500305013;824.5217773691245;1007.3804610164842;1052.3633201912885;1067.9442074007504;1089.983650245247;12949.935250323688;13678.658033549042;13882.930585347009;14573.781393279109;15245.695086098216;686979.1302087015;693898;696538;699160;700090;57048;57511;57822;57853;57969.42030579725;1344.529414704865;1372.4235820955223;1377.4490203918513;1380.0891411668251;1382.4608402722745;25147.622785657863;27391.493467859458;29067;29206.7079329204;29882.8505857478;39437;48778.512214877395;48928.26607600818;49015.75492122517;49192.75403622959;574987;580931;583167;583961;586599;25788.742112578635;25894.223173304832;25978.090766823825;26223.344416389744;26392.8680356597;45331.64005079853;45674.77162614166;46287;46639.76680116577;46692;38103.428448573846;38290.83073785003;38532.42201366926;39325.213495730495;39877.80061099676;38548.80725596354;38679;38838.33939927227;38880.80559597184;40073.998150046486;33274.33451331008;38115.42826857655;38334.42498362471;38431.80784096062;38644;32057.839710801298;32105.839470802497;33407.331853363285;34442.311153777286;34592.135196620286;25173.370665733502;25334;25453.745462545878;26176.476470470865;26218 21:53:44 INFO - PROCESS | 2380 | |2;jquery.html;333.37492202121024;370.6497359994806;376.46165982644817;385.4911516797828;402.77444631006637;17852.64294714105;19803.910784906824;21039.47401314964;21280.893595532038;21528.784712152872;9739.610415583367;12192.390380480963;12214.145009849291;12227.38863056846;12935.611931642043;5254.737263136847;5532.474414930575;5537.723113844312;5545.77816887324;5564.165375193733;3945.7829819360068;5523.447655234485;5860.882782344351;6132.754689812425;6197.969010154943;6147.293061297941;6191.504679625619;6212.502999760031;6225.47083497901;6232.594881332721;6372.394622510854;6718.193816741998;6783.592984420901;6834.282400347991;6839.897401538981;5789.478946894757;5954.672493012882;5994.880102397971;6022.69886505673;6045.33501314855;13088.280144592043;13298.534551290659;13331.86668133316;15468.381264749509;25028.624570631455;2072.067569593689;2101.117530637136;2188.277868303456;2541.440883005736;2619.3975385661215;10370.637027703995;10801.243912926098;12838.16551924122;13503.864961350358;14718.70562588753;1558.9844101559065;1894.1192345559382;1997.2011195521761;2012.3560460652598;2053.6303465376377;83998.58000709958;106908.465457673;110827.9999999992;111363.44318278438;113084;11466.770664586662;11493.080553555696;11595.840415958337;11600.709982250342;11718.531258749725;14454.421823127168;14497.057691250035;14561.708765824624;14579.708405832036;14585.343659535134;8457.900472938478;8616.310695144499;8643.870341944876;8675.869861952076;8808.867866982;7019.964900175466;8605.741827745178;8662.393632445794;8788.869231461204;8825.911740882637;4466.709663871838;4509.503954564995;4511.661625378109;4519.909601807945;4547.113312903952;1575.2123938030984;1660.5433505786004;1678.630701245724;1910.2836436336374;1964;732.2604169788445;887.2591386192602;997.7605374710017;1003.4531180506641;1016.8474728790593;15164.393424262906;16048;16788.6642267154;21491.462713431978;21602.351929442142;953.666216824106;968.4431451915177;976.5507866381402;1023.744063984004;1032.8915463876335;10380.96190380971;11295.912676280755;11340.6030788922;11358;11416.31502109876;584545;595040;600800;606058;608280;103783.48108259411;128642.35678821734;133904;134005;134061.318773624;991.8710567626307;1029.5675816157081;1051.8001579699833;1055.308772753832;1057.5241141486363;13554.59336219915;18814;18852;19161.425157245307;19200.94394808243;42398.576014239465;53237.7338113307;55004.89990200254;55254;55437.72281138568;75816;78072.60963695146;78660;80533;81182;6961;7780.377569794516;7958.801029974065;7977.7207797726805;7998.880016799869;11109.333439993627;11387.804280550423;11609.941950290195;12074.939625301817;12187.268763874195;10548.820413103325;10589.046985771318;10667.946660266649;10810.621628242974;11123.888761112285;10500.842487362848;10591.841122383325;10644.308119972176;10757.892421075689;10850.511726972147;10912.7271818202;11072.28030178068;11232.4383780809;11379.317240965569;11423.657290281304;7895.249951254622;8051.436399451982;8054.718084867001;8125.268725814469;8198.344132469507;10080.798384032425;10337.932413517176;10802;11066.778664426505;11207.271527350696 21:53:44 INFO - PROCESS | 2380 | |3;mootools.html;1447.0304895719867;1454.2219912346895;1661.3852874436461;1828.2412798688545;1870.4014715291103;7895.960520197391;8441.409101362899;8505.74482765518;8967.596458159382;8992.550372481373;8558.957205213981;8663.436876603017;8750.343724220676;8763.167499087576;8790.736277911657;2354.3996280948377;2374.050379848057;2390.4621460171497;2393.1743548475833;2395.5448464791634;2352.6353415220583;2354.2348736660656;2393.401649587603;2400.531896280226;2405.0139442828445;2043.6117137743856;2380.940476488093;2381.1189859751917;2381.249906279525;2389.116027069978;2027.3715148303925;2035.8167764901157;2120.8515403921733;2233.575620632083;2316.629339305703;1991.412533302679;2003.6293285742183;2316.6409206573007;2340.1809366721513;2351.1183306260154;7233.927660723378;8715.172058654418;8751.868721969111;8815.118488151134;8829.381943263974;5094.1594636884865;5237.319148510669;5248.6588371755715;5249.16013437852;5252.8686782830355;3486.250456151914;3582.5700915889997;3653.6711695947492;3716.4425336199515;3717.6654101130757;1052.5474046160148;1067.4368956423434;1104.950277237518;1124.7131981344787;1126.6957921361186;11317.717057073556;11492;11516.251443656136;11640.301581905029;11644.359560224184;4526.388937493396;4626.537346265414;4781.402324709411;4831;4845.9757701211265;8648.394612377073;9384.577694003787;9777.608895644236;9803.068708472683;9833.213342932548;5294.391145018295;5310.867228319245;5312.256284120226;5327.067763141512;5329.147336426153;4013.3979903014197;4371.737695738202;4737.549932756383;4863.489333619989;4869.63477739171;3828;3889.572147063821;3919.8828333925267;3973.980130099331;4003.619656132663;1123.5112725964232;1314.3625341709264;1316.9341532923506;1328.249539010456;1331.600519844043;797.8045378882211;858.2018722588053;898.0435835834814;912.4662072687552;942.3121121581285;4704.317873908264;5750.326836521867;5786.392428795;5813.244278243886;6048;930.3487558708931;943.056943056943;982.8771403574553;988.1551273661061;1006.6677996261217;3812.275667623143;3812.599677033927;3924.7645141291614;4003.6997225208224;4028.315186418316;10364.740881478023;10604.57581696739;10614.150867930546;10697.572097116028;10709.410982396043;10649.094826939798;10652.147828173727;10659.520321585547;10673.398990151383;10681.359118452918;7756.612169391507;8073.9192608074345;8147.79630509242;8154.38842086834;8212;5883.382244864228;5900.350961394157;5900.5574581907385;5966.671833049224;6082.057281121433;9787.657431989846;10055.798884022424;10084.394936303845;10109.39343639384;10136.847947280945;9714.077162669535;9984.750381240527;10136.239782016231;10173.389596624227;10227.02843229892;7309.122905251404;7480.3641690456925;7504.587247701429;7524.247575242432;7556.093268807778;8117.391195660231;8195.975503062116;8196.180381961994;8200.343972482306;8213.548254845802;6500.674966251763;7230.060092187982;7402.33378995893;7414.555126692416;7496.662650180646;6304.968475157595;7232.819179520554;7249.456290778318;7274.4907856449545;7294.671739771829;6727.529072964846;6833.077534532775;6908.654567271516;6932.861342773015;6936.445084393337;4889.63327750413;4932.926006109983;4948.529889660476;4976.104301225814;5034.119029169954;4878.756062196805;4936.111499930047;5002.924956125734;5054.595632349477;5080.415752188545 21:53:44 INFO - PROCESS | 2380 | |4;prototype.html;249.52091983391887;277.93607470281825;294.75829819547977;310.3049169859515;312.09181282468023;4358.389825424443;5256.526912577867;5350.652207606503;5357.678539287635;5553.694546799924;4910.484399138093;5349.491798279167;5380.300560927084;5462.071447853865;5469.206964990084;3480.3387356402327;4160.23190144189;4160.83356665733;4180.24755544002;4203.789810509477;3099.5195744659613;3564.679178873901;4187.832486700544;4193.727407718504;4200.810963506634;3838.692904567642;4020.035191554035;4043.575424580421;4045.898852528681;4046.7774272415004;4062.918741625151;4064.796760161995;4069.7354671946227;4075.2868248056766;4082.9387559186634;3524.8766293179874;3525.065857547752;3540.929181416383;3547.6629720176543;3547.875824346161;4311.353297005443;4579.702319349265;4779.689320194177;5296.390915044781;5332.280142180794;4090;4168.935832163357;4471.105778844244;4661.370714953472;4700.1539722849875;5411.702356370398;5471.616986810925;5756.395578464243;5803.9709801451145;5815;3613.4941108244866;3811.8475260989526;3896.649301562873;3898.161895192519;3906.277338692351;5598.916016259759;5736.942630573682;5994.790182343597;6059.363766804466;6167.044108163242;3878.70909681775;3923.3722604383734;3924.50943632046;3926.0577461409625;3926.5680775114715;3702.1674271235374;3901.4342920276968;3913.8630147944687;3975.7216994810665;3981.223661386002;4034.6973976951845;4313.676474264443;4615.653825963066;4621.4223222097235;4650.906981860411;5755.827325180251;5765.740541675635;5775.797847075332;5796.855078623068;5823.883522329615;1270.8157317188957;1322.259534660574;1346.7575836349356;1351.7904724767677;1356.3625096204769;871.1897934920459;894.9149830766066;904.8778414914034;909.3361845852564;909.7082143356449;4884.511548845087;5303.787848486095;5350.9732451337495;5351.785928562814;5356.848277620253;4723.527647235318;5421.322334708161;5843.883122337529;5889.910366582196;5914.8817023659285;4268.914621707549;4603.6777425579885;4657.394538709946;4801.903961920742;5486.695587853112;2277.8974946127464;2337.4974380508274;2420.7458216887326;2589.3267750384657;2596.2730435478097;4381.693281470267;4434.33484977256;4467.754273514988;5040.294358789773;5159.793608255703;4645.86062418128;4696.530346965276;4971.751412429364;5061.683962169863;5074.949250507521;4145.461090058213;4358.607725304738;4940.629452791055;4957.3307603473795;5081.644284900021;4352.542982986741;4901.676547332272;4902.264660300983;4939.295942899694;4980.775865086008;3894.883153505399;3930.9017274567223;4069.2878746218703;4470.508244093082;4832.710037397767;4431.401760762255;4832.734199619054;5089.9745501272255;5096.46487118862;5179.792808287626;2915.70251238197;3087.799293045945;3215.9196020099685;3349.5310656507622;3380.4253276942486;4172.728772629769;4489.887752806076;4571.537108125368;4656.697314674535;4964.6524743269065;5272.420033796356;5678.3186017678145;5679.716014199356;5693.459121383462;5733.248040940289;5571.799139340019;5734.426557344233;5756.89127870945;5782.277215348082;5782.913256301243;5089.440161582149;5105.591552675852;5169.2941329360465;5568.387477377566;5580.38615752275;5485.232067510631;5683.465464324692;5731.570132240182;5764.567657425794;5830.47525722687;4839.927401088916;5551.916721249266;5624.887502250014;5668.971655141698;5676.517496012885 21:53:44 INFO - PROCESS | 2380 | |5;yui.html;706.1667232665454;725.434161354144;729.6898818002347;738.4609235258259;741.7811745535066;15164.77252841207;15601.219939003062;15787.526374208763;15817.050976941391;16635.83364166361;16098.83901160988;16108;16136.919315403407;16139.677206455894;16158.676826463463;848.088305072047;865.1564724393697;869.7086476030537;871.5685735560907;874.7244617945358;685.7736946807566;726.803762983994;861.9914699801217;874.7419511244167;878.4948654523643;904.9547522623875;918.8989211186764;919.5310391700248;923.7044145873323;925.7222833150062;906.8639704044413;913.4062859141545;917.5824999625155;918.0085507651787;918.3158546882548;682.3551743602297;683.7538486144983;684.7124207832723;685.1401491128652;688.3323176518819;15070.171140587263;15171.165585892772;15176.620584485367;15181;15253.847461525354;14060.929695351459;14109.85890141106;14114.576562703136;14145.080569763035;14165.575032749033;11923.642290731292;11925.880741192565;11969.82045269313;11995.520179192821;12278.263304201777;3664.633536646341;3794.260119276742;3837.6929845612563;3919.1573811630637;3993.7004724645767;102375;103839;104069.43895841569;108888.45555772171;110556.23609409749;10407.94796026015;10412.114970227616;10474.486123330498;10604.204684648683;10847.67456976292;21529.246476373253;21561.137554497956;21561.784382156293;21609.135634574755;21634.56730865374;7600.087989441303;7629.809254768676;7654.2345765424125;7680;7896.802579935547;4423.181711383404;4439.755813430228;4439.866803995886;4505.986153115588;4591.127685739699;3534.4168212245204;3665.6884164845756;3666.6333366663653;3683.5211422514903;3815.751976121543;609.3966972696998;699.1086364884812;715.5313269808283;723.5550136665912;725.8802297620939;554.8058179637095;568.6985897474345;604.3473049106954;610.8045425463808;665.4809248785955;8858.778530536789;9180.632774689071;9642.180414664832;9702.611895524242;9707.225862128915;580.4659713063992;621.2979333353372;653.1313353240179;662.8342914271432;666.5534092158266;3722.664960153599;4069.4099355593867;4520.796564154621;4634.814607415733;4679.3916790816975;129526.70473295335;134144;157514.6372804409;171964.1401792983;173581;148868;151027;151940;153382;153561;126739.36630316789;128279.35860320825;128732;129977.35011325072;130753.69246307605;7862.685492580462;9054.773630659287;9098.408603440756;9152.542372881462;9200.435925892749;68072.6596367015;70097.29902700908;71872;72045.95678302468;72722.63638681773;99697.50151249197;102689;106824.46587767011;107730;110655;10324.328918620266;11421.771564568828;11815.881841181823;12020.218685785396;12153.088518361335;12979.351032448529;13130.934345328213;13161.341932903508;13534.984876134526;13894.652633683834;12004.256190709091;12252.87747122553;12264.87735122673;12414.379281036092;12668.113232073667;11892.227005244631;12231.143819932518;12287.385630718607;12289.815652765037;12684.11211215243;11919.3444360561;12061.698457538632;12191.451384687887;12254.671612393617;12610.936945315214;8491.405601607828;10066.396016239049;10260.486975651038;10388.324758890649;10621.840672389766;8636.769644559145;10523.15814734804;10535.420551869414;10557.94420557938;10593.417362044853 21:53:44 INFO - PROCESS | 2380 | __end_tp_report 21:53:44 INFO - PROCESS | 2380 | __start_cc_report 21:53:44 INFO - PROCESS | 2380 | _x_x_mozilla_cycle_collect,386 21:53:44 INFO - PROCESS | 2380 | __end_cc_report 21:53:44 INFO - PROCESS | 2380 | __startTimestamp1473224024802__endTimestamp 21:53:44 INFO - PROCESS | 2380 | 21:53:44 INFO - PROCESS | 2380 | ------- Summary: start ------- 21:53:44 INFO - PROCESS | 2380 | Number of tests: 6 21:53:44 INFO - PROCESS | 2380 | 21:53:44 INFO - PROCESS | 2380 | [#0] dojo.html Cycles:165 Average:5776.68 Median:6018.94 stddev:670.42 (11.1%) stddev-sans-first:645.96 21:53:44 INFO - PROCESS | 2380 | Values: 3396.6 3435.7 3436.0 3438.9 3464.3 5711.6 5855.8 5884.0 5886.6 5960.8 4561.7 4655.8 5049.9 5757.7 5876.2 4334.0 4357.0 5238.4 5329.4 5423.1 5388.7 5433.1 5442.4 5480.4 5494.8 5214.1 5239.3 5269.8 5272.7 5303.2 4372.9 5274.7 5286.1 5312.0 5354.7 5254.7 5297.3 5341.7 5362.1 5394.4 5723.8 5829.2 5913.9 5929.0 5963.8 5206.6 6207.6 6227.5 6273.8 6282.0 6256.0 6304.4 6318.8 6339.0 6358.2 5888.9 6002.3 6018.9 6024.4 6100.5 5508.8 6358.0 6395.8 6397.5 6415.2 5789.3 6247.0 6282.2 6288.2 6312.8 6155.4 6195.1 6219.3 6238.2 6345.5 6115.2 6154.8 6212.9 6230.7 6273.5 6206.3 6232.7 6259.9 6267.4 6271.3 4957.6 5004.3 5023.5 5083.8 5139.0 4509.3 4542.8 4593.9 4606.8 4641.4 5887.3 5975.1 5981.7 5994.3 6006.6 6180.6 6187.9 6188.2 6281.8 6310.4 5946.8 5947.4 5979.8 6015.8 6031.9 5582.7 5590.2 5593.2 5604.2 5608.9 6259.3 6331.2 6340.7 6455.9 6490.3 4823.8 6032.9 6205.0 6236.9 6256.9 5463.9 6444.1 6451.4 6469.2 6495.7 5942.2 6146.0 6192.3 6236.1 6308.8 6290.3 6313.2 6329.3 6340.3 6340.5 4856.3 6182.0 6196.9 6226.8 6254.3 6115.8 6154.7 6171.5 6239.3 6265.6 5373.5 5924.8 6089.5 6135.6 6144.4 6152.8 6209.7 6224.4 6264.1 6288.1 5733.1 5993.4 6090.8 6131.0 6156.4 21:53:44 INFO - PROCESS | 2380 | 21:53:44 INFO - PROCESS | 2380 | [#1] ext.html Cycles:180 Average:67759.87 Median:389014.03 stddev:152733.97 (39.3%) stddev-sans-first:153078.95 21:53:44 INFO - PROCESS | 2380 | Values: 500.2 519.7 526.8 529.2 549.8 37940.6 40499.8 40512.8 40697.6 40819.8 41129.4 41860.4 41878.2 41897.8 41927.6 9620.3 9763.5 9790.4 9799.2 9823.4 9647.3 9651.9 9745.8 9763.6 9784.0 9757.2 9766.6 9770.9 9775.6 9784.9 9581.3 9645.6 9690.7 9708.4 9708.8 9625.5 9717.5 9720.0 9746.1 9746.7 38824.6 39487.2 39800.2 39859.0 39929.0 3458.8 3752.2 4007.3 4007.5 4009.5 30075.5 30420.7 30573.8 30614.0 30638.8 3164.4 3206.4 3219.4 3280.9 3281.1 386785.0 387025.0 388929.1 389099.0 390528.0 32348.5 33341.5 57779.4 58298.0 58569.4 34713.5 34881.8 34894.7 35177.3 35197.5 14640.0 16186.9 16441.3 16576.8 16994.9 12718.5 12804.2 12817.7 12835.5 12902.4 18926.1 18934.3 18992.9 19045.5 19111.4 6853.6 8080.4 8085.9 8129.2 8172.4 828.3 910.3 931.7 995.7 1005.6 29596.6 29617.0 30035.8 30396.8 30499.7 824.5 1007.4 1052.4 1067.9 1090.0 12949.9 13678.7 13882.9 14573.8 15245.7 686979.1 693898.0 696538.0 699160.0 700090.0 57048.0 57511.0 57822.0 57853.0 57969.4 1344.5 1372.4 1377.4 1380.1 1382.5 25147.6 27391.5 29067.0 29206.7 29882.9 39437.0 48778.5 48928.3 49015.8 49192.8 574987.0 580931.0 583167.0 583961.0 586599.0 25788.7 25894.2 25978.1 26223.3 26392.9 45331.6 45674.8 46287.0 46639.8 46692.0 38103.4 38290.8 38532.4 39325.2 39877.8 38548.8 38679.0 38838.3 38880.8 40074.0 33274.3 38115.4 38334.4 38431.8 38644.0 32057.8 32105.8 33407.3 34442.3 34592.1 25173.4 25334.0 25453.7 26176.5 26218.0 21:53:44 INFO - PROCESS | 2380 | 21:53:44 INFO - PROCESS | 2380 | [#2] jquery.html Cycles:180 Average:34104.18 Median:2086.59 stddev:99798.86 (4782.9%) stddev-sans-first:100046.61 21:53:44 INFO - PROCESS | 2380 | Values: 333.4 370.6 376.5 385.5 402.8 17852.6 19803.9 21039.5 21280.9 21528.8 9739.6 12192.4 12214.1 12227.4 12935.6 5254.7 5532.5 5537.7 5545.8 5564.2 3945.8 5523.4 5860.9 6132.8 6198.0 6147.3 6191.5 6212.5 6225.5 6232.6 6372.4 6718.2 6783.6 6834.3 6839.9 5789.5 5954.7 5994.9 6022.7 6045.3 13088.3 13298.5 13331.9 15468.4 25028.6 2072.1 2101.1 2188.3 2541.4 2619.4 10370.6 10801.2 12838.2 13503.9 14718.7 1559.0 1894.1 1997.2 2012.4 2053.6 83998.6 106908.5 110828.0 111363.4 113084.0 11466.8 11493.1 11595.8 11600.7 11718.5 14454.4 14497.1 14561.7 14579.7 14585.3 8457.9 8616.3 8643.9 8675.9 8808.9 7020.0 8605.7 8662.4 8788.9 8825.9 4466.7 4509.5 4511.7 4519.9 4547.1 1575.2 1660.5 1678.6 1910.3 1964.0 732.3 887.3 997.8 1003.5 1016.8 15164.4 16048.0 16788.7 21491.5 21602.4 953.7 968.4 976.6 1023.7 1032.9 10381.0 11295.9 11340.6 11358.0 11416.3 584545.0 595040.0 600800.0 606058.0 608280.0 103783.5 128642.4 133904.0 134005.0 134061.3 991.9 1029.6 1051.8 1055.3 1057.5 13554.6 18814.0 18852.0 19161.4 19200.9 42398.6 53237.7 55004.9 55254.0 55437.7 75816.0 78072.6 78660.0 80533.0 81182.0 6961.0 7780.4 7958.8 7977.7 7998.9 11109.3 11387.8 11609.9 12074.9 12187.3 10548.8 10589.0 10667.9 10810.6 11123.9 10500.8 10591.8 10644.3 10757.9 10850.5 10912.7 11072.3 11232.4 11379.3 11423.7 7895.2 8051.4 8054.7 8125.3 8198.3 10080.8 10337.9 10802.0 11066.8 11207.3 21:53:44 INFO - PROCESS | 2380 | 21:53:44 INFO - PROCESS | 2380 | [#3] mootools.html Cycles:180 Average:5641.19 Median:4911.28 stddev:3157.07 (64.3%) stddev-sans-first:3150.19 21:53:44 INFO - PROCESS | 2380 | Values: 1447.0 1454.2 1661.4 1828.2 1870.4 7896.0 8441.4 8505.7 8967.6 8992.6 8559.0 8663.4 8750.3 8763.2 8790.7 2354.4 2374.1 2390.5 2393.2 2395.5 2352.6 2354.2 2393.4 2400.5 2405.0 2043.6 2380.9 2381.1 2381.2 2389.1 2027.4 2035.8 2120.9 2233.6 2316.6 1991.4 2003.6 2316.6 2340.2 2351.1 7233.9 8715.2 8751.9 8815.1 8829.4 5094.2 5237.3 5248.7 5249.2 5252.9 3486.3 3582.6 3653.7 3716.4 3717.7 1052.5 1067.4 1105.0 1124.7 1126.7 11317.7 11492.0 11516.3 11640.3 11644.4 4526.4 4626.5 4781.4 4831.0 4846.0 8648.4 9384.6 9777.6 9803.1 9833.2 5294.4 5310.9 5312.3 5327.1 5329.1 4013.4 4371.7 4737.5 4863.5 4869.6 3828.0 3889.6 3919.9 3974.0 4003.6 1123.5 1314.4 1316.9 1328.2 1331.6 797.8 858.2 898.0 912.5 942.3 4704.3 5750.3 5786.4 5813.2 6048.0 930.3 943.1 982.9 988.2 1006.7 3812.3 3812.6 3924.8 4003.7 4028.3 10364.7 10604.6 10614.2 10697.6 10709.4 10649.1 10652.1 10659.5 10673.4 10681.4 7756.6 8073.9 8147.8 8154.4 8212.0 5883.4 5900.4 5900.6 5966.7 6082.1 9787.7 10055.8 10084.4 10109.4 10136.8 9714.1 9984.8 10136.2 10173.4 10227.0 7309.1 7480.4 7504.6 7524.2 7556.1 8117.4 8196.0 8196.2 8200.3 8213.5 6500.7 7230.1 7402.3 7414.6 7496.7 6305.0 7232.8 7249.5 7274.5 7294.7 6727.5 6833.1 6908.7 6932.9 6936.4 4889.6 4932.9 4948.5 4976.1 5034.1 4878.8 4936.1 5002.9 5054.6 5080.4 21:53:44 INFO - PROCESS | 2380 | 21:53:44 INFO - PROCESS | 2380 | [#4] prototype.html Cycles:180 Average:4362.82 Median:4678.95 stddev:1354.19 (28.9%) stddev-sans-first:1322.32 21:53:44 INFO - PROCESS | 2380 | Values: 249.5 277.9 294.8 310.3 312.1 4358.4 5256.5 5350.7 5357.7 5553.7 4910.5 5349.5 5380.3 5462.1 5469.2 3480.3 4160.2 4160.8 4180.2 4203.8 3099.5 3564.7 4187.8 4193.7 4200.8 3838.7 4020.0 4043.6 4045.9 4046.8 4062.9 4064.8 4069.7 4075.3 4082.9 3524.9 3525.1 3540.9 3547.7 3547.9 4311.4 4579.7 4779.7 5296.4 5332.3 4090.0 4168.9 4471.1 4661.4 4700.2 5411.7 5471.6 5756.4 5804.0 5815.0 3613.5 3811.8 3896.6 3898.2 3906.3 5598.9 5736.9 5994.8 6059.4 6167.0 3878.7 3923.4 3924.5 3926.1 3926.6 3702.2 3901.4 3913.9 3975.7 3981.2 4034.7 4313.7 4615.7 4621.4 4650.9 5755.8 5765.7 5775.8 5796.9 5823.9 1270.8 1322.3 1346.8 1351.8 1356.4 871.2 894.9 904.9 909.3 909.7 4884.5 5303.8 5351.0 5351.8 5356.8 4723.5 5421.3 5843.9 5889.9 5914.9 4268.9 4603.7 4657.4 4801.9 5486.7 2277.9 2337.5 2420.7 2589.3 2596.3 4381.7 4434.3 4467.8 5040.3 5159.8 4645.9 4696.5 4971.8 5061.7 5074.9 4145.5 4358.6 4940.6 4957.3 5081.6 4352.5 4901.7 4902.3 4939.3 4980.8 3894.9 3930.9 4069.3 4470.5 4832.7 4431.4 4832.7 5090.0 5096.5 5179.8 2915.7 3087.8 3215.9 3349.5 3380.4 4172.7 4489.9 4571.5 4656.7 4964.7 5272.4 5678.3 5679.7 5693.5 5733.2 5571.8 5734.4 5756.9 5782.3 5782.9 5089.4 5105.6 5169.3 5568.4 5580.4 5485.2 5683.5 5731.6 5764.6 5830.5 4839.9 5551.9 5624.9 5669.0 5676.5 21:53:44 INFO - PROCESS | 2380 | 21:53:44 INFO - PROCESS | 2380 | [#5] yui.html Cycles:180 Average:26465.96 Median:21621.85 stddev:43787.22 (202.5%) stddev-sans-first:43867.34 21:53:44 INFO - PROCESS | 2380 | Values: 706.2 725.4 729.7 738.5 741.8 15164.8 15601.2 15787.5 15817.1 16635.8 16098.8 16108.0 16136.9 16139.7 16158.7 848.1 865.2 869.7 871.6 874.7 685.8 726.8 862.0 874.7 878.5 905.0 918.9 919.5 923.7 925.7 906.9 913.4 917.6 918.0 918.3 682.4 683.8 684.7 685.1 688.3 15070.2 15171.2 15176.6 15181.0 15253.8 14060.9 14109.9 14114.6 14145.1 14165.6 11923.6 11925.9 11969.8 11995.5 12278.3 3664.6 3794.3 3837.7 3919.2 3993.7 102375.0 103839.0 104069.4 108888.5 110556.2 10407.9 10412.1 10474.5 10604.2 10847.7 21529.2 21561.1 21561.8 21609.1 21634.6 7600.1 7629.8 7654.2 7680.0 7896.8 4423.2 4439.8 4439.9 4506.0 4591.1 3534.4 3665.7 3666.6 3683.5 3815.8 609.4 699.1 715.5 723.6 725.9 554.8 568.7 604.3 610.8 665.5 8858.8 9180.6 9642.2 9702.6 9707.2 580.5 621.3 653.1 662.8 666.6 3722.7 4069.4 4520.8 4634.8 4679.4 129526.7 134144.0 157514.6 171964.1 173581.0 148868.0 151027.0 151940.0 153382.0 153561.0 126739.4 128279.4 128732.0 129977.4 130753.7 7862.7 9054.8 9098.4 9152.5 9200.4 68072.7 70097.3 71872.0 72046.0 72722.6 99697.5 102689.0 106824.5 107730.0 110655.0 10324.3 11421.8 11815.9 12020.2 12153.1 12979.4 13130.9 13161.3 13535.0 13894.7 12004.3 12252.9 12264.9 12414.4 12668.1 11892.2 12231.1 12287.4 12289.8 12684.1 11919.3 12061.7 12191.5 12254.7 12610.9 8491.4 10066.4 10260.5 10388.3 10621.8 8636.8 10523.2 10535.4 10557.9 10593.4 21:53:44 INFO - PROCESS | 2380 | -------- Summary: end -------- 21:53:44 INFO - PROCESS | 2380 | 21:53:45 INFO - TEST-INFO | 2380: exit 0 21:53:45 INFO - TEST-OK | dromaeo_css | took 1114365ms 21:53:45 INFO - TEST-START | kraken 21:53:45 INFO - operating with platform_type : mac_ 21:53:45 INFO - Initialising browser for kraken test... 21:53:45 INFO - TEST-INFO | started process 2385 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmp58GxUl/profile http://localhost:49495/getInfo.html) 21:53:48 INFO - PROCESS | 2385 | __metrics Screen width/height:1600/1200 21:53:48 INFO - PROCESS | 2385 | colorDepth:24 21:53:48 INFO - PROCESS | 2385 | Browser inner width/height: 1024/689 21:53:48 INFO - PROCESS | 2385 | __metrics 21:53:48 ERROR - PROCESS | 2385 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 21:53:48 INFO - TEST-INFO | 2385: exit 0 21:53:48 INFO - Browser initialized. 21:53:48 INFO - Running cycle 1/1 for kraken test... 21:53:48 INFO - TEST-INFO | started process 2387 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmp58GxUl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:54:00 INFO - PROCESS | 2387 | RSS: Main: 244248576 21:54:00 INFO - PROCESS | 2387 | 21:54:03 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49495/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49495/tests/kraken/driver.html?testName=audio-beat-detection) 21:54:04 INFO - PROCESS | 2387 | RSS: Main: 257179648 21:54:04 INFO - PROCESS | 2387 | 21:54:07 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49495/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49495/tests/kraken/driver.html?testName=audio-dft) 21:54:07 INFO - PROCESS | 2387 | RSS: Main: 265789440 21:54:07 INFO - PROCESS | 2387 | 21:54:11 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49495/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49495/tests/kraken/driver.html?testName=audio-fft) 21:54:11 INFO - PROCESS | 2387 | RSS: Main: 263499776 21:54:11 INFO - PROCESS | 2387 | 21:54:13 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49495/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49495/tests/kraken/driver.html?testName=audio-oscillator) 21:54:14 INFO - PROCESS | 2387 | RSS: Main: 264925184 21:54:14 INFO - PROCESS | 2387 | 21:54:16 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49495/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49495/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:54:17 INFO - PROCESS | 2387 | RSS: Main: 265285632 21:54:17 INFO - PROCESS | 2387 | 21:54:19 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49495/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49495/tests/kraken/driver.html?testName=imaging-darkroom) 21:54:20 INFO - PROCESS | 2387 | RSS: Main: 266108928 21:54:20 INFO - PROCESS | 2387 | 21:54:23 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49495/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49495/tests/kraken/driver.html?testName=imaging-desaturate) 21:54:23 INFO - PROCESS | 2387 | RSS: Main: 266018816 21:54:23 INFO - PROCESS | 2387 | 21:54:27 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49495/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49495/tests/kraken/driver.html?testName=json-parse-financial) 21:54:27 INFO - PROCESS | 2387 | RSS: Main: 266420224 21:54:27 INFO - PROCESS | 2387 | 21:54:29 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49495/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49495/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:54:29 INFO - PROCESS | 2387 | RSS: Main: 267423744 21:54:29 INFO - PROCESS | 2387 | 21:54:31 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49495/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49495/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:54:32 INFO - PROCESS | 2387 | RSS: Main: 266780672 21:54:32 INFO - PROCESS | 2387 | 21:54:34 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49495/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49495/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:54:35 INFO - PROCESS | 2387 | RSS: Main: 266878976 21:54:35 INFO - PROCESS | 2387 | 21:54:38 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49495/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49495/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:54:39 INFO - PROCESS | 2387 | RSS: Main: 267124736 21:54:39 INFO - PROCESS | 2387 | 21:54:42 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49495/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49495/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:54:42 INFO - PROCESS | 2387 | RSS: Main: 267194368 21:54:42 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49495/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49495/tests/kraken/driver.html?testName=ai-astar) 21:54:44 INFO - PROCESS | 2387 | RSS: Main: 269340672 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | __start_tp_report 21:54:44 INFO - PROCESS | 2387 | _x_x_mozilla_page_load 21:54:44 INFO - PROCESS | 2387 | _x_x_mozilla_page_load_details 21:54:44 INFO - PROCESS | 2387 | |i|pagename|runs| 21:54:44 INFO - PROCESS | 2387 | |0;ai-astar;108;115;118;116;116;116;120;117;118;135 21:54:44 INFO - PROCESS | 2387 | |1;audio-beat-detection;143;114;137;161;111;136;136;110;158;110 21:54:44 INFO - PROCESS | 2387 | |2;audio-dft;200;209;219;203;198;197;199;199;197;195 21:54:44 INFO - PROCESS | 2387 | |3;audio-fft;65;85;76;156;60;59;60;59;133;60 21:54:44 INFO - PROCESS | 2387 | |4;audio-oscillator;70;73;128;94;71;85;118;68;71;115 21:54:44 INFO - PROCESS | 2387 | |5;imaging-gaussian-blur;89;86;88;87;90;85;90;87;88;86 21:54:44 INFO - PROCESS | 2387 | |6;imaging-darkroom;95;96;96;95;96;95;96;97;96;106 21:54:44 INFO - PROCESS | 2387 | |7;imaging-desaturate;78;145;134;122;85;143;81;150;78;147 21:54:44 INFO - PROCESS | 2387 | |8;json-parse-financial;56;58;59;56;57;58;58;59;60;86 21:54:44 INFO - PROCESS | 2387 | |9;json-stringify-tinderbox;65;69;65;76;74;75;75;75;70;70 21:54:44 INFO - PROCESS | 2387 | |10;stanford-crypto-aes;89;88;86;78;111;109;81;98;78;91 21:54:44 INFO - PROCESS | 2387 | |11;stanford-crypto-ccm;130;165;159;160;127;158;164;161;191;157 21:54:44 INFO - PROCESS | 2387 | |12;stanford-crypto-pbkdf2;168;175;172;173;173;190;174;170;170;169 21:54:44 INFO - PROCESS | 2387 | |13;stanford-crypto-sha256-iterative;72;67;68;69;67;68;68;69;67;69 21:54:44 INFO - PROCESS | 2387 | __end_tp_report 21:54:44 INFO - PROCESS | 2387 | __start_cc_report 21:54:44 INFO - PROCESS | 2387 | _x_x_mozilla_cycle_collect,966 21:54:44 INFO - PROCESS | 2387 | __end_cc_report 21:54:44 INFO - PROCESS | 2387 | __startTimestamp1473224084708__endTimestamp 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | ------- Summary: start ------- 21:54:44 INFO - PROCESS | 2387 | Number of tests: 14 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | [#0] ai-astar Cycles:10 Average:117.90 Median:117.50 stddev:6.79 (5.8%) stddev-sans-first:6.18 21:54:44 INFO - PROCESS | 2387 | Values: 108.0 115.0 118.0 116.0 116.0 116.0 120.0 117.0 118.0 135.0 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | [#1] audio-beat-detection Cycles:10 Average:131.60 Median:136.50 stddev:19.51 (14.3%) stddev-sans-first:20.25 21:54:44 INFO - PROCESS | 2387 | Values: 143.0 114.0 137.0 161.0 111.0 136.0 136.0 110.0 158.0 110.0 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | [#2] audio-dft Cycles:10 Average:201.60 Median:199.50 stddev:7.26 (3.6%) stddev-sans-first:7.68 21:54:44 INFO - PROCESS | 2387 | Values: 200.0 209.0 219.0 203.0 198.0 197.0 199.0 199.0 197.0 195.0 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | [#3] audio-fft Cycles:10 Average:81.30 Median:60.00 stddev:34.83 (58.0%) stddev-sans-first:36.44 21:54:44 INFO - PROCESS | 2387 | Values: 65.0 85.0 76.0 156.0 60.0 59.0 60.0 59.0 133.0 60.0 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:71.00 stddev:23.06 (32.5%) stddev-sans-first:23.37 21:54:44 INFO - PROCESS | 2387 | Values: 70.0 73.0 128.0 94.0 71.0 85.0 118.0 68.0 71.0 115.0 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.00 stddev:1.71 (1.9%) stddev-sans-first:1.74 21:54:44 INFO - PROCESS | 2387 | Values: 89.0 86.0 88.0 87.0 90.0 85.0 90.0 87.0 88.0 86.0 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | [#6] imaging-darkroom Cycles:10 Average:96.80 Median:96.00 stddev:3.29 (3.4%) stddev-sans-first:3.43 21:54:44 INFO - PROCESS | 2387 | Values: 95.0 96.0 96.0 95.0 96.0 95.0 96.0 97.0 96.0 106.0 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | [#7] imaging-desaturate Cycles:10 Average:116.30 Median:114.00 stddev:31.83 (27.9%) stddev-sans-first:30.60 21:54:44 INFO - PROCESS | 2387 | Values: 78.0 145.0 134.0 122.0 85.0 143.0 81.0 150.0 78.0 147.0 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | [#8] json-parse-financial Cycles:10 Average:60.70 Median:58.50 stddev:8.98 (15.4%) stddev-sans-first:9.36 21:54:44 INFO - PROCESS | 2387 | Values: 56.0 58.0 59.0 56.0 57.0 58.0 58.0 59.0 60.0 86.0 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:74.50 stddev:4.20 (5.6%) stddev-sans-first:3.76 21:54:44 INFO - PROCESS | 2387 | Values: 65.0 69.0 65.0 76.0 74.0 75.0 75.0 75.0 70.0 70.0 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:87.00 stddev:11.78 (13.5%) stddev-sans-first:12.47 21:54:44 INFO - PROCESS | 2387 | Values: 89.0 88.0 86.0 78.0 111.0 109.0 81.0 98.0 78.0 91.0 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | [#11] stanford-crypto-ccm Cycles:10 Average:157.20 Median:160.50 stddev:18.04 (11.2%) stddev-sans-first:16.22 21:54:44 INFO - PROCESS | 2387 | Values: 130.0 165.0 159.0 160.0 127.0 158.0 164.0 161.0 191.0 157.0 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:173.00 stddev:6.26 (3.6%) stddev-sans-first:6.32 21:54:44 INFO - PROCESS | 2387 | Values: 168.0 175.0 172.0 173.0 173.0 190.0 174.0 170.0 170.0 169.0 21:54:44 INFO - PROCESS | 2387 | 21:54:44 INFO - PROCESS | 2387 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.50 stddev:1.51 (2.2%) stddev-sans-first:0.87 21:54:44 INFO - PROCESS | 2387 | Values: 72.0 67.0 68.0 69.0 67.0 68.0 68.0 69.0 67.0 69.0 21:54:44 INFO - PROCESS | 2387 | -------- Summary: end -------- 21:54:44 INFO - PROCESS | 2387 | 21:54:45 INFO - TEST-INFO | 2387: exit 0 21:54:45 INFO - TEST-OK | kraken | took 60152ms 21:54:45 INFO - SUITE-END | took 1174s 21:54:45 INFO - Completed test suite (00:19:34) 21:54:45 INFO - javascript benchmark 21:54:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7241.8214639708685, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3396.626371099179, 3435.7079648229883, 3436.0, 3438.9484157737625, 3464.2725027744173, 5711.628744131628, 5855.824325270243, 5883.999720047592, 5886.5585081118925, 5960.761569537219, 4561.7491037992895, 4655.837045703401, 5049.889024414623, 5757.68332741699, 5876.206712093866, 4333.978330108345, 4356.978215108936, 5238.397584277819, 5329.413764485904, 5423.132298832187, 5388.676679399229, 5433.103537916259, 5442.428545002777, 5480.3971563128025, 5494.780208791643, 5214.139666954986, 5239.292695486137, 5269.789208431658, 5272.657277277003, 5303.151495760679, 4372.884914346853, 5274.736263186825, 5286.07493688603, 5311.973440132774, 5354.732263386815, 5254.65844720092, 5297.284866543045, 5341.706206158659, 5362.088444964326, 5394.352677678704, 5723.828285151452, 5829.242198514166, 5913.852153696149, 5929.021711417611, 5963.791267305667, 5206.583473322125, 6207.6275423474735, 6227.470664993481, 6273.811785646438, 6282.026285925689, 6255.96872015637, 6304.432601065926, 6318.810435686937, 6339.0, 6358.1734374531015, 5888.911666325009, 6002.339742628307, 6018.939810601925, 6024.427679370453, 6100.511959043266, 5508.834734957958, 6357.96821015892, 6395.840103997437, 6397.488200943913, 6415.1981002374705, 5789.2763404574425, 6247.0, 6282.246130464374, 6288.182536270256, 6312.779052733133, 6155.415235552616, 6195.070739389128, 6219.284782250099, 6238.1890354254565, 6345.460635846005, 6115.179495343419, 6154.753809847645, 6212.875742485125, 6230.6573138476915, 6273.466755325849, 6206.348333425016, 6232.688365581703, 6259.874802503924, 6267.4045965633195, 6271.310155882849, 4957.553820156203, 5004.299398084271, 5023.522765337287, 5083.847484575469, 5139.049275883974, 4509.346144808985, 4542.772861356919, 4593.908121837611, 4606.8157273708675, 4641.350210970467, 5887.293524776968, 5975.073863551156, 5981.700914954235, 5994.310654274727, 6006.579539432285, 6180.6291622502795, 6187.876242475125, 6188.195534580475, 6281.811545653638, 6310.368963103745, 5946.821595352147, 5947.37552556984, 5979.790707325223, 6015.789447369321, 6031.9396806032255, 5582.720863956786, 5590.24531688225, 5593.244911936918, 5604.187392828017, 5608.915866262009, 6259.311475737756, 6331.2085989251345, 6340.651264180515, 6455.903161452582, 6490.286068532458, 4823.758812059453, 6032.944234758812, 6204.968975155095, 6236.937630623635, 6256.90504161779, 5463.890722185613, 6444.065610486547, 6451.3548645135115, 6469.191351081115, 6495.74017039332, 5942.1978032965, 6146.0473626587955, 6192.349803270592, 6236.126942228182, 6308.842278943063, 6290.2766181889665, 6313.179286692701, 6329.30377658448, 6340.334264902119, 6340.49276057905, 4856.271559266138, 6181.96909015452, 6196.907046394398, 6226.750929962932, 6254.343293954069, 6115.755369785159, 6154.661493617894, 6171.506279497719, 6239.319635973518, 6265.624062556261, 5373.462653734595, 5924.763009479745, 6089.451949324582, 6135.601185922901, 6144.433169541739, 6152.830962117184, 6209.720562574786, 6224.37756224374, 6264.123022776906, 6288.088227207121, 5733.111367738007, 5993.370696077023, 6090.756369745161, 6130.969345153246, 6156.445919867234], "value": 5736.842294798669, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.2421331682502, 519.6907839835296, 526.8067826373265, 529.2194013829601, 549.8103154411726, 37940.620593794054, 40499.79750101245, 40512.79743601278, 40697.59302406975, 40819.795901020494, 41129.383059254134, 41860.372094418606, 41878.16243675132, 41897.810218978084, 41927.58072419283, 9620.278479114077, 9763.511824408786, 9790.412575245507, 9799.16707079899, 9823.410595364265, 9647.324687271894, 9651.855222171673, 9745.805083898318, 9763.560639771193, 9784.0, 9757.170640495566, 9766.609335626566, 9770.902290977107, 9775.560099795526, 9784.853227201598, 9581.329306948446, 9645.614175432975, 9690.660826871026, 9708.417494950325, 9708.805823883553, 9625.518724063839, 9717.46553939533, 9720.0, 9746.122848943558, 9746.658866939693, 38824.61175388238, 39487.21025579501, 39800.20399592021, 39859.0, 39929.0, 3458.792472451661, 3752.1745216052695, 4007.318755811519, 4007.5391329997133, 4009.51885773709, 30075.548866767014, 30420.695793042007, 30573.847130764425, 30614.0, 30638.84680576605, 3164.3671265746943, 3206.374756922401, 3219.3722224166295, 3280.934381312384, 3281.146901805524, 386785.0, 387025.0, 388929.05535472423, 389099.0, 390528.0473597642, 32348.514772278435, 33341.499877501854, 57779.4222057774, 58298.0, 58569.41430585639, 34713.479297810554, 34881.825590871886, 34894.65105348965, 35177.296454070776, 35197.472037919455, 14640.0, 16186.919065404833, 16441.26014329358, 16576.83423165753, 16994.915025424794, 12718.491260349667, 12804.231746095264, 12817.743645127046, 12835.48658053686, 12902.419391127421, 18926.148323325484, 18934.337298194772, 18992.905035474734, 19045.523861903563, 19111.426657200307, 6853.554518956252, 8080.39397045224, 8085.919140808634, 8129.227723366271, 8172.42793004484, 828.329053466694, 910.349100393222, 931.6552875435997, 995.6515219673056, 1005.6178652112296, 29596.55605165924, 29616.963406280676, 30035.849820750755, 30396.84801575978, 30499.69500305013, 824.5217773691245, 1007.3804610164842, 1052.3633201912885, 1067.9442074007504, 1089.983650245247, 12949.935250323688, 13678.658033549042, 13882.930585347009, 14573.781393279109, 15245.695086098216, 686979.1302087015, 693898.0, 696538.0, 699160.0, 700090.0, 57048.0, 57511.0, 57822.0, 57853.0, 57969.42030579725, 1344.529414704865, 1372.4235820955223, 1377.4490203918513, 1380.0891411668251, 1382.4608402722745, 25147.622785657863, 27391.493467859458, 29067.0, 29206.7079329204, 29882.8505857478, 39437.0, 48778.512214877395, 48928.26607600818, 49015.75492122517, 49192.75403622959, 574987.0, 580931.0, 583167.0, 583961.0, 586599.0, 25788.742112578635, 25894.223173304832, 25978.090766823825, 26223.344416389744, 26392.8680356597, 45331.64005079853, 45674.77162614166, 46287.0, 46639.76680116577, 46692.0, 38103.428448573846, 38290.83073785003, 38532.42201366926, 39325.213495730495, 39877.80061099676, 38548.80725596354, 38679.0, 38838.33939927227, 38880.80559597184, 40073.998150046486, 33274.33451331008, 38115.42826857655, 38334.42498362471, 38431.80784096062, 38644.0, 32057.839710801298, 32105.839470802497, 33407.331853363285, 34442.311153777286, 34592.135196620286, 25173.370665733502, 25334.0, 25453.745462545878, 26176.476470470865, 26218.0], "value": 19397.055160871387, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.37492202121024, 370.6497359994806, 376.46165982644817, 385.4911516797828, 402.77444631006637, 17852.64294714105, 19803.910784906824, 21039.47401314964, 21280.893595532038, 21528.784712152872, 9739.610415583367, 12192.390380480963, 12214.145009849291, 12227.38863056846, 12935.611931642043, 5254.737263136847, 5532.474414930575, 5537.723113844312, 5545.77816887324, 5564.165375193733, 3945.7829819360068, 5523.447655234485, 5860.882782344351, 6132.754689812425, 6197.969010154943, 6147.293061297941, 6191.504679625619, 6212.502999760031, 6225.47083497901, 6232.594881332721, 6372.394622510854, 6718.193816741998, 6783.592984420901, 6834.282400347991, 6839.897401538981, 5789.478946894757, 5954.672493012882, 5994.880102397971, 6022.69886505673, 6045.33501314855, 13088.280144592043, 13298.534551290659, 13331.86668133316, 15468.381264749509, 25028.624570631455, 2072.067569593689, 2101.117530637136, 2188.277868303456, 2541.440883005736, 2619.3975385661215, 10370.637027703995, 10801.243912926098, 12838.16551924122, 13503.864961350358, 14718.70562588753, 1558.9844101559065, 1894.1192345559382, 1997.2011195521761, 2012.3560460652598, 2053.6303465376377, 83998.58000709958, 106908.465457673, 110827.9999999992, 111363.44318278438, 113084.0, 11466.770664586662, 11493.080553555696, 11595.840415958337, 11600.709982250342, 11718.531258749725, 14454.421823127168, 14497.057691250035, 14561.708765824624, 14579.708405832036, 14585.343659535134, 8457.900472938478, 8616.310695144499, 8643.870341944876, 8675.869861952076, 8808.867866982, 7019.964900175466, 8605.741827745178, 8662.393632445794, 8788.869231461204, 8825.911740882637, 4466.709663871838, 4509.503954564995, 4511.661625378109, 4519.909601807945, 4547.113312903952, 1575.2123938030984, 1660.5433505786004, 1678.630701245724, 1910.2836436336374, 1964.0, 732.2604169788445, 887.2591386192602, 997.7605374710017, 1003.4531180506641, 1016.8474728790593, 15164.393424262906, 16048.0, 16788.6642267154, 21491.462713431978, 21602.351929442142, 953.666216824106, 968.4431451915177, 976.5507866381402, 1023.744063984004, 1032.8915463876335, 10380.96190380971, 11295.912676280755, 11340.6030788922, 11358.0, 11416.31502109876, 584545.0, 595040.0, 600800.0, 606058.0, 608280.0, 103783.48108259411, 128642.35678821734, 133904.0, 134005.0, 134061.318773624, 991.8710567626307, 1029.5675816157081, 1051.8001579699833, 1055.308772753832, 1057.5241141486363, 13554.59336219915, 18814.0, 18852.0, 19161.425157245307, 19200.94394808243, 42398.576014239465, 53237.7338113307, 55004.89990200254, 55254.0, 55437.72281138568, 75816.0, 78072.60963695146, 78660.0, 80533.0, 81182.0, 6961.0, 7780.377569794516, 7958.801029974065, 7977.7207797726805, 7998.880016799869, 11109.333439993627, 11387.804280550423, 11609.941950290195, 12074.939625301817, 12187.268763874195, 10548.820413103325, 10589.046985771318, 10667.946660266649, 10810.621628242974, 11123.888761112285, 10500.842487362848, 10591.841122383325, 10644.308119972176, 10757.892421075689, 10850.511726972147, 10912.7271818202, 11072.28030178068, 11232.4383780809, 11379.317240965569, 11423.657290281304, 7895.249951254622, 8051.436399451982, 8054.718084867001, 8125.268725814469, 8198.344132469507, 10080.798384032425, 10337.932413517176, 10802.0, 11066.778664426505, 11207.271527350696], "value": 9273.168116167153, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1447.0304895719867, 1454.2219912346895, 1661.3852874436461, 1828.2412798688545, 1870.4014715291103, 7895.960520197391, 8441.409101362899, 8505.74482765518, 8967.596458159382, 8992.550372481373, 8558.957205213981, 8663.436876603017, 8750.343724220676, 8763.167499087576, 8790.736277911657, 2354.3996280948377, 2374.050379848057, 2390.4621460171497, 2393.1743548475833, 2395.5448464791634, 2352.6353415220583, 2354.2348736660656, 2393.401649587603, 2400.531896280226, 2405.0139442828445, 2043.6117137743856, 2380.940476488093, 2381.1189859751917, 2381.249906279525, 2389.116027069978, 2027.3715148303925, 2035.8167764901157, 2120.8515403921733, 2233.575620632083, 2316.629339305703, 1991.412533302679, 2003.6293285742183, 2316.6409206573007, 2340.1809366721513, 2351.1183306260154, 7233.927660723378, 8715.172058654418, 8751.868721969111, 8815.118488151134, 8829.381943263974, 5094.1594636884865, 5237.319148510669, 5248.6588371755715, 5249.16013437852, 5252.8686782830355, 3486.250456151914, 3582.5700915889997, 3653.6711695947492, 3716.4425336199515, 3717.6654101130757, 1052.5474046160148, 1067.4368956423434, 1104.950277237518, 1124.7131981344787, 1126.6957921361186, 11317.717057073556, 11492.0, 11516.251443656136, 11640.301581905029, 11644.359560224184, 4526.388937493396, 4626.537346265414, 4781.402324709411, 4831.0, 4845.9757701211265, 8648.394612377073, 9384.577694003787, 9777.608895644236, 9803.068708472683, 9833.213342932548, 5294.391145018295, 5310.867228319245, 5312.256284120226, 5327.067763141512, 5329.147336426153, 4013.3979903014197, 4371.737695738202, 4737.549932756383, 4863.489333619989, 4869.63477739171, 3828.0, 3889.572147063821, 3919.8828333925267, 3973.980130099331, 4003.619656132663, 1123.5112725964232, 1314.3625341709264, 1316.9341532923506, 1328.249539010456, 1331.600519844043, 797.8045378882211, 858.2018722588053, 898.0435835834814, 912.4662072687552, 942.3121121581285, 4704.317873908264, 5750.326836521867, 5786.392428795, 5813.244278243886, 6048.0, 930.3487558708931, 943.056943056943, 982.8771403574553, 988.1551273661061, 1006.6677996261217, 3812.275667623143, 3812.599677033927, 3924.7645141291614, 4003.6997225208224, 4028.315186418316, 10364.740881478023, 10604.57581696739, 10614.150867930546, 10697.572097116028, 10709.410982396043, 10649.094826939798, 10652.147828173727, 10659.520321585547, 10673.398990151383, 10681.359118452918, 7756.612169391507, 8073.9192608074345, 8147.79630509242, 8154.38842086834, 8212.0, 5883.382244864228, 5900.350961394157, 5900.5574581907385, 5966.671833049224, 6082.057281121433, 9787.657431989846, 10055.798884022424, 10084.394936303845, 10109.39343639384, 10136.847947280945, 9714.077162669535, 9984.750381240527, 10136.239782016231, 10173.389596624227, 10227.02843229892, 7309.122905251404, 7480.3641690456925, 7504.587247701429, 7524.247575242432, 7556.093268807778, 8117.391195660231, 8195.975503062116, 8196.180381961994, 8200.343972482306, 8213.548254845802, 6500.674966251763, 7230.060092187982, 7402.33378995893, 7414.555126692416, 7496.662650180646, 6304.968475157595, 7232.819179520554, 7249.456290778318, 7274.4907856449545, 7294.671739771829, 6727.529072964846, 6833.077534532775, 6908.654567271516, 6932.861342773015, 6936.445084393337, 4889.63327750413, 4932.926006109983, 4948.529889660476, 4976.104301225814, 5034.119029169954, 4878.756062196805, 4936.111499930047, 5002.924956125734, 5054.595632349477, 5080.415752188545], "value": 4543.460190985231, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [249.52091983391887, 277.93607470281825, 294.75829819547977, 310.3049169859515, 312.09181282468023, 4358.389825424443, 5256.526912577867, 5350.652207606503, 5357.678539287635, 5553.694546799924, 4910.484399138093, 5349.491798279167, 5380.300560927084, 5462.071447853865, 5469.206964990084, 3480.3387356402327, 4160.23190144189, 4160.83356665733, 4180.24755544002, 4203.789810509477, 3099.5195744659613, 3564.679178873901, 4187.832486700544, 4193.727407718504, 4200.810963506634, 3838.692904567642, 4020.035191554035, 4043.575424580421, 4045.898852528681, 4046.7774272415004, 4062.918741625151, 4064.796760161995, 4069.7354671946227, 4075.2868248056766, 4082.9387559186634, 3524.8766293179874, 3525.065857547752, 3540.929181416383, 3547.6629720176543, 3547.875824346161, 4311.353297005443, 4579.702319349265, 4779.689320194177, 5296.390915044781, 5332.280142180794, 4090.0, 4168.935832163357, 4471.105778844244, 4661.370714953472, 4700.1539722849875, 5411.702356370398, 5471.616986810925, 5756.395578464243, 5803.9709801451145, 5815.0, 3613.4941108244866, 3811.8475260989526, 3896.649301562873, 3898.161895192519, 3906.277338692351, 5598.916016259759, 5736.942630573682, 5994.790182343597, 6059.363766804466, 6167.044108163242, 3878.70909681775, 3923.3722604383734, 3924.50943632046, 3926.0577461409625, 3926.5680775114715, 3702.1674271235374, 3901.4342920276968, 3913.8630147944687, 3975.7216994810665, 3981.223661386002, 4034.6973976951845, 4313.676474264443, 4615.653825963066, 4621.4223222097235, 4650.906981860411, 5755.827325180251, 5765.740541675635, 5775.797847075332, 5796.855078623068, 5823.883522329615, 1270.8157317188957, 1322.259534660574, 1346.7575836349356, 1351.7904724767677, 1356.3625096204769, 871.1897934920459, 894.9149830766066, 904.8778414914034, 909.3361845852564, 909.7082143356449, 4884.511548845087, 5303.787848486095, 5350.9732451337495, 5351.785928562814, 5356.848277620253, 4723.527647235318, 5421.322334708161, 5843.883122337529, 5889.910366582196, 5914.8817023659285, 4268.914621707549, 4603.6777425579885, 4657.394538709946, 4801.903961920742, 5486.695587853112, 2277.8974946127464, 2337.4974380508274, 2420.7458216887326, 2589.3267750384657, 2596.2730435478097, 4381.693281470267, 4434.33484977256, 4467.754273514988, 5040.294358789773, 5159.793608255703, 4645.86062418128, 4696.530346965276, 4971.751412429364, 5061.683962169863, 5074.949250507521, 4145.461090058213, 4358.607725304738, 4940.629452791055, 4957.3307603473795, 5081.644284900021, 4352.542982986741, 4901.676547332272, 4902.264660300983, 4939.295942899694, 4980.775865086008, 3894.883153505399, 3930.9017274567223, 4069.2878746218703, 4470.508244093082, 4832.710037397767, 4431.401760762255, 4832.734199619054, 5089.9745501272255, 5096.46487118862, 5179.792808287626, 2915.70251238197, 3087.799293045945, 3215.9196020099685, 3349.5310656507622, 3380.4253276942486, 4172.728772629769, 4489.887752806076, 4571.537108125368, 4656.697314674535, 4964.6524743269065, 5272.420033796356, 5678.3186017678145, 5679.716014199356, 5693.459121383462, 5733.248040940289, 5571.799139340019, 5734.426557344233, 5756.89127870945, 5782.277215348082, 5782.913256301243, 5089.440161582149, 5105.591552675852, 5169.2941329360465, 5568.387477377566, 5580.38615752275, 5485.232067510631, 5683.465464324692, 5731.570132240182, 5764.567657425794, 5830.47525722687, 4839.927401088916, 5551.916721249266, 5624.887502250014, 5668.971655141698, 5676.517496012885], "value": 3935.0616782603324, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.1667232665454, 725.434161354144, 729.6898818002347, 738.4609235258259, 741.7811745535066, 15164.77252841207, 15601.219939003062, 15787.526374208763, 15817.050976941391, 16635.83364166361, 16098.83901160988, 16108.0, 16136.919315403407, 16139.677206455894, 16158.676826463463, 848.088305072047, 865.1564724393697, 869.7086476030537, 871.5685735560907, 874.7244617945358, 685.7736946807566, 726.803762983994, 861.9914699801217, 874.7419511244167, 878.4948654523643, 904.9547522623875, 918.8989211186764, 919.5310391700248, 923.7044145873323, 925.7222833150062, 906.8639704044413, 913.4062859141545, 917.5824999625155, 918.0085507651787, 918.3158546882548, 682.3551743602297, 683.7538486144983, 684.7124207832723, 685.1401491128652, 688.3323176518819, 15070.171140587263, 15171.165585892772, 15176.620584485367, 15181.0, 15253.847461525354, 14060.929695351459, 14109.85890141106, 14114.576562703136, 14145.080569763035, 14165.575032749033, 11923.642290731292, 11925.880741192565, 11969.82045269313, 11995.520179192821, 12278.263304201777, 3664.633536646341, 3794.260119276742, 3837.6929845612563, 3919.1573811630637, 3993.7004724645767, 102375.0, 103839.0, 104069.43895841569, 108888.45555772171, 110556.23609409749, 10407.94796026015, 10412.114970227616, 10474.486123330498, 10604.204684648683, 10847.67456976292, 21529.246476373253, 21561.137554497956, 21561.784382156293, 21609.135634574755, 21634.56730865374, 7600.087989441303, 7629.809254768676, 7654.2345765424125, 7680.0, 7896.802579935547, 4423.181711383404, 4439.755813430228, 4439.866803995886, 4505.986153115588, 4591.127685739699, 3534.4168212245204, 3665.6884164845756, 3666.6333366663653, 3683.5211422514903, 3815.751976121543, 609.3966972696998, 699.1086364884812, 715.5313269808283, 723.5550136665912, 725.8802297620939, 554.8058179637095, 568.6985897474345, 604.3473049106954, 610.8045425463808, 665.4809248785955, 8858.778530536789, 9180.632774689071, 9642.180414664832, 9702.611895524242, 9707.225862128915, 580.4659713063992, 621.2979333353372, 653.1313353240179, 662.8342914271432, 666.5534092158266, 3722.664960153599, 4069.4099355593867, 4520.796564154621, 4634.814607415733, 4679.3916790816975, 129526.70473295335, 134144.0, 157514.6372804409, 171964.1401792983, 173581.0, 148868.0, 151027.0, 151940.0, 153382.0, 153561.0, 126739.36630316789, 128279.35860320825, 128732.0, 129977.35011325072, 130753.69246307605, 7862.685492580462, 9054.773630659287, 9098.408603440756, 9152.542372881462, 9200.435925892749, 68072.6596367015, 70097.29902700908, 71872.0, 72045.95678302468, 72722.63638681773, 99697.50151249197, 102689.0, 106824.46587767011, 107730.0, 110655.0, 10324.328918620266, 11421.771564568828, 11815.881841181823, 12020.218685785396, 12153.088518361335, 12979.351032448529, 13130.934345328213, 13161.341932903508, 13534.984876134526, 13894.652633683834, 12004.256190709091, 12252.87747122553, 12264.87735122673, 12414.379281036092, 12668.113232073667, 11892.227005244631, 12231.143819932518, 12287.385630718607, 12289.815652765037, 12684.11211215243, 11919.3444360561, 12061.698457538632, 12191.451384687887, 12254.671612393617, 12610.936945315214, 8491.405601607828, 10066.396016239049, 10260.486975651038, 10388.324758890649, 10621.840672389766, 8636.769644559145, 10523.15814734804, 10535.420551869414, 10557.94420557938, 10593.417362044853], "value": 7817.451012583686, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1544.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 115.0, 118.0, 116.0, 116.0, 116.0, 120.0, 117.0, 118.0, 135.0], "value": 117.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 114.0, 137.0, 161.0, 111.0, 136.0, 136.0, 110.0, 158.0, 110.0], "value": 131.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 209.0, 219.0, 203.0, 198.0, 197.0, 199.0, 199.0, 197.0, 195.0], "value": 201.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 85.0, 76.0, 156.0, 60.0, 59.0, 60.0, 59.0, 133.0, 60.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 128.0, 94.0, 71.0, 85.0, 118.0, 68.0, 71.0, 115.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 87.0, 90.0, 85.0, 90.0, 87.0, 88.0, 86.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 97.0, 96.0, 106.0], "value": 96.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 145.0, 134.0, 122.0, 85.0, 143.0, 81.0, 150.0, 78.0, 147.0], "value": 116.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 59.0, 56.0, 57.0, 58.0, 58.0, 59.0, 60.0, 86.0], "value": 60.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 65.0, 76.0, 74.0, 75.0, 75.0, 75.0, 70.0, 70.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 86.0, 78.0, 111.0, 109.0, 81.0, 98.0, 78.0, 91.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 165.0, 159.0, 160.0, 127.0, 158.0, 164.0, 161.0, 191.0, 157.0], "value": 157.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 175.0, 172.0, 173.0, 173.0, 190.0, 174.0, 170.0, 170.0, 169.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 67.0, 68.0, 69.0, 67.0, 68.0, 68.0, 69.0, 67.0, 69.0], "value": 68.4, "unit": "score"}]}]} 21:54:45 INFO - Return code: 0 21:54:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:54:46 ERROR - # TBPL SUCCESS # 21:54:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:54:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:54:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:54:46 INFO - 2016-09-06 21:54:46.062 screenresolution[2392:551017] starting screenresolution argv=screenresolution get 21:54:46 INFO - 2016-09-06 21:54:46.065 screenresolution[2392:551017] Display 0: 1600x1200x32@60 21:54:46 INFO - 2016-09-06 21:54:46.071 screenresolution[2393:551020] starting screenresolution argv=screenresolution list 21:54:46 INFO - Available Modes on Display 0 21:54:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:54:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:54:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:54:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:54:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:54:46 INFO - Intel Iris: 21:54:46 INFO - Chipset Model: Intel Iris 21:54:46 INFO - Type: GPU 21:54:46 INFO - Bus: Built-In 21:54:46 INFO - VRAM (Dynamic, Max): 1536 MB 21:54:46 INFO - Vendor: Intel (0x8086) 21:54:46 INFO - Device ID: 0x0a2e 21:54:46 INFO - Revision ID: 0x0009 21:54:46 INFO - Displays: 21:54:46 INFO - Display: 21:54:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:54:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:54:46 INFO - Display Serial Number: 1600x1200 60 21:54:46 INFO - Main Display: Yes 21:54:46 INFO - Mirror: Off 21:54:46 INFO - Online: Yes 21:54:46 INFO - Rotation: Supported 21:54:46 INFO - Return code: 0 21:54:46 INFO - Running post-action listener: _resource_record_post_action 21:54:46 INFO - [mozharness: 2016-09-07 04:54:46.307804Z] Finished run-tests step (success) 21:54:46 INFO - Running post-run listener: _resource_record_post_run 21:54:46 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 1522688; Write bytes: 710389760; Read time: 18; Write time: 2057 21:54:46 INFO - TinderboxPrint: CPU usage
54.5% 21:54:46 INFO - TinderboxPrint: I/O read bytes / time
1,522,688 / 18 21:54:46 INFO - TinderboxPrint: I/O write bytes / time
710,389,760 / 2,057 21:54:46 INFO - TinderboxPrint: CPU idle
2,199.5 (45.5%) 21:54:46 INFO - TinderboxPrint: CPU system
974.1 (20.1%) 21:54:46 INFO - TinderboxPrint: CPU user
1,664.3 (34.4%) 21:54:46 INFO - TinderboxPrint: Swap in / out
422,285,312 / 0 21:54:46 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402283008; Write bytes: 396101632; Read time: 29754; Write time: 830 21:54:46 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 1448960; Write bytes: 297218048; Read time: 17; Write time: 1171 21:54:46 INFO - Running post-run listener: _upload_blobber_files 21:54:46 INFO - Blob upload gear active. 21:54:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:54:46 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 21:54:46 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'] 21:54:46 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 21:54:47 INFO - (blobuploader) - INFO - Open directory for files ... 21:54:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:54:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:54:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:54:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:54:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:54:48 INFO - (blobuploader) - INFO - Done attempting. 21:54:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:54:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:54:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:54:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:54:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:54:49 INFO - (blobuploader) - INFO - Done attempting. 21:54:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:54:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:54:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:54:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:54:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:54:51 INFO - (blobuploader) - INFO - Done attempting. 21:54:51 INFO - (blobuploader) - INFO - Iteration through files over. 21:54:51 INFO - Return code: 0 21:54:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:54:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:54:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5283a613be98ae4a2b8a5da1cea65f8756650f895e23c4b870710f4fd63e54dbe3f410f19dd05ec01bad745ef6f28589b086a9b5cf2b8a3c89a051372734507", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/26aa1c7b77ac08c9907f87f4896c9b0ea6c85f78eb8b7dca1a26efec8b768f2a5e59c927f63519f3f5a8e7fbff5fe9f59eea0e4f4676fc67353e7fcb6b97ab9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ade3634e34c52634698b64d6552f4978d17b9c34b824a599b34b0063a90e675f79120caa4b95753912296f3ee0108cc4516e6c0f2762d3c6c580be11b4abadd2"} 21:54:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:54:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:54:51 INFO - Contents: 21:54:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5283a613be98ae4a2b8a5da1cea65f8756650f895e23c4b870710f4fd63e54dbe3f410f19dd05ec01bad745ef6f28589b086a9b5cf2b8a3c89a051372734507", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/26aa1c7b77ac08c9907f87f4896c9b0ea6c85f78eb8b7dca1a26efec8b768f2a5e59c927f63519f3f5a8e7fbff5fe9f59eea0e4f4676fc67353e7fcb6b97ab9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ade3634e34c52634698b64d6552f4978d17b9c34b824a599b34b0063a90e675f79120caa4b95753912296f3ee0108cc4516e6c0f2762d3c6c580be11b4abadd2"} 21:54:51 INFO - Running post-run listener: copy_logs_to_upload_dir 21:54:51 INFO - Copying logs to upload dir... 21:54:51 INFO - mkdir: /builds/slave/test/build/upload/logs 21:54:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.845723 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-09-06 21:54:51.553226) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 21:54:51.557007) ========= 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.FhMv6cwCdT/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.VksNfuKh38/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5283a613be98ae4a2b8a5da1cea65f8756650f895e23c4b870710f4fd63e54dbe3f410f19dd05ec01bad745ef6f28589b086a9b5cf2b8a3c89a051372734507", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/26aa1c7b77ac08c9907f87f4896c9b0ea6c85f78eb8b7dca1a26efec8b768f2a5e59c927f63519f3f5a8e7fbff5fe9f59eea0e4f4676fc67353e7fcb6b97ab9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ade3634e34c52634698b64d6552f4978d17b9c34b824a599b34b0063a90e675f79120caa4b95753912296f3ee0108cc4516e6c0f2762d3c6c580be11b4abadd2"} build_url:https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011260 build_url: 'https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b5283a613be98ae4a2b8a5da1cea65f8756650f895e23c4b870710f4fd63e54dbe3f410f19dd05ec01bad745ef6f28589b086a9b5cf2b8a3c89a051372734507", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/26aa1c7b77ac08c9907f87f4896c9b0ea6c85f78eb8b7dca1a26efec8b768f2a5e59c927f63519f3f5a8e7fbff5fe9f59eea0e4f4676fc67353e7fcb6b97ab9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ade3634e34c52634698b64d6552f4978d17b9c34b824a599b34b0063a90e675f79120caa4b95753912296f3ee0108cc4516e6c0f2762d3c6c580be11b4abadd2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 21:54:51.590705) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:54:51.591023) ========= 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.FhMv6cwCdT/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.VksNfuKh38/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.005220 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:54:51.639583) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 21:54:51.639984) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 21:54:51.640315) ========= ========= Total master_lag: 2.17 =========