builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0228 starttime: 1473409327.71 results: success (0) buildid: 20160909000818 builduid: 1dd8f7a4a75a4c808b722fca9b555350 revision: 399174fe175afe9d112d5c4e115a5b92395f3b1b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 01:22:07.710620) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 01:22:07.719390) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 01:22:07.719728) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 01:22:07.769880) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 01:22:07.770305) ========= 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.anHJFGrKsu/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.Olgkr8aD3X/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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-09 01:22:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.6M=0.001s 2016-09-09 01:22:08 (19.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.545766 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 01:22:08.338975) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 01:22:08.339343) ========= 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.anHJFGrKsu/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.Olgkr8aD3X/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.038013 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 01:22:08.400289) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-09 01:22:08.400678) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 399174fe175afe9d112d5c4e115a5b92395f3b1b --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 399174fe175afe9d112d5c4e115a5b92395f3b1b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.anHJFGrKsu/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.Olgkr8aD3X/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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-09 01:22:08,490 truncating revision to first 12 chars 2016-09-09 01:22:08,490 Setting DEBUG logging. 2016-09-09 01:22:08,490 attempt 1/10 2016-09-09 01:22:08,490 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/399174fe175a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-09 01:22:09,698 unpacking tar archive at: autoland-399174fe175a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.465187 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-09 01:22:09.888387) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 01:22:09.888701) ========= script_repo_revision: 399174fe175afe9d112d5c4e115a5b92395f3b1b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 01:22:09.889104) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 01:22:09.889426) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 01:22:09.908793) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-09 01:22:09.909483) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.anHJFGrKsu/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.Olgkr8aD3X/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 01:22:10 INFO - MultiFileLogger online at 20160909 01:22:10 in /builds/slave/test 01:22:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 01:22:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:22:10 INFO - {'append_to_log': False, 01:22:10 INFO - 'base_work_dir': '/builds/slave/test', 01:22:10 INFO - 'blob_upload_branch': 'Autoland', 01:22:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:22:10 INFO - 'branch': 'Autoland', 01:22:10 INFO - 'buildbot_json_path': 'buildprops.json', 01:22:10 INFO - 'config_files': ('talos/mac_config.py',), 01:22:10 INFO - 'default_actions': ('clobber', 01:22:10 INFO - 'read-buildbot-config', 01:22:10 INFO - 'download-and-extract', 01:22:10 INFO - 'populate-webroot', 01:22:10 INFO - 'create-virtualenv', 01:22:10 INFO - 'install', 01:22:10 INFO - 'run-tests'), 01:22:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:22:10 INFO - 'download_minidump_stackwalk': True, 01:22:10 INFO - 'download_symbols': 'ondemand', 01:22:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:22:10 INFO - 'tooltool.py': '/tools/tooltool.py', 01:22:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:22:10 INFO - '/tools/misc-python/virtualenv.py')}, 01:22:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:22:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:22:10 INFO - 'installer_path': 'installer.exe', 01:22:10 INFO - 'log_level': 'info', 01:22:10 INFO - 'log_name': 'talos', 01:22:10 INFO - 'log_to_console': True, 01:22:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:22:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:22:10 INFO - 'opt_config_files': (), 01:22:10 INFO - 'pip_index': False, 01:22:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:22:10 INFO - 'cmd': ('bash', 01:22:10 INFO - '-c', 01:22:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:22:10 INFO - 'enabled': True, 01:22:10 INFO - 'halt_on_failure': False, 01:22:10 INFO - 'name': 'check_screen_resolution'},), 01:22:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:22:10 INFO - 'cmd': ('bash', 01:22:10 INFO - '-c', 01:22:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:22:10 INFO - 'enabled': True, 01:22:10 INFO - 'halt_on_failure': False, 01:22:10 INFO - 'name': 'check_screen_resolution'},), 01:22:10 INFO - 'run_cmd_checks_enabled': True, 01:22:10 INFO - 'sps_profile': False, 01:22:10 INFO - 'sps_profile_interval': 0, 01:22:10 INFO - 'suite': 'dromaeojs', 01:22:10 INFO - 'system_bits': '32', 01:22:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:22:10 INFO - 'title': 't-yosemite-r7-0228', 01:22:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:22:10 INFO - 'use_talos_json': True, 01:22:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:22:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:22:10 INFO - 'work_dir': 'build'} 01:22:10 INFO - [mozharness: 2016-09-09 08:22:10.109114Z] Running clobber step. 01:22:10 INFO - Running pre-action listener: _resource_record_pre_action 01:22:10 INFO - Running main action method: clobber 01:22:10 INFO - rmtree: /builds/slave/test/build 01:22:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:22:12 INFO - Running post-action listener: _resource_record_post_action 01:22:12 INFO - [mozharness: 2016-09-09 08:22:12.168135Z] Finished clobber step (success) 01:22:12 INFO - [mozharness: 2016-09-09 08:22:12.168254Z] Running read-buildbot-config step. 01:22:12 INFO - Running pre-action listener: _resource_record_pre_action 01:22:12 INFO - Running main action method: read_buildbot_config 01:22:12 INFO - Using buildbot properties: 01:22:12 INFO - { 01:22:12 INFO - "project": "", 01:22:12 INFO - "product": "firefox", 01:22:12 INFO - "script_repo_revision": "production", 01:22:12 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 01:22:12 INFO - "repository": "", 01:22:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 01:22:12 INFO - "buildid": "20160909000818", 01:22:12 INFO - "pgo_build": "False", 01:22:12 INFO - "basedir": "/builds/slave/test", 01:22:12 INFO - "buildnumber": 183, 01:22:12 INFO - "slavename": "t-yosemite-r7-0228", 01:22:12 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 01:22:12 INFO - "platform": "yosemite_r7", 01:22:12 INFO - "scheduler": "tests-autoland-macosx64-talos", 01:22:12 INFO - "branch": "autoland", 01:22:12 INFO - "repo_path": "integration/autoland", 01:22:12 INFO - "revision": "399174fe175afe9d112d5c4e115a5b92395f3b1b", 01:22:12 INFO - "stage_platform": "macosx64", 01:22:12 INFO - "builduid": "1dd8f7a4a75a4c808b722fca9b555350", 01:22:12 INFO - "slavebuilddir": "test" 01:22:12 INFO - } 01:22:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 01:22:12 INFO - Running post-action listener: _resource_record_post_action 01:22:12 INFO - [mozharness: 2016-09-09 08:22:12.171441Z] Finished read-buildbot-config step (success) 01:22:12 INFO - [mozharness: 2016-09-09 08:22:12.171551Z] Running download-and-extract step. 01:22:12 INFO - Running pre-action listener: _resource_record_pre_action 01:22:12 INFO - Running main action method: download_and_extract 01:22:12 INFO - mkdir: /builds/slave/test/build/tests 01:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:12 INFO - trying https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 01:22:12 INFO - Downloading https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/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 01:22:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/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 01:22:13 INFO - Downloaded 1199 bytes. 01:22:13 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 01:22:13 INFO - Using the following test package requirements: 01:22:13 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 01:22:13 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:22:13 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 01:22:13 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:22:13 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 01:22:13 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:22:13 INFO - u'jsshell-mac.zip'], 01:22:13 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:22:13 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 01:22:13 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 01:22:13 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:22:13 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 01:22:13 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:22:13 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 01:22:13 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:22:13 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 01:22:13 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 01:22:13 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 01:22:13 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:22:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 01:22:14 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 01:22:21 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 01:22:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 01:22:21 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 01:22:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 01:22:28 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 01:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:29 INFO - trying https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:22:29 INFO - Downloading https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 01:22:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/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 01:22:31 INFO - Downloaded 111534068 bytes. 01:22:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:22:31 INFO - mkdir: /builds/slave/test/properties 01:22:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:22:31 INFO - Writing to file /builds/slave/test/properties/build_url 01:22:31 INFO - Contents: 01:22:31 INFO - build_url:https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 01:22:32 INFO - Running post-action listener: _resource_record_post_action 01:22:32 INFO - Running post-action listener: set_extra_try_arguments 01:22:32 INFO - [mozharness: 2016-09-09 08:22:32.649481Z] Finished download-and-extract step (success) 01:22:32 INFO - [mozharness: 2016-09-09 08:22:32.649600Z] Running populate-webroot step. 01:22:32 INFO - Running pre-action listener: _resource_record_pre_action 01:22:32 INFO - Running main action method: populate_webroot 01:22:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:22:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:22:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:22:32 INFO - '--tppagecycles', 01:22:32 INFO - '7'], 01:22:32 INFO - 'tests': ['tsvgm']}}, 01:22:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:22:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:22:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:22:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:22:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:22:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:22:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:32 INFO - 'talos_options': ['--disable-e10s'], 01:22:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:22:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:22:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:32 INFO - 'talos_options': ['--disable-e10s'], 01:22:32 INFO - 'tests': ['damp', 'tps']}, 01:22:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:32 INFO - 'tests': ['damp', 'tps']}, 01:22:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:22:32 INFO - 'tests': ['dromaeo_dom']}, 01:22:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:22:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:22:32 INFO - 'tests': ['basic_compositor_video']}, 01:22:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:22:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:22:32 INFO - 'tests': ['a11yr', 01:22:32 INFO - 'ts_paint', 01:22:32 INFO - 'tpaint', 01:22:32 INFO - 'sessionrestore', 01:22:32 INFO - 'sessionrestore_no_auto_restore', 01:22:32 INFO - 'tabpaint']}, 01:22:32 INFO - 'other-e10s': {'tests': ['a11yr', 01:22:32 INFO - 'ts_paint', 01:22:32 INFO - 'tpaint', 01:22:32 INFO - 'sessionrestore', 01:22:32 INFO - 'sessionrestore_no_auto_restore', 01:22:32 INFO - 'tabpaint']}, 01:22:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:22:32 INFO - 'ts_paint', 01:22:32 INFO - 'tpaint', 01:22:32 INFO - 'sessionrestore', 01:22:32 INFO - 'sessionrestore_no_auto_restore', 01:22:32 INFO - 'tabpaint']}, 01:22:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:22:32 INFO - 'ts_paint', 01:22:32 INFO - 'tpaint', 01:22:32 INFO - 'sessionrestore', 01:22:32 INFO - 'sessionrestore_no_auto_restore', 01:22:32 INFO - 'tabpaint']}, 01:22:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:22:32 INFO - 'tests': ['a11yr', 01:22:32 INFO - 'ts_paint', 01:22:32 INFO - 'tpaint', 01:22:32 INFO - 'sessionrestore', 01:22:32 INFO - 'sessionrestore_no_auto_restore', 01:22:32 INFO - 'tabpaint']}, 01:22:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:22:32 INFO - 'tests': ['a11yr', 01:22:32 INFO - 'ts_paint', 01:22:32 INFO - 'tpaint', 01:22:32 INFO - 'sessionrestore', 01:22:32 INFO - 'sessionrestore_no_auto_restore', 01:22:32 INFO - 'tabpaint']}, 01:22:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:22:32 INFO - 'tests': ['tsvgx', 01:22:32 INFO - 'tsvgr_opacity', 01:22:32 INFO - 'tart', 01:22:32 INFO - 'tscrollx', 01:22:32 INFO - 'cart']}, 01:22:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:22:32 INFO - 'tsvgr_opacity', 01:22:32 INFO - 'tart', 01:22:32 INFO - 'tscrollx', 01:22:32 INFO - 'cart']}, 01:22:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:32 INFO - 'talos_options': ['--disable-e10s'], 01:22:32 INFO - 'tests': ['tp5o']}, 01:22:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:32 INFO - 'tests': ['tp5o']}, 01:22:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:32 INFO - 'talos_options': ['--disable-e10s', 01:22:32 INFO - '--xperf_path', 01:22:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:22:32 INFO - 'tests': ['tp5n']}, 01:22:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:32 INFO - 'talos_options': ['--xperf_path', 01:22:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:22:32 INFO - 'tests': ['tp5n']}}, 01:22:32 INFO - 'talos.zip': {'path': '', 01:22:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:22:32 INFO - Running post-action listener: _resource_record_post_action 01:22:32 INFO - [mozharness: 2016-09-09 08:22:32.668575Z] Finished populate-webroot step (success) 01:22:32 INFO - [mozharness: 2016-09-09 08:22:32.668689Z] Running create-virtualenv step. 01:22:32 INFO - Running pre-action listener: _resource_record_pre_action 01:22:32 INFO - Running main action method: create_virtualenv 01:22:32 INFO - Creating virtualenv /builds/slave/test/build/venv 01:22:32 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 01:22:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:22:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:22:32 INFO - Using real prefix '/tools/python27' 01:22:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:22:33 INFO - Installing distribute.............................................................................................................................................................................................done. 01:22:36 INFO - Installing pip.................done. 01:22:36 INFO - Return code: 0 01:22:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10decba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df60618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffae1d87e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de2fd30>, '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 0x10db94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de27b20>, '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.anHJFGrKsu/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.Olgkr8aD3X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 01:22:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:22:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:22:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.anHJFGrKsu/Render', 01:22:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:36 INFO - 'HOME': '/Users/cltbld', 01:22:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:36 INFO - 'LOGNAME': 'cltbld', 01:22:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:36 INFO - 'MOZ_NO_REMOTE': '1', 01:22:36 INFO - 'NO_EM_RESTART': '1', 01:22:36 INFO - 'PAGER': '/bin/cat', 01:22:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:36 INFO - 'PWD': '/builds/slave/test', 01:22:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:36 INFO - 'SHELL': '/bin/bash', 01:22:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Olgkr8aD3X/Listeners', 01:22:36 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 01:22:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:36 INFO - 'USER': 'cltbld', 01:22:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:36 INFO - 'XPC_FLAGS': '0x0', 01:22:36 INFO - 'XPC_SERVICE_NAME': '0', 01:22:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:36 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 01:22:36 INFO - Cleaning up... 01:22:36 INFO - Return code: 0 01:22:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10decba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df60618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffae1d87e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de2fd30>, '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 0x10db94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de27b20>, '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.anHJFGrKsu/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.Olgkr8aD3X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 01:22:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:22:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:22:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.anHJFGrKsu/Render', 01:22:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:36 INFO - 'HOME': '/Users/cltbld', 01:22:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:36 INFO - 'LOGNAME': 'cltbld', 01:22:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:36 INFO - 'MOZ_NO_REMOTE': '1', 01:22:36 INFO - 'NO_EM_RESTART': '1', 01:22:36 INFO - 'PAGER': '/bin/cat', 01:22:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:36 INFO - 'PWD': '/builds/slave/test', 01:22:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:36 INFO - 'SHELL': '/bin/bash', 01:22:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Olgkr8aD3X/Listeners', 01:22:36 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 01:22:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:36 INFO - 'USER': 'cltbld', 01:22:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:36 INFO - 'XPC_FLAGS': '0x0', 01:22:36 INFO - 'XPC_SERVICE_NAME': '0', 01:22:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:36 INFO - Downloading/unpacking psutil>=3.1.1 01:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:22:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:22:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:22:39 INFO - Installing collected packages: psutil 01:22:39 INFO - Running setup.py install for psutil 01:22:40 INFO - building 'psutil._psutil_osx' extension 01:22:40 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 01:22:40 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 01:22:40 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 01:22:40 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 01:22:40 INFO - building 'psutil._psutil_posix' extension 01:22:40 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 01:22:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:22:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:22:40 INFO - ^ 01:22:40 INFO - 1 warning generated. 01:22:40 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 01:22:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:22:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:22:41 INFO - Successfully installed psutil 01:22:41 INFO - Cleaning up... 01:22:41 INFO - Return code: 0 01:22:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10decba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df60618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffae1d87e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de2fd30>, '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 0x10db94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de27b20>, '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.anHJFGrKsu/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.Olgkr8aD3X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 01:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:22:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.anHJFGrKsu/Render', 01:22:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:41 INFO - 'HOME': '/Users/cltbld', 01:22:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:41 INFO - 'LOGNAME': 'cltbld', 01:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:41 INFO - 'MOZ_NO_REMOTE': '1', 01:22:41 INFO - 'NO_EM_RESTART': '1', 01:22:41 INFO - 'PAGER': '/bin/cat', 01:22:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:41 INFO - 'PWD': '/builds/slave/test', 01:22:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:41 INFO - 'SHELL': '/bin/bash', 01:22:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Olgkr8aD3X/Listeners', 01:22:41 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 01:22:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:41 INFO - 'USER': 'cltbld', 01:22:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:41 INFO - 'XPC_FLAGS': '0x0', 01:22:41 INFO - 'XPC_SERVICE_NAME': '0', 01:22:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:22:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:22:43 INFO - Installing collected packages: mozsystemmonitor 01:22:43 INFO - Running setup.py install for mozsystemmonitor 01:22:44 INFO - Successfully installed mozsystemmonitor 01:22:44 INFO - Cleaning up... 01:22:44 INFO - Return code: 0 01:22:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:22:44 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')]} 01:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:44 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')]} 01:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:44 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 0x10decba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df60618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffae1d87e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de2fd30>, '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 0x10db94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de27b20>, '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.anHJFGrKsu/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.Olgkr8aD3X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 01:22:44 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 01:22:44 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 01:22:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.anHJFGrKsu/Render', 01:22:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:44 INFO - 'HOME': '/Users/cltbld', 01:22:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:44 INFO - 'LOGNAME': 'cltbld', 01:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:44 INFO - 'MOZ_NO_REMOTE': '1', 01:22:44 INFO - 'NO_EM_RESTART': '1', 01:22:44 INFO - 'PAGER': '/bin/cat', 01:22:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:44 INFO - 'PWD': '/builds/slave/test', 01:22:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:44 INFO - 'SHELL': '/bin/bash', 01:22:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Olgkr8aD3X/Listeners', 01:22:44 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 01:22:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:44 INFO - 'USER': 'cltbld', 01:22:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:44 INFO - 'XPC_FLAGS': '0x0', 01:22:44 INFO - 'XPC_SERVICE_NAME': '0', 01:22:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:44 INFO - Downloading/unpacking jsonschema==2.5.1 01:22:44 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 01:22:44 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 01:22:44 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 01:22:44 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 01:22:44 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 01:22:44 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 01:22:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:22:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:47 INFO - Downloading functools32-3.2.3-2.tar.gz 01:22:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:22:47 INFO - warning: no files found matching '*.txt' 01:22:47 INFO - no previously-included directories found matching 'build' 01:22:47 INFO - no previously-included directories found matching 'dist' 01:22:47 INFO - no previously-included directories found matching '.git*' 01:22:47 INFO - Installing collected packages: jsonschema, functools32 01:22:47 INFO - Running setup.py install for functools32 01:22:47 INFO - warning: no files found matching '*.txt' 01:22:47 INFO - no previously-included directories found matching 'build' 01:22:47 INFO - no previously-included directories found matching 'dist' 01:22:47 INFO - no previously-included directories found matching '.git*' 01:22:47 INFO - Successfully installed jsonschema functools32 01:22:47 INFO - Cleaning up... 01:22:47 INFO - Return code: 0 01:22:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:22:47 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')]} 01:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:47 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')]} 01:22:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:47 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 0x10decba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df60618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffae1d87e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de2fd30>, '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 0x10db94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de27b20>, '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.anHJFGrKsu/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.Olgkr8aD3X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 01:22:47 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 01:22:47 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 01:22:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.anHJFGrKsu/Render', 01:22:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:47 INFO - 'HOME': '/Users/cltbld', 01:22:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:47 INFO - 'LOGNAME': 'cltbld', 01:22:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:47 INFO - 'MOZ_NO_REMOTE': '1', 01:22:47 INFO - 'NO_EM_RESTART': '1', 01:22:47 INFO - 'PAGER': '/bin/cat', 01:22:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:47 INFO - 'PWD': '/builds/slave/test', 01:22:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:47 INFO - 'SHELL': '/bin/bash', 01:22:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Olgkr8aD3X/Listeners', 01:22:47 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 01:22:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:47 INFO - 'USER': 'cltbld', 01:22:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:47 INFO - 'XPC_FLAGS': '0x0', 01:22:47 INFO - 'XPC_SERVICE_NAME': '0', 01:22:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:22:48 INFO - Cleaning up... 01:22:48 INFO - Return code: 0 01:22:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:22:48 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')]} 01:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:48 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')]} 01:22:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:48 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 0x10decba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df60618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffae1d87e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de2fd30>, '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 0x10db94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de27b20>, '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.anHJFGrKsu/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.Olgkr8aD3X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 01:22:48 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 01:22:48 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 01:22:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.anHJFGrKsu/Render', 01:22:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:48 INFO - 'HOME': '/Users/cltbld', 01:22:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:48 INFO - 'LOGNAME': 'cltbld', 01:22:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:48 INFO - 'MOZ_NO_REMOTE': '1', 01:22:48 INFO - 'NO_EM_RESTART': '1', 01:22:48 INFO - 'PAGER': '/bin/cat', 01:22:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:48 INFO - 'PWD': '/builds/slave/test', 01:22:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:48 INFO - 'SHELL': '/bin/bash', 01:22:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Olgkr8aD3X/Listeners', 01:22:48 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 01:22:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:48 INFO - 'USER': 'cltbld', 01:22:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:48 INFO - 'XPC_FLAGS': '0x0', 01:22:48 INFO - 'XPC_SERVICE_NAME': '0', 01:22:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:48 INFO - Downloading/unpacking blobuploader==1.2.4 01:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:50 INFO - Downloading blobuploader-1.2.4.tar.gz 01:22:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:22:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:22:50 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 01:22:50 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 01:22:50 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 01:22:50 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 01:22:50 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 01:22:50 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 01:22:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:22:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:22:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:22:51 INFO - Downloading docopt-0.6.1.tar.gz 01:22:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:22:51 INFO - Installing collected packages: blobuploader, requests, docopt 01:22:51 INFO - Running setup.py install for blobuploader 01:22:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:22:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:22:52 INFO - Running setup.py install for requests 01:22:52 INFO - Running setup.py install for docopt 01:22:52 INFO - Successfully installed blobuploader requests docopt 01:22:52 INFO - Cleaning up... 01:22:52 INFO - Return code: 0 01:22:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:52 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 0x10decba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df60618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffae1d87e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de2fd30>, '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 0x10db94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de27b20>, '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.anHJFGrKsu/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.Olgkr8aD3X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 01:22:52 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 01:22:52 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 01:22:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.anHJFGrKsu/Render', 01:22:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:52 INFO - 'HOME': '/Users/cltbld', 01:22:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:52 INFO - 'LOGNAME': 'cltbld', 01:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:52 INFO - 'MOZ_NO_REMOTE': '1', 01:22:52 INFO - 'NO_EM_RESTART': '1', 01:22:52 INFO - 'PAGER': '/bin/cat', 01:22:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:52 INFO - 'PWD': '/builds/slave/test', 01:22:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:52 INFO - 'SHELL': '/bin/bash', 01:22:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Olgkr8aD3X/Listeners', 01:22:52 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 01:22:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:52 INFO - 'USER': 'cltbld', 01:22:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:52 INFO - 'XPC_FLAGS': '0x0', 01:22:52 INFO - 'XPC_SERVICE_NAME': '0', 01:22:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:22:53 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-glEmYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:22:53 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-0jIxXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:22:53 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-3XJ6r5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:22:53 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-XrINO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:22:53 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Oq24gu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:22:53 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ITp12u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:22:53 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-kEDEd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:22:53 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-LIw1rE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:22:53 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-d5pWAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:22:54 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-VwoRzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:22:54 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-5EelCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:22:54 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Lbxh1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:22:54 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-JMpIBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:22:54 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-K7aOPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:54 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-PiKyFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:22:54 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-kFClCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:22:54 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-vug103-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:22:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:22:54 INFO - Running setup.py install for manifestparser 01:22:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:22:55 INFO - Running setup.py install for mozcrash 01:22:55 INFO - Running setup.py install for mozdebug 01:22:55 INFO - Running setup.py install for mozdevice 01:22:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:22:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:22:55 INFO - Running setup.py install for mozfile 01:22:55 INFO - Running setup.py install for mozhttpd 01:22:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:22:55 INFO - Running setup.py install for mozinfo 01:22:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:22:55 INFO - Running setup.py install for mozInstall 01:22:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:22:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:22:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:22:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:22:56 INFO - Running setup.py install for mozleak 01:22:56 INFO - Running setup.py install for mozlog 01:22:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:22:56 INFO - Running setup.py install for moznetwork 01:22:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:22:56 INFO - Running setup.py install for mozprocess 01:22:56 INFO - Running setup.py install for mozprofile 01:22:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:22:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:22:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:22:56 INFO - Running setup.py install for mozrunner 01:22:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:22:56 INFO - Running setup.py install for mozscreenshot 01:22:57 INFO - Running setup.py install for moztest 01:22:57 INFO - Running setup.py install for mozversion 01:22:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:22:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:22:57 INFO - Cleaning up... 01:22:57 INFO - Return code: 0 01:22:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:22:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:57 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 0x10decba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df60618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffae1d87e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de2fd30>, '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 0x10db94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de27b20>, '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.anHJFGrKsu/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.Olgkr8aD3X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 01:22:57 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 01:22:57 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 01:22:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.anHJFGrKsu/Render', 01:22:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:22:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:22:57 INFO - 'HOME': '/Users/cltbld', 01:22:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:22:57 INFO - 'LOGNAME': 'cltbld', 01:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:57 INFO - 'MOZ_NO_REMOTE': '1', 01:22:57 INFO - 'NO_EM_RESTART': '1', 01:22:57 INFO - 'PAGER': '/bin/cat', 01:22:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:57 INFO - 'PWD': '/builds/slave/test', 01:22:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:22:57 INFO - 'SHELL': '/bin/bash', 01:22:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Olgkr8aD3X/Listeners', 01:22:57 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 01:22:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:22:57 INFO - 'USER': 'cltbld', 01:22:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:22:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:57 INFO - 'XPC_FLAGS': '0x0', 01:22:57 INFO - 'XPC_SERVICE_NAME': '0', 01:22:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:22:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:22:57 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-uMVmu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:22:57 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)) 01:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:22:57 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-rRzf7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:22:57 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)) 01:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:22:57 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-_uHQ8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:22:58 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)) 01:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:22:58 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-LJPSWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:22:58 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)) 01:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:22:58 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-o_RV3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:22:58 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)) 01:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:22:58 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-7T_LSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:22:58 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)) 01:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:22:58 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-jh9uLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:22:58 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)) 01:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:22:58 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-9YqymT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:22:58 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)) 01:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:22:58 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-3R9Fju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:22:58 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)) 01:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:22:58 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-B9dmUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:22:58 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)) 01:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:22:58 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-MvIx6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:22:58 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)) 01:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:22:58 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-zS6dDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:22:58 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)) 01:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:22:58 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-zOIru7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:22:59 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)) 01:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:22:59 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-KXaYwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:22:59 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)) 01:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:59 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-jMaftv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:59 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)) 01:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:22:59 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-KhPS7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:22:59 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)) 01:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:22:59 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-5aZy4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:22:59 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)) 01:22:59 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)) 01:22:59 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)) 01:22:59 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)) 01:22:59 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)) 01:22:59 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)) 01:22:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:22:59 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 01:22:59 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 01:22:59 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 01:22:59 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 01:22:59 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 01:22:59 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 01:23:01 INFO - Downloading blessings-1.6.tar.gz 01:23:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:23:02 INFO - Installing collected packages: blessings 01:23:02 INFO - Running setup.py install for blessings 01:23:02 INFO - Successfully installed blessings 01:23:02 INFO - Cleaning up... 01:23:02 INFO - Return code: 0 01:23:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:23:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:23:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:23:02 INFO - Reading from file tmpfile_stdout 01:23:02 INFO - Current package versions: 01:23:02 INFO - blessings == 1.6 01:23:02 INFO - blobuploader == 1.2.4 01:23:02 INFO - docopt == 0.6.1 01:23:02 INFO - functools32 == 3.2.3-2 01:23:02 INFO - jsonschema == 2.5.1 01:23:02 INFO - manifestparser == 1.1 01:23:02 INFO - mozInstall == 1.12 01:23:02 INFO - mozcrash == 0.17 01:23:02 INFO - mozdebug == 0.1 01:23:02 INFO - mozdevice == 0.48 01:23:02 INFO - mozfile == 1.2 01:23:02 INFO - mozhttpd == 0.7 01:23:02 INFO - mozinfo == 0.9 01:23:02 INFO - mozleak == 0.1 01:23:02 INFO - mozlog == 3.3 01:23:02 INFO - moznetwork == 0.27 01:23:02 INFO - mozprocess == 0.23 01:23:02 INFO - mozprofile == 0.28 01:23:02 INFO - mozrunner == 6.12 01:23:02 INFO - mozscreenshot == 0.1 01:23:02 INFO - mozsystemmonitor == 0.3 01:23:02 INFO - moztest == 0.7 01:23:02 INFO - mozversion == 1.4 01:23:02 INFO - psutil == 3.1.1 01:23:02 INFO - requests == 1.2.3 01:23:02 INFO - wsgiref == 0.1.2 01:23:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:23:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:23:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:23:02 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 0x10decba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df60618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffae1d87e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de2fd30>, '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 0x10db94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de27b20>, '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.anHJFGrKsu/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.Olgkr8aD3X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 01:23:02 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 01:23:02 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 01:23:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.anHJFGrKsu/Render', 01:23:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:23:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:23:02 INFO - 'HOME': '/Users/cltbld', 01:23:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:23:02 INFO - 'LOGNAME': 'cltbld', 01:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:23:02 INFO - 'MOZ_NO_REMOTE': '1', 01:23:02 INFO - 'NO_EM_RESTART': '1', 01:23:02 INFO - 'PAGER': '/bin/cat', 01:23:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:23:02 INFO - 'PWD': '/builds/slave/test', 01:23:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:23:02 INFO - 'SHELL': '/bin/bash', 01:23:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Olgkr8aD3X/Listeners', 01:23:02 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 01:23:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:23:02 INFO - 'USER': 'cltbld', 01:23:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:23:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:23:02 INFO - 'XPC_FLAGS': '0x0', 01:23:02 INFO - 'XPC_SERVICE_NAME': '0', 01:23:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:23:02 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)) 01:23:02 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)) 01:23:02 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)) 01:23:02 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)) 01:23:02 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)) 01:23:02 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)) 01:23:02 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)) 01:23:02 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)) 01:23:02 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)) 01:23:02 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)) 01:23:02 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)) 01:23:02 INFO - Cleaning up... 01:23:02 INFO - Return code: 0 01:23:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:23:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:23:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:23:02 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 0x10decba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df60618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffae1d87e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de2fd30>, '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 0x10db94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de27b20>, '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.anHJFGrKsu/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.Olgkr8aD3X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 01:23:02 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 01:23:02 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 01:23:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.anHJFGrKsu/Render', 01:23:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:23:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:23:02 INFO - 'HOME': '/Users/cltbld', 01:23:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:23:02 INFO - 'LOGNAME': 'cltbld', 01:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:23:02 INFO - 'MOZ_NO_REMOTE': '1', 01:23:02 INFO - 'NO_EM_RESTART': '1', 01:23:02 INFO - 'PAGER': '/bin/cat', 01:23:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:23:02 INFO - 'PWD': '/builds/slave/test', 01:23:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:23:02 INFO - 'SHELL': '/bin/bash', 01:23:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Olgkr8aD3X/Listeners', 01:23:02 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 01:23:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:23:02 INFO - 'USER': 'cltbld', 01:23:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:23:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:23:02 INFO - 'XPC_FLAGS': '0x0', 01:23:02 INFO - 'XPC_SERVICE_NAME': '0', 01:23:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:23:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:23:03 INFO - Cleaning up... 01:23:03 INFO - Return code: 0 01:23:03 INFO - Running post-action listener: _resource_record_post_action 01:23:03 INFO - Running post-action listener: _start_resource_monitoring 01:23:03 INFO - Starting resource monitoring. 01:23:03 INFO - [mozharness: 2016-09-09 08:23:03.188982Z] Finished create-virtualenv step (success) 01:23:03 INFO - [mozharness: 2016-09-09 08:23:03.189966Z] Running install step. 01:23:03 INFO - Running pre-action listener: _resource_record_pre_action 01:23:03 INFO - Running main action method: install 01:23:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:23:03 INFO - Reading from file tmpfile_stdout 01:23:03 INFO - Detecting whether we're running mozinstall >=1.0... 01:23:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:23:03 INFO - Reading from file tmpfile_stdout 01:23:03 INFO - Output received: 01:23:03 INFO - Usage: mozinstall [options] installer 01:23:03 INFO - Options: 01:23:03 INFO - -h, --help show this help message and exit 01:23:03 INFO - -d DEST, --destination=DEST 01:23:03 INFO - Directory to install application into. [default: 01:23:03 INFO - "/builds/slave/test"] 01:23:03 INFO - --app=APP Application being installed. [default: firefox] 01:23:03 INFO - mkdir: /builds/slave/test/build/application 01:23:03 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'] 01:23:03 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 01:23:37 INFO - Reading from file tmpfile_stdout 01:23:37 INFO - Output received: 01:23:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:23:37 INFO - Running post-action listener: _resource_record_post_action 01:23:37 INFO - [mozharness: 2016-09-09 08:23:37.998229Z] Finished install step (success) 01:23:37 INFO - [mozharness: 2016-09-09 08:23:37.998330Z] Running run-tests step. 01:23:37 INFO - Running pre-action listener: _resource_record_pre_action 01:23:37 INFO - Running main action method: run_tests 01:23:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:23:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:23:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:23:38 INFO - 2016-09-09 01:23:38.011 screenresolution[1644:8567] starting screenresolution argv=screenresolution get 01:23:38 INFO - 2016-09-09 01:23:38.013 screenresolution[1644:8567] Display 0: 1600x1200x32@60 01:23:38 INFO - 2016-09-09 01:23:38.018 screenresolution[1645:8569] starting screenresolution argv=screenresolution list 01:23:38 INFO - Available Modes on Display 0 01:23:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:23:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:23:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:23:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:23:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:23:38 INFO - Intel Iris: 01:23:38 INFO - Chipset Model: Intel Iris 01:23:38 INFO - Type: GPU 01:23:38 INFO - Bus: Built-In 01:23:38 INFO - VRAM (Dynamic, Max): 1536 MB 01:23:38 INFO - Vendor: Intel (0x8086) 01:23:38 INFO - Device ID: 0x0a2e 01:23:38 INFO - Revision ID: 0x0009 01:23:38 INFO - Displays: 01:23:38 INFO - Display: 01:23:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:23:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:23:38 INFO - Display Serial Number: 1600x1200 60 01:23:38 INFO - Main Display: Yes 01:23:38 INFO - Mirror: Off 01:23:38 INFO - Online: Yes 01:23:38 INFO - Rotation: Supported 01:23:38 INFO - Return code: 0 01:23:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:23:38 INFO - Python 2.7.3 01:23:38 INFO - Return code: 0 01:23:38 INFO - grabbing minidump binary from tooltool 01:23:38 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')]} 01:23:38 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 0x10de2fd30>, '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 0x10db94db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de27b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:23:38 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 01:23:38 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 01:23:38 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 01:23:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:23:38 INFO - Return code: 0 01:23:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:23:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:23:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:23:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:23:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:23:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0228 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:23:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.anHJFGrKsu/Render', 01:23:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:23:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:23:38 INFO - 'HOME': '/Users/cltbld', 01:23:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:23:38 INFO - 'LOGNAME': 'cltbld', 01:23:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:23:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:23:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:23:38 INFO - 'MOZ_NO_REMOTE': '1', 01:23:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:23:38 INFO - 'NO_EM_RESTART': '1', 01:23:38 INFO - 'PAGER': '/bin/cat', 01:23:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:23:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:23:38 INFO - 'PWD': '/builds/slave/test', 01:23:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:23:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:23:38 INFO - 'SHELL': '/bin/bash', 01:23:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Olgkr8aD3X/Listeners', 01:23:38 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 01:23:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:23:38 INFO - 'USER': 'cltbld', 01:23:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:23:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:23:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:23:38 INFO - 'XPC_FLAGS': '0x0', 01:23:38 INFO - 'XPC_SERVICE_NAME': '0', 01:23:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:23:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:23:38 INFO - mozversion application_buildid: 20160909000818 01:23:38 INFO - mozversion application_changeset: 399174fe175afe9d112d5c4e115a5b92395f3b1b 01:23:38 INFO - mozversion application_display_name: Nightly 01:23:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:23:38 INFO - mozversion application_name: Firefox 01:23:38 INFO - mozversion application_remotingname: firefox 01:23:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 01:23:38 INFO - mozversion application_vendor: Mozilla 01:23:38 INFO - mozversion application_version: 51.0a1 01:23:38 INFO - mozversion platform_buildid: 20160909000818 01:23:38 INFO - mozversion platform_changeset: 399174fe175afe9d112d5c4e115a5b92395f3b1b 01:23:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 01:23:38 INFO - mozversion platform_version: 51.0a1 01:23:38 INFO - using testdate: 1473409418 01:23:38 INFO - actual date: 1473409418 01:23:38 INFO - starting webserver on 'localhost:49274' 01:23:38 INFO - SUITE-START | Running 2 tests 01:23:38 INFO - TEST-START | dromaeo_css 01:23:38 INFO - operating with platform_type : mac_ 01:23:38 INFO - Initialising browser for dromaeo_css test... 01:23:38 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmph2iJtL/profile http://localhost:49274/getInfo.html) 01:23:41 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 01:23:41 INFO - PROCESS | 1655 | colorDepth:24 01:23:41 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 01:23:41 INFO - PROCESS | 1655 | __metrics 01:23:41 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:23:41 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:23:41 INFO - TEST-INFO | 1655: exit 0 01:23:41 INFO - Browser initialized. 01:23:41 INFO - Running cycle 1/1 for dromaeo_css test... 01:23:41 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmph2iJtL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:23:53 INFO - PROCESS | 1658 | RSS: Main: 232443904 01:23:53 INFO - PROCESS | 1658 | 01:24:44 INFO - PROCESS | 1658 | 1473409484950 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] 01:26:56 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 01:26:57 INFO - PROCESS | 1658 | RSS: Main: 349786112 01:26:57 INFO - PROCESS | 1658 | 01:30:00 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 01:30:01 INFO - PROCESS | 1658 | RSS: Main: 370618368 01:30:01 INFO - PROCESS | 1658 | 01:33:03 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 01:33:04 INFO - PROCESS | 1658 | RSS: Main: 345243648 01:33:04 INFO - PROCESS | 1658 | 01:36:06 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 01:36:07 INFO - PROCESS | 1658 | RSS: Main: 344997888 01:36:07 INFO - PROCESS | 1658 | 01:39:10 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 01:39:11 INFO - PROCESS | 1658 | RSS: Main: 330338304 01:39:11 INFO - PROCESS | 1658 | 01:42:14 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 01:42:14 INFO - PROCESS | 1658 | RSS: Main: 427991040 01:42:14 INFO - PROCESS | 1658 | 01:42:14 INFO - PROCESS | 1658 | __start_tp_report 01:42:14 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 01:42:14 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 01:42:14 INFO - PROCESS | 1658 | |i|pagename|runs| 01:42:14 INFO - PROCESS | 1658 | |0;dojo.html;3340.749443791716;3355.9161020974475;3374.5444365010717;3407.812570308632;3457.2048428861362;5678.43215678432;5735.799247026355;5792.913106303409;5858.062709966406;5880.206172166756;5826.533877289818;5828.854278643036;5869.647821130735;5872.324682661484;5909.379515150912;5153.1754919212935;5198.7660555274915;5259.263703081552;5284.630075894689;5378.973105134489;5346.91979620306;5390.8382748517415;5403.432639572848;5407.999520088796;5439.945600544003;5207.765650545734;5242.711650859202;5257.447967963385;5322.840314790563;5397.919031214535;5243.6067294952645;5254.605904557136;5264.973675131638;5304.920426193572;5412.296401467784;5213.218017297398;5260.710660913648;5276.314079169683;5329.680219186823;5335.1197052486295;5679.062954612484;5717.42825717425;5900.203472531196;5916.26046744157;5919.733611987428;6218.409251121137;6243.2508099027955;6255.093011513351;6292.779752708633;6328.303886572473;5193.766280517386;6222.315545290015;6232.594881332699;6247.5939063960695;6248.781292654735;5712.485876271073;5769.94230057694;5909.6158749681135;5910.911336329958;5923.614965027303;6140.565599444434;6180.969095154585;6213.96893015532;6268.592541484789;6285.151504546919;6214.720337584819;6241.126242326078;6242.313345531988;6254;6265.8746825063245;6150.446459818638;6236;6285.465735412449;6303.6532990685955;6306.369363063657;6171.691415429211;6186.845328866814;6245.687715614201;6327.17746692927;6329.936700633027;6273.027680709497;6294.276158241769;6299.149614801943;6301.590396624205;6301.873962520724;4084.469019027567;4945.604351651859;4952.653314267966;4964.304997300381;4992.350994370781;4422.845200418034;4440.2007638625355;4488.932666010012;4536.070105628338;4571.200039993055;5804;5806.564507661942;5817.505512031525;5819.214406055213;5825.650460972355;6210;6213.068039793977;6247.968760156261;6258.843528911722;6274.372562743689;5660.320761508563;5697.173909783059;5722.7710891563875;5763.020286551213;5768.884622307531;5478.91781623276;5486.588505862076;5489.780408783604;5493.642913210629;5520.972395137998;6336.873262534816;6337;6342.778002769881;6342.9682851587295;6360.586561873556;6192.876142477216;6216.129741836237;6235.220597425322;6257.874842503215;6272.749090036347;6317.936820631735;6331.6517591531065;6345.90481142774;6349.396807303298;6364.490840732823;6240.625562466266;6280.717367718555;6288.245410550763;6290.968545157245;6292;6166.876662466815;6192.411720886508;6228.5951413158;6256.343083976117;6267.2479302484;6190.783322583688;6231.501479881508;6237.656928868956;6244.188255526752;6249.125122482947;6188.412100850412;6210.968945155245;6215.192025036717;6217.440430361289;6221.09794079865;5848.2689663792025;5993.981023226149;5999.820005399845;6067.393260673897;6093.421124993296;6177.135201071944;6214.098955651315;6231.2522497300615;6231.9688401557705;6247.12540244357;6072.512060476416;6077.756889724362;6081.391860813883;6086.300075491198;6088.238970128734 01:42:14 INFO - PROCESS | 1658 | |1;ext.html;444.93216280925776;469.631339398572;470.6164475952099;472.48263151848727;534.3000669123446;29625;30699.846500767493;31696.36607267859;31697.36605267899;31713.68286317139;40628;41615;41639;43724.78137609307;43890.56109438905;9561.569729362178;9739.172170365491;9743.317967742261;9745.171660408838;9747.561359738793;8645.438046526988;9635.421874687507;9742.366746161511;9745.415275083482;9755.95122024389;11784.234024788404;11968;11987.460564274585;12206.084543659217;12257.44841482131;11816.881831181707;11927.821082683766;11938.701532461759;11947.58183463579;11962.40187990597;8568.143185681382;10291.125254353388;11771.94114029433;11832.763344733057;11866.940665296705;38598.80700596507;38666.226675466336;38817;41174.794126029476;41236.38145427821;3239.141627468699;3338.2488939988552;3392.9830350848333;3417.9658203418144;3697.0572504011307;30346.54480182799;30697.38605227883;30958;30989.22526936822;31256.843715781502;3161.96838031619;3181.9045428636946;3201.375731732313;3274.099622603779;3308.553345298378;507592;507741;510513.9999999963;511243;511800;49881.75059124681;56797.148042779394;58104.70947645235;58185.70907145522;59463.70268148631;62594;62647.373526265066;62874.68562657249;62920.68539657273;63002;15764.68470630581;15902.840971590136;15912.283947222402;15920.124393158487;15929.575141344667;10201.642942496977;12286.631401057983;12336.50653973831;12367.319797411048;12420.62738117858;14418.783718244234;14748.483803066842;16659.083750393846;16663.333866581288;16814.07522586245;6612.5040621952585;6742.224644165984;6841.692123854339;6877.243503214642;6892.931070689329;834.5493433545938;861.7931696392945;940.4075432477496;973.9415635061919;1083.9024487796137;27891.30271743182;28372.290692732433;29414.852925735664;30059.849700751794;30625;1020.4846552490944;1037.509976057468;1056.1339701444895;1061;1064.7338165458636;13643.795343069862;14001.789973150411;14441.78337324941;15369.077855328716;15392.999455035388;655258.4741525905;660854;661583;662274;662387;56427.153592696144;56747.71626141926;56783.14825277541;56811.71594142003;56899.43100568941;1335.2789493673502;1361.0744693608442;1378.269517155909;1381.544090450149;1382.246675561801;26870.694118115505;28433.857830711542;28517.572236416054;29676.40647187001;29764.80049243935;50093.74953125211;50273.99452011012;50422.49577504178;50447.74776126096;50463.74768126136;592677;596707;596835;597889;598030;25477.3630659235;33019;40575.59424405836;40609.79695101506;41066.79466602648;36946.076348090435;46143.538564613926;46370;46390.07219855652;46565;38167.427488587135;38552.036199095244;38614;39321;39910.800445997585;33071.33857322888;37337.31982060868;37928.741757609685;38126.237475249785;39230;32026.039218823473;36300.63699363078;37201;37636.05909852275;37687.869363919126;31629.525557117122;34126;34293;34409.82795086008;34425.72955569217;22480.88759556192;22544.436389089748;24989;25277.747222528276;25651 01:42:14 INFO - PROCESS | 1658 | |2;jquery.html;312.5187211693992;340.1462031926004;355.79541763485986;363.07235014537855;394.57583098169476;15274.007189532687;18157.818421815777;20365.287214947442;20500.974951252432;20548.89725551374;20033.398998030083;20088.196472141095;20339.18643254268;21043.158273669036;21273.57452850942;5407.3781515125675;5853.707314634273;6095.9695201524155;6097.176881121036;6104.938950610482;6589.275179730226;6619.073329733841;6639.302873198317;6794.286599907013;6803.013563033357;6307.621542707452;6536.248331441874;6548.869022619545;6569.37590928861;6605.70274337656;6371.617702937792;6590.439812615933;6640.269570347258;6642.568233064883;6650.966745166292;5743.994800909866;5798.971005144947;5802.390748971382;5805.88388232233;5831.475167234927;23437.88281058601;23821;24499.632505512433;25413.364665883502;26115.608265876028;2017.9293724719564;2102.726645536086;2273.8408311418207;2395.73646898841;2892.248015515981;9713.125818676353;10000.79998400028;10185.643502477378;10324.72855971685;14087;1837.5406148462885;1840.5122642499748;1863.4409677096817;1866.5706887415834;1881.595456976743;105261.42107868388;111177.44411277973;111672;113311;115046.99999999916;11240.583274596002;11259.437028148559;11382.317060976367;11498.597549085742;11615.012723918393;14193.503227386991;14194.503192388216;14216.573502794727;14223.146611203361;14228.644283892778;8136.83726325482;8283.420160588821;8295.419320647497;8297;8402.41183117176;8196;8197.877031844528;8369.455985361054;8375.078741338448;8388.37087218461;4315.762633055149;4437.312216606431;4450.5104438511735;4467.6202522785925;4581.244094724333;1695.3981336625568;1800.6488734696609;1816.6366726654721;1831.4871835885974;1901.306023301485;377.69595475641825;379.18664464723014;413.3964411958574;793.7340990768042;867.7917299848117;11648.53405863773;11657.300561966309;11737.002354799937;11995.940020300017;12056.336901470328;369.9622558722808;373.32428304768456;889.1908363389284;912.1289168843738;923.7505873414275;8856.822863542822;11057.944710276397;11163.49704212495;11189.888101118884;11306.886931130748;553880;576781;587052;587196;599338;112622;115201;130551.89489237659;132005;147583;964.6912987843823;970.112347202302;1006.2654262388497;1007.3502590829021;1011.9241056920761;12959.870401295866;14028.859711403164;18492.907535462236;18640.161192746087;19486.61026779428;42347.57652423436;43385;54748.72625636846;54859.72570137124;56030.159547606;78241.43517129739;78298.04254893673;78464.60767696354;79582;80062;11247.550097996316;11293.548258069914;11294.830577541179;11675.299482031105;11869.10981676395;11000.22998390137;12838.42227099764;13064.54274100402;13259.93920486378;13395.330233488481;11143.498542565441;11198.552057917917;11655.883441165819;11726.70683232926;11766.411679416165;11194.496247668712;11245.100391968459;11297.26567773092;11708.297502149899;11787.41062946832;11375.715607109889;11425.028872545594;11446.255993360404;11836.76326473483;11838.644840654793;11666.88333116658;11725.648230553097;11956.940215298868;11972;12100.273983561014;9482.38364506305;10154.62912506802;11189;11309.773804524028;11817.350045747222 01:42:14 INFO - PROCESS | 1658 | |3;mootools.html;1494.8430414806446;1505.3000354835003;1672.6069373697187;1824.2885274742844;1828.8079751626078;8352.331813454924;8899.33255005874;8909.198172164504;8911.331650126234;8913.955430222848;7711.2288771123;7954.2841144297;8390.790230244247;8582.970043594762;8661.090585488511;2370.3007612754236;2399.7120345558556;2405.6391541268777;2406.9638955415685;2419.4314336130997;2382.2376839411395;2398.7121545414575;2413.779323022823;2420.4432980414517;2421.6367544868235;2384.0582969726947;2398.1486572266854;2409.9879500602474;2413.1915808204185;2417.62526808345;2368.6920700309024;2377.8335516513766;2387.39121524012;2389.3668177932864;2403.435192729707;2342.9648555271683;2358.657994590792;2388.8924998375114;2390.2829151254728;2392.9401764956015;8773.736787896374;8871.733847984506;8880.644774209024;8888.3333749969;8904.109589041109;5157.638965272433;5322.988632159877;5328.227407025959;5334.306540149794;5350.384705758848;3641.635836416364;3644.671979521856;3697.1126929536986;3697.149655579205;3714.7399682022;1086.8261078227445;1095.1512577752226;1102.1348241630362;1111.8721347045112;1122.2256642916443;11679.357635330054;11798.528058877635;11812.704682382922;11823.586174483851;11823.704407389798;4455.821767129279;4566.06395688883;4736.8105275788585;4754.120487709784;4759.809607615726;9936.850947235796;9938.701838944844;9940.055694708992;9948.104670579538;9977.900220997843;5296.1261391870685;5318.175682769177;5320.255164277004;5322.2548843161985;5323.121684922022;4202.957970420257;4255.489341279066;4412.227860124465;4821.686590371614;4881.608741508653;3864.9227015459533;3867.16855875988;4003.7597744134864;4007.2968988179905;4029.2545879012478;1103.7020004598714;1268.7081971146504;1296.714722760991;1300.206873806977;1302.3813688498078;773.8684423647993;826.2233500509622;836.0737419033383;876.4434584039182;953.3564843730454;5637.8590535236945;5665.716714164275;5945.851353716192;6030.306514750772;6166.4758495527485;874.3136637739472;916.7662246127328;944.2493217891655;962.0619895601733;995.2585323933715;3218.8733943120187;3741.644543768338;3966.186931678999;4039.7374170678813;4080.3471444568727;10411.271211015157;10626.574937002588;10726.946365268124;10745.623903163352;10755.032047115797;10010.899891000996;10445.791084178274;10448.007439293408;10684.786304274026;10742.78514429707;7961.681532738626;8199.918000820035;8208.671653133928;8216.34269258458;8217.424780265324;5880.353161152184;5913.70431478433;5929.881402372014;6055.396179642796;6207.193064901534;9303.906960930575;9967.900320996698;10098.798024039625;10151.340162889388;10155.441450720282;10082.596696132072;10122.44326562046;10200.4389758564;10224.846627300745;10309.48452577353;6768.56004359715;7284.198738138906;7415;7578.545287282781;7585.6965721370525;8118;8213.835723285381;8343.874841877256;8356.707515236938;8357.582120894052;7301.780946571611;7411.4070874331;7419.666115024945;7474.738384156529;7558.773236802905;7299;7303.488755787256;7355.2277010913085;7467.887981680171;7492.812679683051;6642.368974947372;6762.323767623198;6842.452603791784;6845.657717114024;6846.178458585002;5076.289319495199;5091.770870310771;5132.43543210254;5175.9741201293755;5268.894622107459;4788.808447662054;4952.925706114339;4990.820561941298;5059.367579052619;5102.4897510248675 01:42:14 INFO - PROCESS | 1658 | |4;prototype.html;251.26755507695066;295.49912897638484;304.9344390955945;308.5941986288031;311.2312587907867;4379.853871602915;5035.974820125894;5451.427600101987;5463.644863083901;5487.231787549741;5105.3873535175735;5242.475752424755;5315.840524784263;5354.303940487741;5468.9726551367285;3497.7551571390013;4112.2803509385885;4141.420201171838;4142.46148000761;4186.351115577075;3579.355715971124;3630.509881166048;4114.683301343572;4152.335626299792;4201.411802347674;3909.6090390960962;4087.57080506547;4139.56534563871;4140.3168477201225;4160.791960401968;3638.490611314418;4109.363048727452;4120.361343991671;4125.174965006996;4128.60778226068;3387.8644854205804;3512.7541072124964;3513.7540372173958;3526.2947410518;3528.5060091587197;5066.265391518209;5401.70290634015;5419.295491586108;5431.701256430894;5437.4562543745305;4179.874603761861;4182.9790851045855;4278.165757677254;4490.618297444721;4742.430908291027;4783.9475315430545;5819.67991760453;5860.824175274706;5864.794732184353;5880.26496687914;3421.1105112670634;3797.8413637067365;3917.2948869203533;3932.1545867638597;3933.272344616255;5623.734659701617;5803.245578074865;5855.8536036599;5932.644041357532;5945.346011938726;3388.288459423549;3738.0281126906657;3899.941500877489;3913.882583522499;3924.2543916655745;3341.3818443588016;3655.6527129922615;3977.900552486211;3986.980065099714;4000.979995100006;3895.240428116547;3904.4338570907057;4489.608221451361;4625.722456652612;4683.625309975194;5716.456936591017;5720.456556627114;5729.541636669056;5745.396733343023;5759.65442073477;1278.622806272152;1331.6271443995697;1355.213975893979;1356.1456282542133;1362.9863701363058;766.4290103872539;773.8585586260222;825.6490991328661;853.1979938857454;866.5139286124202;3916.7845768483007;3926.3521518949055;4087.7547347159266;4476.4180656514445;4511.954880451219;4480.922360651791;4606.654500912445;4842.467328593852;5047.693760586822;5092.719900405513;3631.202554735423;4209.979209979228;4328.761918094535;4469.687121901436;4556.70381425213;2102.36928921323;2492.4890397468475;2572.5755250383477;2612.987999240574;2642.0356569851865;5133.153029750049;5150.4334523202415;5163.690178589297;5165.380154381498;5236.842894713165;4378.840101903204;4422.071365013319;4769.642276829251;5066.4680208577565;5101.821436249714;4578.717958971427;4752.738599377068;4887.731174785421;5122.667026643257;5138.655797970069;4381.671374646978;4489.91020179588;4514.68397212192;4806;4970.27930950003;4592.920663644108;4612.53874612536;4850.005748821553;4864.73243971585;4882.6338024649;4091.6726661867574;4414.84548040817;4979.576735977483;4983.8504844854715;5167.767450464663;2874.3820078682347;3398.4732366483236;3404.3531728972407;3418.897433076912;3426.074959760801;4524.411826462539;4855.514448555116;4957.339291337298;4986.301917731448;5049.6465247432325;4170.020045289415;4201.537830838544;4615.746133962664;4808.639352048541;4812.205986012269;4705.976470117627;4739.099571081483;4779.617630589614;4787.234042553175;4823.324734537097;4393.802278897521;4607.562281583244;4674.719516829001;4691;4723.480417154179;4919.532644398776;5377.704226267437;5697.4302569742695;5822.912656310074;5868.853278668067;5277.577793776411;5634.549236061187;5722.971385143048;5739.827805165852;5787 01:42:14 INFO - PROCESS | 1658 | |5;yui.html;641.5765594707492;755.9584222867744;779.703712589216;806.8749343851704;812.4434762187898;14869.40522379105;16304;16361.100139492322;16362.100084495349;16363.754543681824;14388.208648524327;14779.92610036951;14789.260536973135;14802.185879776607;14805.703885922276;818.5866137600533;821.5645707774889;835.7701632051204;840.1430540848331;841.3731769831484;659.1529884098934;826.6693322670919;828.2876726015621;829.0383155539575;829.2288172000037;726.832828449457;863.3524856357732;887.1483375959087;890.5725251879102;894.6868595991416;884.0849720539243;884.2395539835736;884.681514654727;885.5572213893054;887.1660638999319;517.627308337996;534.6070638081007;653.9084528166061;655.131950166032;656.5010591950153;12342.506299748;12452.31512266825;14386.424543018267;14925.104493730303;14968.47610333633;13686.178829270177;14262.429502819874;14268.286585670676;14403.78394324086;14511;11538.134639901957;11827.645170644895;11940.880591194064;12115.757684846341;12384.938075309567;3762.8682996095004;3780.6030366811365;3789.5073640426845;3793.506844110276;3934.390169523728;119746.99999999913;119821;120498;120533;122041;10030;10773.761017482933;10816.296940698987;10901.34591924487;11315;21744.89127554352;21828.45428864291;22002;22023.22918697838;22047.66928496074;7821.139674635786;7921.920780792234;7957.880631790527;8005.599720013976;8061.565383391706;3759.5676497202626;4463.263561512379;4475.037866858641;4518.390017347616;4635.629149668086;3548.964510354915;3678.8712395066045;3682;3682.447632855093;3687.778733276012;643.5366536094004;654.4032560552332;663.4393937123124;671.0839703804272;673.2123415603755;590.7607418995314;635.8823617630753;667.7562689618254;677.2110491277705;682.7303215230056;9186;9289.953550232342;9423;9632.395519258846;9694.558431756766;542.8235823357425;642.3448082955359;666.1573110015834;685.8511992412689;686.27255109584;4610.95389046112;4616.3998680172035;4646.651501137429;4652.270798489294;4704.670673052922;80907;109559.45220273848;111978;113537;130540;84445;115079.42460287645;115662;115812.42093789477;117677;110659;111800;114530;114638.4268078671;119583.20625190629;8665;8832.823343532964;8878.978917424578;8994.55027248648;9165.725028249164;53342;54641.907161857336;59673.833946390914;62516;64150;84512.57743711243;85221.57389213014;91328.54335728279;94766;97850;11691.941540292244;13220.603381898558;13748.93125534406;13839.446422143405;14812.777808332668;15230.238488075773;15417.922910385376;15871.047737135808;16064.357425702841;16456.177191140636;13850.376733046836;14149.646258843199;14412.639684007983;14422.423103076178;14484.85515144835;14360.640983975483;14468.565943021726;14470;14520.709585808436;14562.490312839;13945.442182312594;13957.93021034929;14002.649933751738;14028.509002184874;14110.717785644434;10956.890431095588;11212.831807523056;11337.603183888525;11374.601888933848;11669.649910502698;10940.343579385262;11339.71650708706;11429.714257143638;11600.419979001184;11738.647840564796 01:42:14 INFO - PROCESS | 1658 | __end_tp_report 01:42:14 INFO - PROCESS | 1658 | __start_cc_report 01:42:14 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1099 01:42:14 INFO - PROCESS | 1658 | __end_cc_report 01:42:14 INFO - PROCESS | 1658 | __startTimestamp1473410534766__endTimestamp 01:42:14 INFO - PROCESS | 1658 | 01:42:14 INFO - PROCESS | 1658 | ------- Summary: start ------- 01:42:14 INFO - PROCESS | 1658 | Number of tests: 6 01:42:14 INFO - PROCESS | 1658 | 01:42:14 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5803.17 Median:6081.39 stddev:644.16 (10.6%) stddev-sans-first:616.49 01:42:14 INFO - PROCESS | 1658 | Values: 3340.7 3355.9 3374.5 3407.8 3457.2 5678.4 5735.8 5792.9 5858.1 5880.2 5826.5 5828.9 5869.6 5872.3 5909.4 5153.2 5198.8 5259.3 5284.6 5379.0 5346.9 5390.8 5403.4 5408.0 5439.9 5207.8 5242.7 5257.4 5322.8 5397.9 5243.6 5254.6 5265.0 5304.9 5412.3 5213.2 5260.7 5276.3 5329.7 5335.1 5679.1 5717.4 5900.2 5916.3 5919.7 6218.4 6243.3 6255.1 6292.8 6328.3 5193.8 6222.3 6232.6 6247.6 6248.8 5712.5 5769.9 5909.6 5910.9 5923.6 6140.6 6181.0 6214.0 6268.6 6285.2 6214.7 6241.1 6242.3 6254.0 6265.9 6150.4 6236.0 6285.5 6303.7 6306.4 6171.7 6186.8 6245.7 6327.2 6329.9 6273.0 6294.3 6299.1 6301.6 6301.9 4084.5 4945.6 4952.7 4964.3 4992.4 4422.8 4440.2 4488.9 4536.1 4571.2 5804.0 5806.6 5817.5 5819.2 5825.7 6210.0 6213.1 6248.0 6258.8 6274.4 5660.3 5697.2 5722.8 5763.0 5768.9 5478.9 5486.6 5489.8 5493.6 5521.0 6336.9 6337.0 6342.8 6343.0 6360.6 6192.9 6216.1 6235.2 6257.9 6272.7 6317.9 6331.7 6345.9 6349.4 6364.5 6240.6 6280.7 6288.2 6291.0 6292.0 6166.9 6192.4 6228.6 6256.3 6267.2 6190.8 6231.5 6237.7 6244.2 6249.1 6188.4 6211.0 6215.2 6217.4 6221.1 5848.3 5994.0 5999.8 6067.4 6093.4 6177.1 6214.1 6231.3 6232.0 6247.1 6072.5 6077.8 6081.4 6086.3 6088.2 01:42:14 INFO - PROCESS | 1658 | 01:42:14 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:71535.30 Median:20449.03 stddev:158403.57 (774.6%) stddev-sans-first:158758.01 01:42:14 INFO - PROCESS | 1658 | Values: 444.9 469.6 470.6 472.5 534.3 29625.0 30699.8 31696.4 31697.4 31713.7 40628.0 41615.0 41639.0 43724.8 43890.6 9561.6 9739.2 9743.3 9745.2 9747.6 8645.4 9635.4 9742.4 9745.4 9756.0 11784.2 11968.0 11987.5 12206.1 12257.4 11816.9 11927.8 11938.7 11947.6 11962.4 8568.1 10291.1 11771.9 11832.8 11866.9 38598.8 38666.2 38817.0 41174.8 41236.4 3239.1 3338.2 3393.0 3418.0 3697.1 30346.5 30697.4 30958.0 30989.2 31256.8 3162.0 3181.9 3201.4 3274.1 3308.6 507592.0 507741.0 510514.0 511243.0 511800.0 49881.8 56797.1 58104.7 58185.7 59463.7 62594.0 62647.4 62874.7 62920.7 63002.0 15764.7 15902.8 15912.3 15920.1 15929.6 10201.6 12286.6 12336.5 12367.3 12420.6 14418.8 14748.5 16659.1 16663.3 16814.1 6612.5 6742.2 6841.7 6877.2 6892.9 834.5 861.8 940.4 973.9 1083.9 27891.3 28372.3 29414.9 30059.8 30625.0 1020.5 1037.5 1056.1 1061.0 1064.7 13643.8 14001.8 14441.8 15369.1 15393.0 655258.5 660854.0 661583.0 662274.0 662387.0 56427.2 56747.7 56783.1 56811.7 56899.4 1335.3 1361.1 1378.3 1381.5 1382.2 26870.7 28433.9 28517.6 29676.4 29764.8 50093.7 50274.0 50422.5 50447.7 50463.7 592677.0 596707.0 596835.0 597889.0 598030.0 25477.4 33019.0 40575.6 40609.8 41066.8 36946.1 46143.5 46370.0 46390.1 46565.0 38167.4 38552.0 38614.0 39321.0 39910.8 33071.3 37337.3 37928.7 38126.2 39230.0 32026.0 36300.6 37201.0 37636.1 37687.9 31629.5 34126.0 34293.0 34409.8 34425.7 22480.9 22544.4 24989.0 25277.7 25651.0 01:42:14 INFO - PROCESS | 1658 | 01:42:14 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:34205.01 Median:20352.24 stddev:97000.28 (476.6%) stddev-sans-first:97239.01 01:42:14 INFO - PROCESS | 1658 | Values: 312.5 340.1 355.8 363.1 394.6 15274.0 18157.8 20365.3 20501.0 20548.9 20033.4 20088.2 20339.2 21043.2 21273.6 5407.4 5853.7 6096.0 6097.2 6104.9 6589.3 6619.1 6639.3 6794.3 6803.0 6307.6 6536.2 6548.9 6569.4 6605.7 6371.6 6590.4 6640.3 6642.6 6651.0 5744.0 5799.0 5802.4 5805.9 5831.5 23437.9 23821.0 24499.6 25413.4 26115.6 2017.9 2102.7 2273.8 2395.7 2892.2 9713.1 10000.8 10185.6 10324.7 14087.0 1837.5 1840.5 1863.4 1866.6 1881.6 105261.4 111177.4 111672.0 113311.0 115047.0 11240.6 11259.4 11382.3 11498.6 11615.0 14193.5 14194.5 14216.6 14223.1 14228.6 8136.8 8283.4 8295.4 8297.0 8402.4 8196.0 8197.9 8369.5 8375.1 8388.4 4315.8 4437.3 4450.5 4467.6 4581.2 1695.4 1800.6 1816.6 1831.5 1901.3 377.7 379.2 413.4 793.7 867.8 11648.5 11657.3 11737.0 11995.9 12056.3 370.0 373.3 889.2 912.1 923.8 8856.8 11057.9 11163.5 11189.9 11306.9 553880.0 576781.0 587052.0 587196.0 599338.0 112622.0 115201.0 130551.9 132005.0 147583.0 964.7 970.1 1006.3 1007.4 1011.9 12959.9 14028.9 18492.9 18640.2 19486.6 42347.6 43385.0 54748.7 54859.7 56030.2 78241.4 78298.0 78464.6 79582.0 80062.0 11247.6 11293.5 11294.8 11675.3 11869.1 11000.2 12838.4 13064.5 13259.9 13395.3 11143.5 11198.6 11655.9 11726.7 11766.4 11194.5 11245.1 11297.3 11708.3 11787.4 11375.7 11425.0 11446.3 11836.8 11838.6 11666.9 11725.6 11956.9 11972.0 12100.3 9482.4 10154.6 11189.0 11309.8 11817.4 01:42:14 INFO - PROCESS | 1658 | 01:42:14 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5702.74 Median:5025.09 stddev:3180.86 (63.3%) stddev-sans-first:3174.06 01:42:14 INFO - PROCESS | 1658 | Values: 1494.8 1505.3 1672.6 1824.3 1828.8 8352.3 8899.3 8909.2 8911.3 8914.0 7711.2 7954.3 8390.8 8583.0 8661.1 2370.3 2399.7 2405.6 2407.0 2419.4 2382.2 2398.7 2413.8 2420.4 2421.6 2384.1 2398.1 2410.0 2413.2 2417.6 2368.7 2377.8 2387.4 2389.4 2403.4 2343.0 2358.7 2388.9 2390.3 2392.9 8773.7 8871.7 8880.6 8888.3 8904.1 5157.6 5323.0 5328.2 5334.3 5350.4 3641.6 3644.7 3697.1 3697.1 3714.7 1086.8 1095.2 1102.1 1111.9 1122.2 11679.4 11798.5 11812.7 11823.6 11823.7 4455.8 4566.1 4736.8 4754.1 4759.8 9936.9 9938.7 9940.1 9948.1 9977.9 5296.1 5318.2 5320.3 5322.3 5323.1 4203.0 4255.5 4412.2 4821.7 4881.6 3864.9 3867.2 4003.8 4007.3 4029.3 1103.7 1268.7 1296.7 1300.2 1302.4 773.9 826.2 836.1 876.4 953.4 5637.9 5665.7 5945.9 6030.3 6166.5 874.3 916.8 944.2 962.1 995.3 3218.9 3741.6 3966.2 4039.7 4080.3 10411.3 10626.6 10726.9 10745.6 10755.0 10010.9 10445.8 10448.0 10684.8 10742.8 7961.7 8199.9 8208.7 8216.3 8217.4 5880.4 5913.7 5929.9 6055.4 6207.2 9303.9 9967.9 10098.8 10151.3 10155.4 10082.6 10122.4 10200.4 10224.8 10309.5 6768.6 7284.2 7415.0 7578.5 7585.7 8118.0 8213.8 8343.9 8356.7 8357.6 7301.8 7411.4 7419.7 7474.7 7558.8 7299.0 7303.5 7355.2 7467.9 7492.8 6642.4 6762.3 6842.5 6845.7 6846.2 5076.3 5091.8 5132.4 5176.0 5268.9 4788.8 4952.9 4990.8 5059.4 5102.5 01:42:14 INFO - PROCESS | 1658 | 01:42:14 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4249.96 Median:4585.82 stddev:1294.45 (28.2%) stddev-sans-first:1262.80 01:42:14 INFO - PROCESS | 1658 | Values: 251.3 295.5 304.9 308.6 311.2 4379.9 5036.0 5451.4 5463.6 5487.2 5105.4 5242.5 5315.8 5354.3 5469.0 3497.8 4112.3 4141.4 4142.5 4186.4 3579.4 3630.5 4114.7 4152.3 4201.4 3909.6 4087.6 4139.6 4140.3 4160.8 3638.5 4109.4 4120.4 4125.2 4128.6 3387.9 3512.8 3513.8 3526.3 3528.5 5066.3 5401.7 5419.3 5431.7 5437.5 4179.9 4183.0 4278.2 4490.6 4742.4 4783.9 5819.7 5860.8 5864.8 5880.3 3421.1 3797.8 3917.3 3932.2 3933.3 5623.7 5803.2 5855.9 5932.6 5945.3 3388.3 3738.0 3899.9 3913.9 3924.3 3341.4 3655.7 3977.9 3987.0 4001.0 3895.2 3904.4 4489.6 4625.7 4683.6 5716.5 5720.5 5729.5 5745.4 5759.7 1278.6 1331.6 1355.2 1356.1 1363.0 766.4 773.9 825.6 853.2 866.5 3916.8 3926.4 4087.8 4476.4 4512.0 4480.9 4606.7 4842.5 5047.7 5092.7 3631.2 4210.0 4328.8 4469.7 4556.7 2102.4 2492.5 2572.6 2613.0 2642.0 5133.2 5150.4 5163.7 5165.4 5236.8 4378.8 4422.1 4769.6 5066.5 5101.8 4578.7 4752.7 4887.7 5122.7 5138.7 4381.7 4489.9 4514.7 4806.0 4970.3 4592.9 4612.5 4850.0 4864.7 4882.6 4091.7 4414.8 4979.6 4983.9 5167.8 2874.4 3398.5 3404.4 3418.9 3426.1 4524.4 4855.5 4957.3 4986.3 5049.6 4170.0 4201.5 4615.7 4808.6 4812.2 4706.0 4739.1 4779.6 4787.2 4823.3 4393.8 4607.6 4674.7 4691.0 4723.5 4919.5 5377.7 5697.4 5822.9 5868.9 5277.6 5634.5 5723.0 5739.8 5787.0 01:42:14 INFO - PROCESS | 1658 | 01:42:14 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:23641.36 Median:3682.22 stddev:36168.56 (982.2%) stddev-sans-first:36228.80 01:42:14 INFO - PROCESS | 1658 | Values: 641.6 756.0 779.7 806.9 812.4 14869.4 16304.0 16361.1 16362.1 16363.8 14388.2 14779.9 14789.3 14802.2 14805.7 818.6 821.6 835.8 840.1 841.4 659.2 826.7 828.3 829.0 829.2 726.8 863.4 887.1 890.6 894.7 884.1 884.2 884.7 885.6 887.2 517.6 534.6 653.9 655.1 656.5 12342.5 12452.3 14386.4 14925.1 14968.5 13686.2 14262.4 14268.3 14403.8 14511.0 11538.1 11827.6 11940.9 12115.8 12384.9 3762.9 3780.6 3789.5 3793.5 3934.4 119747.0 119821.0 120498.0 120533.0 122041.0 10030.0 10773.8 10816.3 10901.3 11315.0 21744.9 21828.5 22002.0 22023.2 22047.7 7821.1 7921.9 7957.9 8005.6 8061.6 3759.6 4463.3 4475.0 4518.4 4635.6 3549.0 3678.9 3682.0 3682.4 3687.8 643.5 654.4 663.4 671.1 673.2 590.8 635.9 667.8 677.2 682.7 9186.0 9290.0 9423.0 9632.4 9694.6 542.8 642.3 666.2 685.9 686.3 4611.0 4616.4 4646.7 4652.3 4704.7 80907.0 109559.5 111978.0 113537.0 130540.0 84445.0 115079.4 115662.0 115812.4 117677.0 110659.0 111800.0 114530.0 114638.4 119583.2 8665.0 8832.8 8879.0 8994.6 9165.7 53342.0 54641.9 59673.8 62516.0 64150.0 84512.6 85221.6 91328.5 94766.0 97850.0 11691.9 13220.6 13748.9 13839.4 14812.8 15230.2 15417.9 15871.0 16064.4 16456.2 13850.4 14149.6 14412.6 14422.4 14484.9 14360.6 14468.6 14470.0 14520.7 14562.5 13945.4 13957.9 14002.6 14028.5 14110.7 10956.9 11212.8 11337.6 11374.6 11669.6 10940.3 11339.7 11429.7 11600.4 11738.6 01:42:14 INFO - PROCESS | 1658 | -------- Summary: end -------- 01:42:14 INFO - PROCESS | 1658 | 01:42:15 INFO - TEST-INFO | 1658: exit 0 01:42:15 INFO - TEST-OK | dromaeo_css | took 1116816ms 01:42:15 INFO - TEST-START | kraken 01:42:15 INFO - operating with platform_type : mac_ 01:42:15 INFO - Initialising browser for kraken test... 01:42:15 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpywqUeJ/profile http://localhost:49274/getInfo.html) 01:42:17 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 01:42:17 INFO - PROCESS | 1667 | colorDepth:24 01:42:17 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 01:42:17 INFO - PROCESS | 1667 | __metrics 01:42:17 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:42:17 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:42:18 INFO - TEST-INFO | 1667: exit 0 01:42:18 INFO - Browser initialized. 01:42:18 INFO - Running cycle 1/1 for kraken test... 01:42:18 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpywqUeJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:42:30 INFO - PROCESS | 1668 | RSS: Main: 232415232 01:42:30 INFO - PROCESS | 1668 | 01:42:32 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 01:42:33 INFO - PROCESS | 1668 | RSS: Main: 292651008 01:42:33 INFO - PROCESS | 1668 | 01:42:36 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 01:42:37 INFO - PROCESS | 1668 | RSS: Main: 288264192 01:42:37 INFO - PROCESS | 1668 | 01:42:40 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 01:42:41 INFO - PROCESS | 1668 | RSS: Main: 375828480 01:42:41 INFO - PROCESS | 1668 | 01:42:43 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 01:42:44 INFO - PROCESS | 1668 | RSS: Main: 278515712 01:42:44 INFO - PROCESS | 1668 | 01:42:46 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:42:46 INFO - PROCESS | 1668 | RSS: Main: 283643904 01:42:46 INFO - PROCESS | 1668 | 01:42:49 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 01:42:50 INFO - PROCESS | 1668 | RSS: Main: 320884736 01:42:50 INFO - PROCESS | 1668 | 01:42:53 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 01:42:53 INFO - PROCESS | 1668 | RSS: Main: 320917504 01:42:53 INFO - PROCESS | 1668 | 01:42:56 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 01:42:57 INFO - PROCESS | 1668 | RSS: Main: 321835008 01:42:57 INFO - PROCESS | 1668 | 01:42:58 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:42:59 INFO - PROCESS | 1668 | RSS: Main: 297480192 01:42:59 INFO - PROCESS | 1668 | 01:43:01 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:43:02 INFO - PROCESS | 1668 | RSS: Main: 302878720 01:43:02 INFO - PROCESS | 1668 | 01:43:04 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:43:05 INFO - PROCESS | 1668 | RSS: Main: 299261952 01:43:05 INFO - PROCESS | 1668 | 01:43:07 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:43:08 INFO - PROCESS | 1668 | RSS: Main: 316272640 01:43:08 INFO - PROCESS | 1668 | 01:43:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:43:11 INFO - PROCESS | 1668 | RSS: Main: 312434688 01:43:11 INFO - PROCESS | 1668 | 01:43:13 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 01:43:14 INFO - PROCESS | 1668 | RSS: Main: 355819520 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | __start_tp_report 01:43:14 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 01:43:14 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 01:43:14 INFO - PROCESS | 1668 | |i|pagename|runs| 01:43:14 INFO - PROCESS | 1668 | |0;ai-astar;114;114;117;113;114;113;114;113;113;125 01:43:14 INFO - PROCESS | 1668 | |1;audio-beat-detection;146;124;120;192;135;136;148;136;196;134 01:43:14 INFO - PROCESS | 1668 | |2;audio-dft;185;182;182;185;184;185;182;183;182;182 01:43:14 INFO - PROCESS | 1668 | |3;audio-fft;72;78;75;165;80;80;80;60;141;57 01:43:14 INFO - PROCESS | 1668 | |4;audio-oscillator;81;84;140;69;70;75;118;68;70;118 01:43:14 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;89;86;90;88;90;88;89;88;91;129 01:43:14 INFO - PROCESS | 1668 | |6;imaging-darkroom;95;96;95;94;95;95;93;96;112;146 01:43:14 INFO - PROCESS | 1668 | |7;imaging-desaturate;132;86;86;86;86;85;84;84;83;84 01:43:14 INFO - PROCESS | 1668 | |8;json-parse-financial;54;54;55;55;55;53;57;54;53;54 01:43:14 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;64;65;75;106;107;94;70;70;70;70 01:43:14 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;86;85;93;105;105;102;83;86;85;86 01:43:14 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;141;139;134;138;138;137;135;134;179;140 01:43:14 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;169;166;166;176;168;167;169;169;167;170 01:43:14 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;62;80;83;89;83;68;65;66;67;67 01:43:14 INFO - PROCESS | 1668 | __end_tp_report 01:43:14 INFO - PROCESS | 1668 | __start_cc_report 01:43:14 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1998 01:43:14 INFO - PROCESS | 1668 | __end_cc_report 01:43:14 INFO - PROCESS | 1668 | __startTimestamp1473410593998__endTimestamp 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | ------- Summary: start ------- 01:43:14 INFO - PROCESS | 1668 | Number of tests: 14 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:115.00 Median:114.00 stddev:3.71 (3.3%) stddev-sans-first:3.92 01:43:14 INFO - PROCESS | 1668 | Values: 114.0 114.0 117.0 113.0 114.0 113.0 114.0 113.0 113.0 125.0 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:146.70 Median:141.00 stddev:26.33 (18.7%) stddev-sans-first:27.93 01:43:14 INFO - PROCESS | 1668 | Values: 146.0 124.0 120.0 192.0 135.0 136.0 148.0 136.0 196.0 134.0 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.50 stddev:1.40 (0.8%) stddev-sans-first:1.32 01:43:14 INFO - PROCESS | 1668 | Values: 185.0 182.0 182.0 185.0 184.0 185.0 182.0 183.0 182.0 182.0 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:88.80 Median:76.50 stddev:35.27 (46.1%) stddev-sans-first:36.88 01:43:14 INFO - PROCESS | 1668 | Values: 72.0 78.0 75.0 165.0 80.0 80.0 80.0 60.0 141.0 57.0 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:70.00 stddev:26.10 (37.3%) stddev-sans-first:27.51 01:43:14 INFO - PROCESS | 1668 | Values: 81.0 84.0 140.0 69.0 70.0 75.0 118.0 68.0 70.0 118.0 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:92.80 Median:89.00 stddev:12.80 (14.4%) stddev-sans-first:13.50 01:43:14 INFO - PROCESS | 1668 | Values: 89.0 86.0 90.0 88.0 90.0 88.0 89.0 88.0 91.0 129.0 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:101.70 Median:95.00 stddev:16.49 (17.4%) stddev-sans-first:17.31 01:43:14 INFO - PROCESS | 1668 | Values: 95.0 96.0 95.0 94.0 95.0 95.0 93.0 96.0 112.0 146.0 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:89.60 Median:85.50 stddev:14.94 (17.5%) stddev-sans-first:1.17 01:43:14 INFO - PROCESS | 1668 | Values: 132.0 86.0 86.0 86.0 86.0 85.0 84.0 84.0 83.0 84.0 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:1.17 (2.2%) stddev-sans-first:1.24 01:43:14 INFO - PROCESS | 1668 | Values: 54.0 54.0 55.0 55.0 55.0 53.0 57.0 54.0 53.0 54.0 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:79.10 Median:70.00 stddev:16.66 (23.8%) stddev-sans-first:16.75 01:43:14 INFO - PROCESS | 1668 | Values: 64.0 65.0 75.0 106.0 107.0 94.0 70.0 70.0 70.0 70.0 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:85.50 stddev:8.97 (10.5%) stddev-sans-first:9.28 01:43:14 INFO - PROCESS | 1668 | Values: 86.0 85.0 93.0 105.0 105.0 102.0 83.0 86.0 85.0 86.0 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:141.50 Median:138.50 stddev:13.39 (9.7%) stddev-sans-first:14.20 01:43:14 INFO - PROCESS | 1668 | Values: 141.0 139.0 134.0 138.0 138.0 137.0 135.0 134.0 179.0 140.0 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.70 Median:169.00 stddev:2.91 (1.7%) stddev-sans-first:3.08 01:43:14 INFO - PROCESS | 1668 | Values: 169.0 166.0 166.0 176.0 168.0 167.0 169.0 169.0 167.0 170.0 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:74.00 stddev:9.64 (13.0%) stddev-sans-first:9.36 01:43:14 INFO - PROCESS | 1668 | Values: 62.0 80.0 83.0 89.0 83.0 68.0 65.0 66.0 67.0 67.0 01:43:14 INFO - PROCESS | 1668 | -------- Summary: end -------- 01:43:14 INFO - PROCESS | 1668 | 01:43:14 INFO - TEST-INFO | 1668: exit 0 01:43:14 INFO - TEST-OK | kraken | took 59397ms 01:43:14 INFO - SUITE-END | took 1176s 01:43:15 INFO - Completed test suite (00:19:36) 01:43:15 INFO - javascript benchmark 01:43:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.749443791716, 3355.9161020974475, 3374.5444365010717, 3407.812570308632, 3457.2048428861362, 5678.43215678432, 5735.799247026355, 5792.913106303409, 5858.062709966406, 5880.206172166756, 5826.533877289818, 5828.854278643036, 5869.647821130735, 5872.324682661484, 5909.379515150912, 5153.1754919212935, 5198.7660555274915, 5259.263703081552, 5284.630075894689, 5378.973105134489, 5346.91979620306, 5390.8382748517415, 5403.432639572848, 5407.999520088796, 5439.945600544003, 5207.765650545734, 5242.711650859202, 5257.447967963385, 5322.840314790563, 5397.919031214535, 5243.6067294952645, 5254.605904557136, 5264.973675131638, 5304.920426193572, 5412.296401467784, 5213.218017297398, 5260.710660913648, 5276.314079169683, 5329.680219186823, 5335.1197052486295, 5679.062954612484, 5717.42825717425, 5900.203472531196, 5916.26046744157, 5919.733611987428, 6218.409251121137, 6243.2508099027955, 6255.093011513351, 6292.779752708633, 6328.303886572473, 5193.766280517386, 6222.315545290015, 6232.594881332699, 6247.5939063960695, 6248.781292654735, 5712.485876271073, 5769.94230057694, 5909.6158749681135, 5910.911336329958, 5923.614965027303, 6140.565599444434, 6180.969095154585, 6213.96893015532, 6268.592541484789, 6285.151504546919, 6214.720337584819, 6241.126242326078, 6242.313345531988, 6254.0, 6265.8746825063245, 6150.446459818638, 6236.0, 6285.465735412449, 6303.6532990685955, 6306.369363063657, 6171.691415429211, 6186.845328866814, 6245.687715614201, 6327.17746692927, 6329.936700633027, 6273.027680709497, 6294.276158241769, 6299.149614801943, 6301.590396624205, 6301.873962520724, 4084.469019027567, 4945.604351651859, 4952.653314267966, 4964.304997300381, 4992.350994370781, 4422.845200418034, 4440.2007638625355, 4488.932666010012, 4536.070105628338, 4571.200039993055, 5804.0, 5806.564507661942, 5817.505512031525, 5819.214406055213, 5825.650460972355, 6210.0, 6213.068039793977, 6247.968760156261, 6258.843528911722, 6274.372562743689, 5660.320761508563, 5697.173909783059, 5722.7710891563875, 5763.020286551213, 5768.884622307531, 5478.91781623276, 5486.588505862076, 5489.780408783604, 5493.642913210629, 5520.972395137998, 6336.873262534816, 6337.0, 6342.778002769881, 6342.9682851587295, 6360.586561873556, 6192.876142477216, 6216.129741836237, 6235.220597425322, 6257.874842503215, 6272.749090036347, 6317.936820631735, 6331.6517591531065, 6345.90481142774, 6349.396807303298, 6364.490840732823, 6240.625562466266, 6280.717367718555, 6288.245410550763, 6290.968545157245, 6292.0, 6166.876662466815, 6192.411720886508, 6228.5951413158, 6256.343083976117, 6267.2479302484, 6190.783322583688, 6231.501479881508, 6237.656928868956, 6244.188255526752, 6249.125122482947, 6188.412100850412, 6210.968945155245, 6215.192025036717, 6217.440430361289, 6221.09794079865, 5848.2689663792025, 5993.981023226149, 5999.820005399845, 6067.393260673897, 6093.421124993296, 6177.135201071944, 6214.098955651315, 6231.2522497300615, 6231.9688401557705, 6247.12540244357, 6072.512060476416, 6077.756889724362, 6081.391860813883, 6086.300075491198, 6088.238970128734], "value": 5761.559771077484, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.93216280925776, 469.631339398572, 470.6164475952099, 472.48263151848727, 534.3000669123446, 29625.0, 30699.846500767493, 31696.36607267859, 31697.36605267899, 31713.68286317139, 40628.0, 41615.0, 41639.0, 43724.78137609307, 43890.56109438905, 9561.569729362178, 9739.172170365491, 9743.317967742261, 9745.171660408838, 9747.561359738793, 8645.438046526988, 9635.421874687507, 9742.366746161511, 9745.415275083482, 9755.95122024389, 11784.234024788404, 11968.0, 11987.460564274585, 12206.084543659217, 12257.44841482131, 11816.881831181707, 11927.821082683766, 11938.701532461759, 11947.58183463579, 11962.40187990597, 8568.143185681382, 10291.125254353388, 11771.94114029433, 11832.763344733057, 11866.940665296705, 38598.80700596507, 38666.226675466336, 38817.0, 41174.794126029476, 41236.38145427821, 3239.141627468699, 3338.2488939988552, 3392.9830350848333, 3417.9658203418144, 3697.0572504011307, 30346.54480182799, 30697.38605227883, 30958.0, 30989.22526936822, 31256.843715781502, 3161.96838031619, 3181.9045428636946, 3201.375731732313, 3274.099622603779, 3308.553345298378, 507592.0, 507741.0, 510513.9999999963, 511243.0, 511800.0, 49881.75059124681, 56797.148042779394, 58104.70947645235, 58185.70907145522, 59463.70268148631, 62594.0, 62647.373526265066, 62874.68562657249, 62920.68539657273, 63002.0, 15764.68470630581, 15902.840971590136, 15912.283947222402, 15920.124393158487, 15929.575141344667, 10201.642942496977, 12286.631401057983, 12336.50653973831, 12367.319797411048, 12420.62738117858, 14418.783718244234, 14748.483803066842, 16659.083750393846, 16663.333866581288, 16814.07522586245, 6612.5040621952585, 6742.224644165984, 6841.692123854339, 6877.243503214642, 6892.931070689329, 834.5493433545938, 861.7931696392945, 940.4075432477496, 973.9415635061919, 1083.9024487796137, 27891.30271743182, 28372.290692732433, 29414.852925735664, 30059.849700751794, 30625.0, 1020.4846552490944, 1037.509976057468, 1056.1339701444895, 1061.0, 1064.7338165458636, 13643.795343069862, 14001.789973150411, 14441.78337324941, 15369.077855328716, 15392.999455035388, 655258.4741525905, 660854.0, 661583.0, 662274.0, 662387.0, 56427.153592696144, 56747.71626141926, 56783.14825277541, 56811.71594142003, 56899.43100568941, 1335.2789493673502, 1361.0744693608442, 1378.269517155909, 1381.544090450149, 1382.246675561801, 26870.694118115505, 28433.857830711542, 28517.572236416054, 29676.40647187001, 29764.80049243935, 50093.74953125211, 50273.99452011012, 50422.49577504178, 50447.74776126096, 50463.74768126136, 592677.0, 596707.0, 596835.0, 597889.0, 598030.0, 25477.3630659235, 33019.0, 40575.59424405836, 40609.79695101506, 41066.79466602648, 36946.076348090435, 46143.538564613926, 46370.0, 46390.07219855652, 46565.0, 38167.427488587135, 38552.036199095244, 38614.0, 39321.0, 39910.800445997585, 33071.33857322888, 37337.31982060868, 37928.741757609685, 38126.237475249785, 39230.0, 32026.039218823473, 36300.63699363078, 37201.0, 37636.05909852275, 37687.869363919126, 31629.525557117122, 34126.0, 34293.0, 34409.82795086008, 34425.72955569217, 22480.88759556192, 22544.436389089748, 24989.0, 25277.747222528276, 25651.0], "value": 19902.014401426226, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.5187211693992, 340.1462031926004, 355.79541763485986, 363.07235014537855, 394.57583098169476, 15274.007189532687, 18157.818421815777, 20365.287214947442, 20500.974951252432, 20548.89725551374, 20033.398998030083, 20088.196472141095, 20339.18643254268, 21043.158273669036, 21273.57452850942, 5407.3781515125675, 5853.707314634273, 6095.9695201524155, 6097.176881121036, 6104.938950610482, 6589.275179730226, 6619.073329733841, 6639.302873198317, 6794.286599907013, 6803.013563033357, 6307.621542707452, 6536.248331441874, 6548.869022619545, 6569.37590928861, 6605.70274337656, 6371.617702937792, 6590.439812615933, 6640.269570347258, 6642.568233064883, 6650.966745166292, 5743.994800909866, 5798.971005144947, 5802.390748971382, 5805.88388232233, 5831.475167234927, 23437.88281058601, 23821.0, 24499.632505512433, 25413.364665883502, 26115.608265876028, 2017.9293724719564, 2102.726645536086, 2273.8408311418207, 2395.73646898841, 2892.248015515981, 9713.125818676353, 10000.79998400028, 10185.643502477378, 10324.72855971685, 14087.0, 1837.5406148462885, 1840.5122642499748, 1863.4409677096817, 1866.5706887415834, 1881.595456976743, 105261.42107868388, 111177.44411277973, 111672.0, 113311.0, 115046.99999999916, 11240.583274596002, 11259.437028148559, 11382.317060976367, 11498.597549085742, 11615.012723918393, 14193.503227386991, 14194.503192388216, 14216.573502794727, 14223.146611203361, 14228.644283892778, 8136.83726325482, 8283.420160588821, 8295.419320647497, 8297.0, 8402.41183117176, 8196.0, 8197.877031844528, 8369.455985361054, 8375.078741338448, 8388.37087218461, 4315.762633055149, 4437.312216606431, 4450.5104438511735, 4467.6202522785925, 4581.244094724333, 1695.3981336625568, 1800.6488734696609, 1816.6366726654721, 1831.4871835885974, 1901.306023301485, 377.69595475641825, 379.18664464723014, 413.3964411958574, 793.7340990768042, 867.7917299848117, 11648.53405863773, 11657.300561966309, 11737.002354799937, 11995.940020300017, 12056.336901470328, 369.9622558722808, 373.32428304768456, 889.1908363389284, 912.1289168843738, 923.7505873414275, 8856.822863542822, 11057.944710276397, 11163.49704212495, 11189.888101118884, 11306.886931130748, 553880.0, 576781.0, 587052.0, 587196.0, 599338.0, 112622.0, 115201.0, 130551.89489237659, 132005.0, 147583.0, 964.6912987843823, 970.112347202302, 1006.2654262388497, 1007.3502590829021, 1011.9241056920761, 12959.870401295866, 14028.859711403164, 18492.907535462236, 18640.161192746087, 19486.61026779428, 42347.57652423436, 43385.0, 54748.72625636846, 54859.72570137124, 56030.159547606, 78241.43517129739, 78298.04254893673, 78464.60767696354, 79582.0, 80062.0, 11247.550097996316, 11293.548258069914, 11294.830577541179, 11675.299482031105, 11869.10981676395, 11000.22998390137, 12838.42227099764, 13064.54274100402, 13259.93920486378, 13395.330233488481, 11143.498542565441, 11198.552057917917, 11655.883441165819, 11726.70683232926, 11766.411679416165, 11194.496247668712, 11245.100391968459, 11297.26567773092, 11708.297502149899, 11787.41062946832, 11375.715607109889, 11425.028872545594, 11446.255993360404, 11836.76326473483, 11838.644840654793, 11666.88333116658, 11725.648230553097, 11956.940215298868, 11972.0, 12100.273983561014, 9482.38364506305, 10154.62912506802, 11189.0, 11309.773804524028, 11817.350045747222], "value": 9404.210967466455, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1494.8430414806446, 1505.3000354835003, 1672.6069373697187, 1824.2885274742844, 1828.8079751626078, 8352.331813454924, 8899.33255005874, 8909.198172164504, 8911.331650126234, 8913.955430222848, 7711.2288771123, 7954.2841144297, 8390.790230244247, 8582.970043594762, 8661.090585488511, 2370.3007612754236, 2399.7120345558556, 2405.6391541268777, 2406.9638955415685, 2419.4314336130997, 2382.2376839411395, 2398.7121545414575, 2413.779323022823, 2420.4432980414517, 2421.6367544868235, 2384.0582969726947, 2398.1486572266854, 2409.9879500602474, 2413.1915808204185, 2417.62526808345, 2368.6920700309024, 2377.8335516513766, 2387.39121524012, 2389.3668177932864, 2403.435192729707, 2342.9648555271683, 2358.657994590792, 2388.8924998375114, 2390.2829151254728, 2392.9401764956015, 8773.736787896374, 8871.733847984506, 8880.644774209024, 8888.3333749969, 8904.109589041109, 5157.638965272433, 5322.988632159877, 5328.227407025959, 5334.306540149794, 5350.384705758848, 3641.635836416364, 3644.671979521856, 3697.1126929536986, 3697.149655579205, 3714.7399682022, 1086.8261078227445, 1095.1512577752226, 1102.1348241630362, 1111.8721347045112, 1122.2256642916443, 11679.357635330054, 11798.528058877635, 11812.704682382922, 11823.586174483851, 11823.704407389798, 4455.821767129279, 4566.06395688883, 4736.8105275788585, 4754.120487709784, 4759.809607615726, 9936.850947235796, 9938.701838944844, 9940.055694708992, 9948.104670579538, 9977.900220997843, 5296.1261391870685, 5318.175682769177, 5320.255164277004, 5322.2548843161985, 5323.121684922022, 4202.957970420257, 4255.489341279066, 4412.227860124465, 4821.686590371614, 4881.608741508653, 3864.9227015459533, 3867.16855875988, 4003.7597744134864, 4007.2968988179905, 4029.2545879012478, 1103.7020004598714, 1268.7081971146504, 1296.714722760991, 1300.206873806977, 1302.3813688498078, 773.8684423647993, 826.2233500509622, 836.0737419033383, 876.4434584039182, 953.3564843730454, 5637.8590535236945, 5665.716714164275, 5945.851353716192, 6030.306514750772, 6166.4758495527485, 874.3136637739472, 916.7662246127328, 944.2493217891655, 962.0619895601733, 995.2585323933715, 3218.8733943120187, 3741.644543768338, 3966.186931678999, 4039.7374170678813, 4080.3471444568727, 10411.271211015157, 10626.574937002588, 10726.946365268124, 10745.623903163352, 10755.032047115797, 10010.899891000996, 10445.791084178274, 10448.007439293408, 10684.786304274026, 10742.78514429707, 7961.681532738626, 8199.918000820035, 8208.671653133928, 8216.34269258458, 8217.424780265324, 5880.353161152184, 5913.70431478433, 5929.881402372014, 6055.396179642796, 6207.193064901534, 9303.906960930575, 9967.900320996698, 10098.798024039625, 10151.340162889388, 10155.441450720282, 10082.596696132072, 10122.44326562046, 10200.4389758564, 10224.846627300745, 10309.48452577353, 6768.56004359715, 7284.198738138906, 7415.0, 7578.545287282781, 7585.6965721370525, 8118.0, 8213.835723285381, 8343.874841877256, 8356.707515236938, 8357.582120894052, 7301.780946571611, 7411.4070874331, 7419.666115024945, 7474.738384156529, 7558.773236802905, 7299.0, 7303.488755787256, 7355.2277010913085, 7467.887981680171, 7492.812679683051, 6642.368974947372, 6762.323767623198, 6842.452603791784, 6845.657717114024, 6846.178458585002, 5076.289319495199, 5091.770870310771, 5132.43543210254, 5175.9741201293755, 5268.894622107459, 4788.808447662054, 4952.925706114339, 4990.820561941298, 5059.367579052619, 5102.4897510248675], "value": 4592.183865700032, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [251.26755507695066, 295.49912897638484, 304.9344390955945, 308.5941986288031, 311.2312587907867, 4379.853871602915, 5035.974820125894, 5451.427600101987, 5463.644863083901, 5487.231787549741, 5105.3873535175735, 5242.475752424755, 5315.840524784263, 5354.303940487741, 5468.9726551367285, 3497.7551571390013, 4112.2803509385885, 4141.420201171838, 4142.46148000761, 4186.351115577075, 3579.355715971124, 3630.509881166048, 4114.683301343572, 4152.335626299792, 4201.411802347674, 3909.6090390960962, 4087.57080506547, 4139.56534563871, 4140.3168477201225, 4160.791960401968, 3638.490611314418, 4109.363048727452, 4120.361343991671, 4125.174965006996, 4128.60778226068, 3387.8644854205804, 3512.7541072124964, 3513.7540372173958, 3526.2947410518, 3528.5060091587197, 5066.265391518209, 5401.70290634015, 5419.295491586108, 5431.701256430894, 5437.4562543745305, 4179.874603761861, 4182.9790851045855, 4278.165757677254, 4490.618297444721, 4742.430908291027, 4783.9475315430545, 5819.67991760453, 5860.824175274706, 5864.794732184353, 5880.26496687914, 3421.1105112670634, 3797.8413637067365, 3917.2948869203533, 3932.1545867638597, 3933.272344616255, 5623.734659701617, 5803.245578074865, 5855.8536036599, 5932.644041357532, 5945.346011938726, 3388.288459423549, 3738.0281126906657, 3899.941500877489, 3913.882583522499, 3924.2543916655745, 3341.3818443588016, 3655.6527129922615, 3977.900552486211, 3986.980065099714, 4000.979995100006, 3895.240428116547, 3904.4338570907057, 4489.608221451361, 4625.722456652612, 4683.625309975194, 5716.456936591017, 5720.456556627114, 5729.541636669056, 5745.396733343023, 5759.65442073477, 1278.622806272152, 1331.6271443995697, 1355.213975893979, 1356.1456282542133, 1362.9863701363058, 766.4290103872539, 773.8585586260222, 825.6490991328661, 853.1979938857454, 866.5139286124202, 3916.7845768483007, 3926.3521518949055, 4087.7547347159266, 4476.4180656514445, 4511.954880451219, 4480.922360651791, 4606.654500912445, 4842.467328593852, 5047.693760586822, 5092.719900405513, 3631.202554735423, 4209.979209979228, 4328.761918094535, 4469.687121901436, 4556.70381425213, 2102.36928921323, 2492.4890397468475, 2572.5755250383477, 2612.987999240574, 2642.0356569851865, 5133.153029750049, 5150.4334523202415, 5163.690178589297, 5165.380154381498, 5236.842894713165, 4378.840101903204, 4422.071365013319, 4769.642276829251, 5066.4680208577565, 5101.821436249714, 4578.717958971427, 4752.738599377068, 4887.731174785421, 5122.667026643257, 5138.655797970069, 4381.671374646978, 4489.91020179588, 4514.68397212192, 4806.0, 4970.27930950003, 4592.920663644108, 4612.53874612536, 4850.005748821553, 4864.73243971585, 4882.6338024649, 4091.6726661867574, 4414.84548040817, 4979.576735977483, 4983.8504844854715, 5167.767450464663, 2874.3820078682347, 3398.4732366483236, 3404.3531728972407, 3418.897433076912, 3426.074959760801, 4524.411826462539, 4855.514448555116, 4957.339291337298, 4986.301917731448, 5049.6465247432325, 4170.020045289415, 4201.537830838544, 4615.746133962664, 4808.639352048541, 4812.205986012269, 4705.976470117627, 4739.099571081483, 4779.617630589614, 4787.234042553175, 4823.324734537097, 4393.802278897521, 4607.562281583244, 4674.719516829001, 4691.0, 4723.480417154179, 4919.532644398776, 5377.704226267437, 5697.4302569742695, 5822.912656310074, 5868.853278668067, 5277.577793776411, 5634.549236061187, 5722.971385143048, 5739.827805165852, 5787.0], "value": 3841.8933410784466, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.5765594707492, 755.9584222867744, 779.703712589216, 806.8749343851704, 812.4434762187898, 14869.40522379105, 16304.0, 16361.100139492322, 16362.100084495349, 16363.754543681824, 14388.208648524327, 14779.92610036951, 14789.260536973135, 14802.185879776607, 14805.703885922276, 818.5866137600533, 821.5645707774889, 835.7701632051204, 840.1430540848331, 841.3731769831484, 659.1529884098934, 826.6693322670919, 828.2876726015621, 829.0383155539575, 829.2288172000037, 726.832828449457, 863.3524856357732, 887.1483375959087, 890.5725251879102, 894.6868595991416, 884.0849720539243, 884.2395539835736, 884.681514654727, 885.5572213893054, 887.1660638999319, 517.627308337996, 534.6070638081007, 653.9084528166061, 655.131950166032, 656.5010591950153, 12342.506299748, 12452.31512266825, 14386.424543018267, 14925.104493730303, 14968.47610333633, 13686.178829270177, 14262.429502819874, 14268.286585670676, 14403.78394324086, 14511.0, 11538.134639901957, 11827.645170644895, 11940.880591194064, 12115.757684846341, 12384.938075309567, 3762.8682996095004, 3780.6030366811365, 3789.5073640426845, 3793.506844110276, 3934.390169523728, 119746.99999999913, 119821.0, 120498.0, 120533.0, 122041.0, 10030.0, 10773.761017482933, 10816.296940698987, 10901.34591924487, 11315.0, 21744.89127554352, 21828.45428864291, 22002.0, 22023.22918697838, 22047.66928496074, 7821.139674635786, 7921.920780792234, 7957.880631790527, 8005.599720013976, 8061.565383391706, 3759.5676497202626, 4463.263561512379, 4475.037866858641, 4518.390017347616, 4635.629149668086, 3548.964510354915, 3678.8712395066045, 3682.0, 3682.447632855093, 3687.778733276012, 643.5366536094004, 654.4032560552332, 663.4393937123124, 671.0839703804272, 673.2123415603755, 590.7607418995314, 635.8823617630753, 667.7562689618254, 677.2110491277705, 682.7303215230056, 9186.0, 9289.953550232342, 9423.0, 9632.395519258846, 9694.558431756766, 542.8235823357425, 642.3448082955359, 666.1573110015834, 685.8511992412689, 686.27255109584, 4610.95389046112, 4616.3998680172035, 4646.651501137429, 4652.270798489294, 4704.670673052922, 80907.0, 109559.45220273848, 111978.0, 113537.0, 130540.0, 84445.0, 115079.42460287645, 115662.0, 115812.42093789477, 117677.0, 110659.0, 111800.0, 114530.0, 114638.4268078671, 119583.20625190629, 8665.0, 8832.823343532964, 8878.978917424578, 8994.55027248648, 9165.725028249164, 53342.0, 54641.907161857336, 59673.833946390914, 62516.0, 64150.0, 84512.57743711243, 85221.57389213014, 91328.54335728279, 94766.0, 97850.0, 11691.941540292244, 13220.603381898558, 13748.93125534406, 13839.446422143405, 14812.777808332668, 15230.238488075773, 15417.922910385376, 15871.047737135808, 16064.357425702841, 16456.177191140636, 13850.376733046836, 14149.646258843199, 14412.639684007983, 14422.423103076178, 14484.85515144835, 14360.640983975483, 14468.565943021726, 14470.0, 14520.709585808436, 14562.490312839, 13945.442182312594, 13957.93021034929, 14002.649933751738, 14028.509002184874, 14110.717785644434, 10956.890431095588, 11212.831807523056, 11337.603183888525, 11374.601888933848, 11669.649910502698, 10940.343579385262, 11339.71650708706, 11429.714257143638, 11600.419979001184, 11738.647840564796], "value": 7765.863485041565, "unit": "score"}], "name": "dromaeo_css", "value": 7270.99289460777, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 117.0, 113.0, 114.0, 113.0, 114.0, 113.0, 113.0, 125.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 124.0, 120.0, 192.0, 135.0, 136.0, 148.0, 136.0, 196.0, 134.0], "value": 146.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 182.0, 185.0, 184.0, 185.0, 182.0, 183.0, 182.0, 182.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 78.0, 75.0, 165.0, 80.0, 80.0, 80.0, 60.0, 141.0, 57.0], "value": 88.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 140.0, 69.0, 70.0, 75.0, 118.0, 68.0, 70.0, 118.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 88.0, 90.0, 88.0, 89.0, 88.0, 91.0, 129.0], "value": 92.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 93.0, 96.0, 112.0, 146.0], "value": 101.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 86.0, 86.0, 86.0, 86.0, 85.0, 84.0, 84.0, 83.0, 84.0], "value": 89.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 55.0, 55.0, 53.0, 57.0, 54.0, 53.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 75.0, 106.0, 107.0, 94.0, 70.0, 70.0, 70.0, 70.0], "value": 79.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 93.0, 105.0, 105.0, 102.0, 83.0, 86.0, 85.0, 86.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 134.0, 138.0, 138.0, 137.0, 135.0, 134.0, 179.0, 140.0], "value": 141.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 166.0, 176.0, 168.0, 167.0, 169.0, 169.0, 167.0, 170.0], "value": 168.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 80.0, 83.0, 89.0, 83.0, 68.0, 65.0, 66.0, 67.0, 67.0], "value": 73.0, "unit": "score"}], "name": "kraken", "value": 1515.3999999999999, "alertThreshold": 2.0}]} 01:43:15 INFO - Return code: 0 01:43:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:43:15 INFO - # TBPL SUCCESS # 01:43:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:43:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:43:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:43:15 INFO - 2016-09-09 01:43:15.349 screenresolution[1672:10919] starting screenresolution argv=screenresolution get 01:43:15 INFO - 2016-09-09 01:43:15.351 screenresolution[1672:10919] Display 0: 1600x1200x32@60 01:43:15 INFO - 2016-09-09 01:43:15.357 screenresolution[1673:10922] starting screenresolution argv=screenresolution list 01:43:15 INFO - Available Modes on Display 0 01:43:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:43:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:43:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:43:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:43:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:43:15 INFO - Intel Iris: 01:43:15 INFO - Chipset Model: Intel Iris 01:43:15 INFO - Type: GPU 01:43:15 INFO - Bus: Built-In 01:43:15 INFO - VRAM (Dynamic, Max): 1536 MB 01:43:15 INFO - Vendor: Intel (0x8086) 01:43:15 INFO - Device ID: 0x0a2e 01:43:15 INFO - Revision ID: 0x0009 01:43:15 INFO - Displays: 01:43:15 INFO - Display: 01:43:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:43:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:43:15 INFO - Display Serial Number: 1600x1200 60 01:43:15 INFO - Main Display: Yes 01:43:15 INFO - Mirror: Off 01:43:15 INFO - Online: Yes 01:43:15 INFO - Rotation: Supported 01:43:15 INFO - Return code: 0 01:43:15 INFO - Running post-action listener: _resource_record_post_action 01:43:15 INFO - [mozharness: 2016-09-09 08:43:15.591869Z] Finished run-tests step (success) 01:43:15 INFO - Running post-run listener: _resource_record_post_run 01:43:15 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32062464; Write bytes: 705144832; Read time: 196; Write time: 1952 01:43:15 INFO - TinderboxPrint: CPU usage
53.6% 01:43:15 INFO - TinderboxPrint: I/O read bytes / time
32,062,464 / 196 01:43:15 INFO - TinderboxPrint: I/O write bytes / time
705,144,832 / 1,952 01:43:15 INFO - TinderboxPrint: CPU idle
2,249.3 (46.4%) 01:43:15 INFO - TinderboxPrint: CPU system
966.3 (19.9%) 01:43:15 INFO - TinderboxPrint: CPU user
1,630.1 (33.6%) 01:43:15 INFO - TinderboxPrint: Swap in / out
434,610,176 / 0 01:43:15 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390099456; Write bytes: 384559104; Read time: 29210; Write time: 787 01:43:15 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31136768; Write bytes: 296448000; Read time: 184; Write time: 1107 01:43:16 INFO - Running post-run listener: _upload_blobber_files 01:43:16 INFO - Blob upload gear active. 01:43:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:43:16 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 01:43:16 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'] 01:43:16 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 01:43:16 INFO - (blobuploader) - INFO - Open directory for files ... 01:43:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:43:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:43:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:43:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:43:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:43:17 INFO - (blobuploader) - INFO - Done attempting. 01:43:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:43:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:43:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:43:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:43:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:43:18 INFO - (blobuploader) - INFO - Done attempting. 01:43:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:43:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:43:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:43:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:43:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:43:20 INFO - (blobuploader) - INFO - Done attempting. 01:43:20 INFO - (blobuploader) - INFO - Iteration through files over. 01:43:20 INFO - Return code: 0 01:43:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:43:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:43:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d0158221fe6f2095bbb8233e991b3d712a048b3ed2bdda354aa5f93588ecee144ffb32d34d9c8b00418c475c2481b392779070b4d7d22b6d5e81f4b3bc8a8441", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c9315de54afea1e8164cbc3fbace7358cf405290b55491f1b0b5887067f56100d12affb1c181d65fe9c1ae7058ec0ac09f407bdc71e92723ffb6a1c55911f817", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/39a9a40d7d3c2849092d69103d365746a8975107a797ff4e19a5a7cc0febbaa1dca429675eade37dc4925a61fb1ad78a49a060a9feb4f38044a714cd29a9b03a"} 01:43:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:43:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:43:20 INFO - Contents: 01:43:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d0158221fe6f2095bbb8233e991b3d712a048b3ed2bdda354aa5f93588ecee144ffb32d34d9c8b00418c475c2481b392779070b4d7d22b6d5e81f4b3bc8a8441", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c9315de54afea1e8164cbc3fbace7358cf405290b55491f1b0b5887067f56100d12affb1c181d65fe9c1ae7058ec0ac09f407bdc71e92723ffb6a1c55911f817", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/39a9a40d7d3c2849092d69103d365746a8975107a797ff4e19a5a7cc0febbaa1dca429675eade37dc4925a61fb1ad78a49a060a9feb4f38044a714cd29a9b03a"} 01:43:20 INFO - Running post-run listener: copy_logs_to_upload_dir 01:43:20 INFO - Copying logs to upload dir... 01:43:20 INFO - mkdir: /builds/slave/test/build/upload/logs 01:43:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.860984 ========= master_lag: 0.17 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-09 01:43:20.939994) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 01:43:20.948729) ========= 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.anHJFGrKsu/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.Olgkr8aD3X/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d0158221fe6f2095bbb8233e991b3d712a048b3ed2bdda354aa5f93588ecee144ffb32d34d9c8b00418c475c2481b392779070b4d7d22b6d5e81f4b3bc8a8441", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c9315de54afea1e8164cbc3fbace7358cf405290b55491f1b0b5887067f56100d12affb1c181d65fe9c1ae7058ec0ac09f407bdc71e92723ffb6a1c55911f817", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/39a9a40d7d3c2849092d69103d365746a8975107a797ff4e19a5a7cc0febbaa1dca429675eade37dc4925a61fb1ad78a49a060a9feb4f38044a714cd29a9b03a"} build_url:https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011069 build_url: 'https://queue.taskcluster.net/v1/task/UYan-I8-TgKtxU-TsQo_Aw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d0158221fe6f2095bbb8233e991b3d712a048b3ed2bdda354aa5f93588ecee144ffb32d34d9c8b00418c475c2481b392779070b4d7d22b6d5e81f4b3bc8a8441", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c9315de54afea1e8164cbc3fbace7358cf405290b55491f1b0b5887067f56100d12affb1c181d65fe9c1ae7058ec0ac09f407bdc71e92723ffb6a1c55911f817", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/39a9a40d7d3c2849092d69103d365746a8975107a797ff4e19a5a7cc0febbaa1dca429675eade37dc4925a61fb1ad78a49a060a9feb4f38044a714cd29a9b03a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 01:43:20.982868) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 01:43:20.987257) ========= 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.anHJFGrKsu/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.Olgkr8aD3X/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.005074 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 01:43:21.039369) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 01:43:21.039747) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 01:43:21.040102) ========= ========= Total master_lag: 0.31 =========