builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0195 starttime: 1473467620.02 results: success (0) buildid: 20160909154019 builduid: a776b1789e604a2d8e36ced2fcdcd501 revision: 57647be72e76cb9c8b8ea6069c6d53362c9892d9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 17:33:40.022020) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 17:33:40.022516) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 17:33:40.022850) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 17:33:40.049552) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 17:33:40.050289) ========= 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.MyPNeIzKBC/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.Rbkx34R72M/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 17:33:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.1M=0.001s 2016-09-09 17:33:40 (15.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130587 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 17:33:40.209610) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 17:33:40.210386) ========= 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.MyPNeIzKBC/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.Rbkx34R72M/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.029841 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 17:33:40.264080) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-09-09 17:33:40.264442) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 57647be72e76cb9c8b8ea6069c6d53362c9892d9 --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 57647be72e76cb9c8b8ea6069c6d53362c9892d9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MyPNeIzKBC/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.Rbkx34R72M/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 17:33:40,343 truncating revision to first 12 chars 2016-09-09 17:33:40,344 Setting DEBUG logging. 2016-09-09 17:33:40,344 attempt 1/10 2016-09-09 17:33:40,344 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/57647be72e76?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-09 17:33:42,400 unpacking tar archive at: autoland-57647be72e76/testing/mozharness/ program finished with exit code 0 elapsedTime=2.319411 ========= master_lag: 1.29 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-09-09 17:33:43.873832) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 17:33:43.874189) ========= script_repo_revision: 57647be72e76cb9c8b8ea6069c6d53362c9892d9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 17:33:43.874636) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 17:33:43.874911) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 17:33:43.908062) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-09 17:33:43.908443) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MyPNeIzKBC/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.Rbkx34R72M/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 17:33:44 INFO - MultiFileLogger online at 20160909 17:33:44 in /builds/slave/test 17:33:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 17:33:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:33:44 INFO - {'append_to_log': False, 17:33:44 INFO - 'base_work_dir': '/builds/slave/test', 17:33:44 INFO - 'blob_upload_branch': 'Autoland', 17:33:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:33:44 INFO - 'branch': 'Autoland', 17:33:44 INFO - 'buildbot_json_path': 'buildprops.json', 17:33:44 INFO - 'config_files': ('talos/mac_config.py',), 17:33:44 INFO - 'default_actions': ('clobber', 17:33:44 INFO - 'read-buildbot-config', 17:33:44 INFO - 'download-and-extract', 17:33:44 INFO - 'populate-webroot', 17:33:44 INFO - 'create-virtualenv', 17:33:44 INFO - 'install', 17:33:44 INFO - 'run-tests'), 17:33:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:33:44 INFO - 'download_minidump_stackwalk': True, 17:33:44 INFO - 'download_symbols': 'ondemand', 17:33:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:33:44 INFO - 'tooltool.py': '/tools/tooltool.py', 17:33:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:33:44 INFO - '/tools/misc-python/virtualenv.py')}, 17:33:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:33:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:33:44 INFO - 'installer_path': 'installer.exe', 17:33:44 INFO - 'log_level': 'info', 17:33:44 INFO - 'log_name': 'talos', 17:33:44 INFO - 'log_to_console': True, 17:33:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:33:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:33:44 INFO - 'opt_config_files': (), 17:33:44 INFO - 'pip_index': False, 17:33:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:33:44 INFO - 'cmd': ('bash', 17:33:44 INFO - '-c', 17:33:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:33:44 INFO - 'enabled': True, 17:33:44 INFO - 'halt_on_failure': False, 17:33:44 INFO - 'name': 'check_screen_resolution'},), 17:33:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:33:44 INFO - 'cmd': ('bash', 17:33:44 INFO - '-c', 17:33:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:33:44 INFO - 'enabled': True, 17:33:44 INFO - 'halt_on_failure': False, 17:33:44 INFO - 'name': 'check_screen_resolution'},), 17:33:44 INFO - 'run_cmd_checks_enabled': True, 17:33:44 INFO - 'sps_profile': False, 17:33:44 INFO - 'sps_profile_interval': 0, 17:33:44 INFO - 'suite': 'dromaeojs-e10s', 17:33:44 INFO - 'system_bits': '32', 17:33:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:33:44 INFO - 'title': 't-yosemite-r7-0195', 17:33:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:33:44 INFO - 'use_talos_json': True, 17:33:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:33:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:33:44 INFO - 'work_dir': 'build'} 17:33:44 INFO - [mozharness: 2016-09-10 00:33:44.106474Z] Running clobber step. 17:33:44 INFO - Running pre-action listener: _resource_record_pre_action 17:33:44 INFO - Running main action method: clobber 17:33:44 INFO - rmtree: /builds/slave/test/build 17:33:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:33:46 INFO - Running post-action listener: _resource_record_post_action 17:33:46 INFO - [mozharness: 2016-09-10 00:33:46.362289Z] Finished clobber step (success) 17:33:46 INFO - [mozharness: 2016-09-10 00:33:46.362410Z] Running read-buildbot-config step. 17:33:46 INFO - Running pre-action listener: _resource_record_pre_action 17:33:46 INFO - Running main action method: read_buildbot_config 17:33:46 INFO - Using buildbot properties: 17:33:46 INFO - { 17:33:46 INFO - "project": "", 17:33:46 INFO - "product": "firefox", 17:33:46 INFO - "script_repo_revision": "production", 17:33:46 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 17:33:46 INFO - "repository": "", 17:33:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 17:33:46 INFO - "buildid": "20160909154019", 17:33:46 INFO - "pgo_build": "False", 17:33:46 INFO - "basedir": "/builds/slave/test", 17:33:46 INFO - "buildnumber": 111, 17:33:46 INFO - "slavename": "t-yosemite-r7-0195", 17:33:46 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 17:33:46 INFO - "platform": "yosemite_r7", 17:33:46 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 17:33:46 INFO - "branch": "autoland", 17:33:46 INFO - "repo_path": "integration/autoland", 17:33:46 INFO - "revision": "57647be72e76cb9c8b8ea6069c6d53362c9892d9", 17:33:46 INFO - "stage_platform": "macosx64", 17:33:46 INFO - "builduid": "a776b1789e604a2d8e36ced2fcdcd501", 17:33:46 INFO - "slavebuilddir": "test" 17:33:46 INFO - } 17:33:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 17:33:46 INFO - Running post-action listener: _resource_record_post_action 17:33:46 INFO - [mozharness: 2016-09-10 00:33:46.365362Z] Finished read-buildbot-config step (success) 17:33:46 INFO - [mozharness: 2016-09-10 00:33:46.365459Z] Running download-and-extract step. 17:33:46 INFO - Running pre-action listener: _resource_record_pre_action 17:33:46 INFO - Running main action method: download_and_extract 17:33:46 INFO - mkdir: /builds/slave/test/build/tests 17:33:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:33:46 INFO - trying https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 17:33:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/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 17:33:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/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 17:33:47 INFO - Downloaded 1199 bytes. 17:33:47 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 17:33:47 INFO - Using the following test package requirements: 17:33:47 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 17:33:47 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:47 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 17:33:47 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:47 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 17:33:47 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:47 INFO - u'jsshell-mac.zip'], 17:33:47 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:47 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 17:33:47 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 17:33:47 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:47 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 17:33:47 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:47 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 17:33:47 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:47 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 17:33:47 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:47 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 17:33:47 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:33:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:33:48 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 17:33:55 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 17:33:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:33:55 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 17:34:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 17:34:03 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 17:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:04 INFO - trying https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:34:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 17:34:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/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 17:34:07 INFO - Downloaded 111604940 bytes. 17:34:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:34:07 INFO - mkdir: /builds/slave/test/properties 17:34:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:34:07 INFO - Writing to file /builds/slave/test/properties/build_url 17:34:07 INFO - Contents: 17:34:07 INFO - build_url:https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:34:07 INFO - Running post-action listener: _resource_record_post_action 17:34:07 INFO - Running post-action listener: set_extra_try_arguments 17:34:07 INFO - [mozharness: 2016-09-10 00:34:07.678675Z] Finished download-and-extract step (success) 17:34:07 INFO - [mozharness: 2016-09-10 00:34:07.678812Z] Running populate-webroot step. 17:34:07 INFO - Running pre-action listener: _resource_record_pre_action 17:34:07 INFO - Running main action method: populate_webroot 17:34:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:34:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:34:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:34:07 INFO - '--tppagecycles', 17:34:07 INFO - '7'], 17:34:07 INFO - 'tests': ['tsvgm']}}, 17:34:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:34:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:34:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:34:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:34:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:34:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:34:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['damp', 'tps']}, 17:34:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'tests': ['damp', 'tps']}, 17:34:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['dromaeo_dom']}, 17:34:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:34:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['basic_compositor_video']}, 17:34:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:34:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['a11yr', 17:34:07 INFO - 'ts_paint', 17:34:07 INFO - 'tpaint', 17:34:07 INFO - 'sessionrestore', 17:34:07 INFO - 'sessionrestore_no_auto_restore', 17:34:07 INFO - 'tabpaint']}, 17:34:07 INFO - 'other-e10s': {'tests': ['a11yr', 17:34:07 INFO - 'ts_paint', 17:34:07 INFO - 'tpaint', 17:34:07 INFO - 'sessionrestore', 17:34:07 INFO - 'sessionrestore_no_auto_restore', 17:34:07 INFO - 'tabpaint']}, 17:34:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:34:07 INFO - 'ts_paint', 17:34:07 INFO - 'tpaint', 17:34:07 INFO - 'sessionrestore', 17:34:07 INFO - 'sessionrestore_no_auto_restore', 17:34:07 INFO - 'tabpaint']}, 17:34:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:34:07 INFO - 'ts_paint', 17:34:07 INFO - 'tpaint', 17:34:07 INFO - 'sessionrestore', 17:34:07 INFO - 'sessionrestore_no_auto_restore', 17:34:07 INFO - 'tabpaint']}, 17:34:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['a11yr', 17:34:07 INFO - 'ts_paint', 17:34:07 INFO - 'tpaint', 17:34:07 INFO - 'sessionrestore', 17:34:07 INFO - 'sessionrestore_no_auto_restore', 17:34:07 INFO - 'tabpaint']}, 17:34:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['a11yr', 17:34:07 INFO - 'ts_paint', 17:34:07 INFO - 'tpaint', 17:34:07 INFO - 'sessionrestore', 17:34:07 INFO - 'sessionrestore_no_auto_restore', 17:34:07 INFO - 'tabpaint']}, 17:34:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['tsvgx', 17:34:07 INFO - 'tsvgr_opacity', 17:34:07 INFO - 'tart', 17:34:07 INFO - 'tscrollx', 17:34:07 INFO - 'cart']}, 17:34:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:34:07 INFO - 'tsvgr_opacity', 17:34:07 INFO - 'tart', 17:34:07 INFO - 'tscrollx', 17:34:07 INFO - 'cart']}, 17:34:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['tp5o']}, 17:34:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'tests': ['tp5o']}, 17:34:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'talos_options': ['--disable-e10s', 17:34:07 INFO - '--xperf_path', 17:34:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:34:07 INFO - 'tests': ['tp5n']}, 17:34:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'talos_options': ['--xperf_path', 17:34:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:34:07 INFO - 'tests': ['tp5n']}}, 17:34:07 INFO - 'talos.zip': {'path': '', 17:34:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:34:07 INFO - Running post-action listener: _resource_record_post_action 17:34:07 INFO - [mozharness: 2016-09-10 00:34:07.697232Z] Finished populate-webroot step (success) 17:34:07 INFO - [mozharness: 2016-09-10 00:34:07.697364Z] Running create-virtualenv step. 17:34:07 INFO - Running pre-action listener: _resource_record_pre_action 17:34:07 INFO - Running main action method: create_virtualenv 17:34:07 INFO - Creating virtualenv /builds/slave/test/build/venv 17:34:07 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 17:34:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:34:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:34:07 INFO - Using real prefix '/tools/python27' 17:34:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:34:08 INFO - Installing distribute.............................................................................................................................................................................................done. 17:34:11 INFO - Installing pip.................done. 17:34:11 INFO - Return code: 0 17:34:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017b2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101728570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78859c6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c6d30>, '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 0x1013ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015beb20>, '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.MyPNeIzKBC/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.Rbkx34R72M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 17:34:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:34:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPNeIzKBC/Render', 17:34:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:11 INFO - 'HOME': '/Users/cltbld', 17:34:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:11 INFO - 'LOGNAME': 'cltbld', 17:34:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:11 INFO - 'MOZ_NO_REMOTE': '1', 17:34:11 INFO - 'NO_EM_RESTART': '1', 17:34:11 INFO - 'PAGER': '/bin/cat', 17:34:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:11 INFO - 'PWD': '/builds/slave/test', 17:34:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:11 INFO - 'SHELL': '/bin/bash', 17:34:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rbkx34R72M/Listeners', 17:34:11 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 17:34:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:11 INFO - 'USER': 'cltbld', 17:34:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:11 INFO - 'XPC_FLAGS': '0x0', 17:34:11 INFO - 'XPC_SERVICE_NAME': '0', 17:34:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:11 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 17:34:11 INFO - Cleaning up... 17:34:11 INFO - Return code: 0 17:34:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1017b2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101728570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78859c6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c6d30>, '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 0x1013ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015beb20>, '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.MyPNeIzKBC/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.Rbkx34R72M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 17:34:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:34:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPNeIzKBC/Render', 17:34:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:11 INFO - 'HOME': '/Users/cltbld', 17:34:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:11 INFO - 'LOGNAME': 'cltbld', 17:34:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:11 INFO - 'MOZ_NO_REMOTE': '1', 17:34:11 INFO - 'NO_EM_RESTART': '1', 17:34:11 INFO - 'PAGER': '/bin/cat', 17:34:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:11 INFO - 'PWD': '/builds/slave/test', 17:34:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:11 INFO - 'SHELL': '/bin/bash', 17:34:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rbkx34R72M/Listeners', 17:34:11 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 17:34:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:11 INFO - 'USER': 'cltbld', 17:34:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:11 INFO - 'XPC_FLAGS': '0x0', 17:34:11 INFO - 'XPC_SERVICE_NAME': '0', 17:34:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:11 INFO - Downloading/unpacking psutil>=3.1.1 17:34:11 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 17:34:11 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 17:34:11 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 17:34:11 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 17:34:11 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 17:34:11 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 17:34:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:34:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:34:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:34:14 INFO - Installing collected packages: psutil 17:34:14 INFO - Running setup.py install for psutil 17:34:15 INFO - building 'psutil._psutil_osx' extension 17:34:15 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 17:34:15 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 17:34:15 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 17:34:15 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 17:34:15 INFO - building 'psutil._psutil_posix' extension 17:34:15 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 17:34:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:34:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:34:15 INFO - ^ 17:34:15 INFO - 1 warning generated. 17:34:16 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 17:34:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:34:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:34:16 INFO - Successfully installed psutil 17:34:16 INFO - Cleaning up... 17:34:16 INFO - Return code: 0 17:34:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017b2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101728570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78859c6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c6d30>, '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 0x1013ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015beb20>, '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.MyPNeIzKBC/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.Rbkx34R72M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 17:34:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:34:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:34:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPNeIzKBC/Render', 17:34:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:16 INFO - 'HOME': '/Users/cltbld', 17:34:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:16 INFO - 'LOGNAME': 'cltbld', 17:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:16 INFO - 'MOZ_NO_REMOTE': '1', 17:34:16 INFO - 'NO_EM_RESTART': '1', 17:34:16 INFO - 'PAGER': '/bin/cat', 17:34:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:16 INFO - 'PWD': '/builds/slave/test', 17:34:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:16 INFO - 'SHELL': '/bin/bash', 17:34:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rbkx34R72M/Listeners', 17:34:16 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 17:34:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:16 INFO - 'USER': 'cltbld', 17:34:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:16 INFO - 'XPC_FLAGS': '0x0', 17:34:16 INFO - 'XPC_SERVICE_NAME': '0', 17:34:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:34:16 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 17:34:16 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 17:34:16 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 17:34:16 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 17:34:16 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 17:34:16 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 17:34:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:34:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:34:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:34:18 INFO - Installing collected packages: mozsystemmonitor 17:34:18 INFO - Running setup.py install for mozsystemmonitor 17:34:19 INFO - Successfully installed mozsystemmonitor 17:34:19 INFO - Cleaning up... 17:34:19 INFO - Return code: 0 17:34:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:34:19 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')]} 17:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:19 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')]} 17:34:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:19 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 0x1017b2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101728570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78859c6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c6d30>, '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 0x1013ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015beb20>, '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.MyPNeIzKBC/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.Rbkx34R72M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 17:34:19 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 17:34:19 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 17:34:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPNeIzKBC/Render', 17:34:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:19 INFO - 'HOME': '/Users/cltbld', 17:34:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:19 INFO - 'LOGNAME': 'cltbld', 17:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:19 INFO - 'MOZ_NO_REMOTE': '1', 17:34:19 INFO - 'NO_EM_RESTART': '1', 17:34:19 INFO - 'PAGER': '/bin/cat', 17:34:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:19 INFO - 'PWD': '/builds/slave/test', 17:34:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:19 INFO - 'SHELL': '/bin/bash', 17:34:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rbkx34R72M/Listeners', 17:34:19 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 17:34:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:19 INFO - 'USER': 'cltbld', 17:34:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:19 INFO - 'XPC_FLAGS': '0x0', 17:34:19 INFO - 'XPC_SERVICE_NAME': '0', 17:34:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:19 INFO - Downloading/unpacking jsonschema==2.5.1 17:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:34:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:34:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:34:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:34:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:34:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:34:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:34:21 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 17:34:21 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 17:34:21 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 17:34:21 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 17:34:21 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 17:34:21 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 17:34:22 INFO - Downloading functools32-3.2.3-2.tar.gz 17:34:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:34:22 INFO - warning: no files found matching '*.txt' 17:34:22 INFO - no previously-included directories found matching 'build' 17:34:22 INFO - no previously-included directories found matching 'dist' 17:34:22 INFO - no previously-included directories found matching '.git*' 17:34:22 INFO - Installing collected packages: jsonschema, functools32 17:34:22 INFO - Running setup.py install for functools32 17:34:22 INFO - warning: no files found matching '*.txt' 17:34:22 INFO - no previously-included directories found matching 'build' 17:34:22 INFO - no previously-included directories found matching 'dist' 17:34:22 INFO - no previously-included directories found matching '.git*' 17:34:22 INFO - Successfully installed jsonschema functools32 17:34:22 INFO - Cleaning up... 17:34:22 INFO - Return code: 0 17:34:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:34:22 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')]} 17:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:22 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')]} 17:34:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:22 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 0x1017b2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101728570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78859c6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c6d30>, '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 0x1013ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015beb20>, '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.MyPNeIzKBC/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.Rbkx34R72M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 17:34:22 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 17:34:22 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 17:34:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPNeIzKBC/Render', 17:34:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:22 INFO - 'HOME': '/Users/cltbld', 17:34:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:22 INFO - 'LOGNAME': 'cltbld', 17:34:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:22 INFO - 'MOZ_NO_REMOTE': '1', 17:34:22 INFO - 'NO_EM_RESTART': '1', 17:34:22 INFO - 'PAGER': '/bin/cat', 17:34:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:22 INFO - 'PWD': '/builds/slave/test', 17:34:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:22 INFO - 'SHELL': '/bin/bash', 17:34:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rbkx34R72M/Listeners', 17:34:22 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 17:34:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:22 INFO - 'USER': 'cltbld', 17:34:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:22 INFO - 'XPC_FLAGS': '0x0', 17:34:22 INFO - 'XPC_SERVICE_NAME': '0', 17:34:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:34:22 INFO - Cleaning up... 17:34:23 INFO - Return code: 0 17:34:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:34:23 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')]} 17:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:23 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')]} 17:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:23 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 0x1017b2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101728570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78859c6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c6d30>, '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 0x1013ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015beb20>, '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.MyPNeIzKBC/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.Rbkx34R72M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 17:34:23 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 17:34:23 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 17:34:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPNeIzKBC/Render', 17:34:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:23 INFO - 'HOME': '/Users/cltbld', 17:34:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:23 INFO - 'LOGNAME': 'cltbld', 17:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:23 INFO - 'MOZ_NO_REMOTE': '1', 17:34:23 INFO - 'NO_EM_RESTART': '1', 17:34:23 INFO - 'PAGER': '/bin/cat', 17:34:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:23 INFO - 'PWD': '/builds/slave/test', 17:34:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:23 INFO - 'SHELL': '/bin/bash', 17:34:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rbkx34R72M/Listeners', 17:34:23 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 17:34:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:23 INFO - 'USER': 'cltbld', 17:34:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:23 INFO - 'XPC_FLAGS': '0x0', 17:34:23 INFO - 'XPC_SERVICE_NAME': '0', 17:34:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:23 INFO - Downloading/unpacking blobuploader==1.2.4 17:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:34:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:34:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:34:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:34:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:34:25 INFO - Downloading blobuploader-1.2.4.tar.gz 17:34:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:34:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:34:25 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 17:34:25 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 17:34:25 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 17:34:25 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 17:34:25 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 17:34:25 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 17:34:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:34:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:34:26 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 17:34:26 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 17:34:26 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 17:34:26 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 17:34:26 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 17:34:26 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 17:34:26 INFO - Downloading docopt-0.6.1.tar.gz 17:34:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:34:27 INFO - Installing collected packages: blobuploader, requests, docopt 17:34:27 INFO - Running setup.py install for blobuploader 17:34:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:34:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:34:27 INFO - Running setup.py install for requests 17:34:27 INFO - Running setup.py install for docopt 17:34:27 INFO - Successfully installed blobuploader requests docopt 17:34:27 INFO - Cleaning up... 17:34:27 INFO - Return code: 0 17:34:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:34:27 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')]} 17:34:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:27 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')]} 17:34:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:27 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 0x1017b2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101728570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78859c6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c6d30>, '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 0x1013ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015beb20>, '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.MyPNeIzKBC/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.Rbkx34R72M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 17:34:27 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 17:34:27 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 17:34:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPNeIzKBC/Render', 17:34:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:27 INFO - 'HOME': '/Users/cltbld', 17:34:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:27 INFO - 'LOGNAME': 'cltbld', 17:34:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:27 INFO - 'MOZ_NO_REMOTE': '1', 17:34:27 INFO - 'NO_EM_RESTART': '1', 17:34:27 INFO - 'PAGER': '/bin/cat', 17:34:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:27 INFO - 'PWD': '/builds/slave/test', 17:34:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:27 INFO - 'SHELL': '/bin/bash', 17:34:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rbkx34R72M/Listeners', 17:34:27 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 17:34:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:27 INFO - 'USER': 'cltbld', 17:34:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:27 INFO - 'XPC_FLAGS': '0x0', 17:34:27 INFO - 'XPC_SERVICE_NAME': '0', 17:34:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:34:28 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-O6Xjs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:34:28 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-GOLX56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:34:28 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Z3IqS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:34:28 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-GPydAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:34:28 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-DZHp0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:34:28 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-rOTGyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:34:28 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-C5YjCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:34:28 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-qPRhPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:34:29 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-u0vRN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:34:29 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-cucaEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:34:29 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-vJiJRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:34:29 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-ptfkwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:34:29 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-CuctsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:34:29 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-GwgdYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:34:29 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-iDIlUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:34:29 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-zNSXxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:34:29 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Jmuq79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:34:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:34:30 INFO - Running setup.py install for manifestparser 17:34:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:34:30 INFO - Running setup.py install for mozcrash 17:34:30 INFO - Running setup.py install for mozdebug 17:34:30 INFO - Running setup.py install for mozdevice 17:34:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:34:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:34:30 INFO - Running setup.py install for mozfile 17:34:30 INFO - Running setup.py install for mozhttpd 17:34:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:34:30 INFO - Running setup.py install for mozinfo 17:34:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Running setup.py install for mozInstall 17:34:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Running setup.py install for mozleak 17:34:31 INFO - Running setup.py install for mozlog 17:34:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Running setup.py install for moznetwork 17:34:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Running setup.py install for mozprocess 17:34:31 INFO - Running setup.py install for mozprofile 17:34:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Running setup.py install for mozrunner 17:34:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:34:32 INFO - Running setup.py install for mozscreenshot 17:34:32 INFO - Running setup.py install for moztest 17:34:32 INFO - Running setup.py install for mozversion 17:34:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:34:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:34:32 INFO - Cleaning up... 17:34:32 INFO - Return code: 0 17:34:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:34:32 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')]} 17:34:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:32 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')]} 17:34:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:32 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 0x1017b2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101728570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78859c6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c6d30>, '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 0x1013ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015beb20>, '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.MyPNeIzKBC/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.Rbkx34R72M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 17:34:32 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 17:34:32 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 17:34:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPNeIzKBC/Render', 17:34:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:32 INFO - 'HOME': '/Users/cltbld', 17:34:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:32 INFO - 'LOGNAME': 'cltbld', 17:34:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:32 INFO - 'MOZ_NO_REMOTE': '1', 17:34:32 INFO - 'NO_EM_RESTART': '1', 17:34:32 INFO - 'PAGER': '/bin/cat', 17:34:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:32 INFO - 'PWD': '/builds/slave/test', 17:34:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:32 INFO - 'SHELL': '/bin/bash', 17:34:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rbkx34R72M/Listeners', 17:34:32 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 17:34:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:32 INFO - 'USER': 'cltbld', 17:34:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:32 INFO - 'XPC_FLAGS': '0x0', 17:34:32 INFO - 'XPC_SERVICE_NAME': '0', 17:34:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:34:32 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-WsOE4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:34:33 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Dz2my0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:34:33 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-hMZijv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:34:33 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-f5FXYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:34:33 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-_2cmva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:34:33 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-mmaY1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:34:33 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-PjlvfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:34:33 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-sKXAo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:34:33 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-351AGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:34:33 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-DhuSpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:34:33 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-68SdxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:34:34 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)) 17:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:34:34 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-MDawg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:34:34 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)) 17:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:34:34 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-bo23wW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:34:34 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)) 17:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:34:34 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-NOwSvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:34:34 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)) 17:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:34:34 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-K8m5TB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:34:34 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)) 17:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:34:34 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-2352Ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:34:34 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)) 17:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:34:34 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-1GlWsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:34:34 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)) 17:34:34 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)) 17:34:34 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)) 17:34:34 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)) 17:34:34 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)) 17:34:34 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)) 17:34:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:34:34 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 17:34:34 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 17:34:34 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 17:34:34 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 17:34:34 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 17:34:34 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 17:34:37 INFO - Downloading blessings-1.6.tar.gz 17:34:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:34:37 INFO - Installing collected packages: blessings 17:34:37 INFO - Running setup.py install for blessings 17:34:37 INFO - Successfully installed blessings 17:34:37 INFO - Cleaning up... 17:34:37 INFO - Return code: 0 17:34:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:34:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:34:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:34:37 INFO - Reading from file tmpfile_stdout 17:34:37 INFO - Current package versions: 17:34:37 INFO - blessings == 1.6 17:34:37 INFO - blobuploader == 1.2.4 17:34:37 INFO - docopt == 0.6.1 17:34:37 INFO - functools32 == 3.2.3-2 17:34:37 INFO - jsonschema == 2.5.1 17:34:37 INFO - manifestparser == 1.1 17:34:37 INFO - mozInstall == 1.12 17:34:37 INFO - mozcrash == 0.17 17:34:37 INFO - mozdebug == 0.1 17:34:37 INFO - mozdevice == 0.48 17:34:37 INFO - mozfile == 1.2 17:34:37 INFO - mozhttpd == 0.7 17:34:37 INFO - mozinfo == 0.9 17:34:37 INFO - mozleak == 0.1 17:34:37 INFO - mozlog == 3.3 17:34:37 INFO - moznetwork == 0.27 17:34:37 INFO - mozprocess == 0.23 17:34:37 INFO - mozprofile == 0.28 17:34:37 INFO - mozrunner == 6.12 17:34:37 INFO - mozscreenshot == 0.1 17:34:37 INFO - mozsystemmonitor == 0.3 17:34:37 INFO - moztest == 0.7 17:34:37 INFO - mozversion == 1.4 17:34:37 INFO - psutil == 3.1.1 17:34:37 INFO - requests == 1.2.3 17:34:37 INFO - wsgiref == 0.1.2 17:34:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:34:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34: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')]} 17:34:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:38 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 0x1017b2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101728570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78859c6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c6d30>, '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 0x1013ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015beb20>, '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.MyPNeIzKBC/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.Rbkx34R72M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 17:34:38 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 17:34:38 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 17:34:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPNeIzKBC/Render', 17:34:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:38 INFO - 'HOME': '/Users/cltbld', 17:34:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:38 INFO - 'LOGNAME': 'cltbld', 17:34:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:38 INFO - 'MOZ_NO_REMOTE': '1', 17:34:38 INFO - 'NO_EM_RESTART': '1', 17:34:38 INFO - 'PAGER': '/bin/cat', 17:34:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:38 INFO - 'PWD': '/builds/slave/test', 17:34:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:38 INFO - 'SHELL': '/bin/bash', 17:34:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rbkx34R72M/Listeners', 17:34:38 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 17:34:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:38 INFO - 'USER': 'cltbld', 17:34:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:38 INFO - 'XPC_FLAGS': '0x0', 17:34:38 INFO - 'XPC_SERVICE_NAME': '0', 17:34:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:38 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)) 17:34:38 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)) 17:34:38 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)) 17:34:38 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)) 17:34:38 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)) 17:34:38 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)) 17:34:38 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)) 17:34:38 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)) 17:34:38 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)) 17:34:38 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)) 17:34:38 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)) 17:34:38 INFO - Cleaning up... 17:34:38 INFO - Return code: 0 17:34:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:34: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')]} 17:34:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34: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')]} 17:34:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:38 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 0x1017b2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101728570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78859c6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015c6d30>, '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 0x1013ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015beb20>, '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.MyPNeIzKBC/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.Rbkx34R72M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 17:34:38 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 17:34:38 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 17:34:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPNeIzKBC/Render', 17:34:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:38 INFO - 'HOME': '/Users/cltbld', 17:34:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:38 INFO - 'LOGNAME': 'cltbld', 17:34:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:38 INFO - 'MOZ_NO_REMOTE': '1', 17:34:38 INFO - 'NO_EM_RESTART': '1', 17:34:38 INFO - 'PAGER': '/bin/cat', 17:34:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:38 INFO - 'PWD': '/builds/slave/test', 17:34:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:38 INFO - 'SHELL': '/bin/bash', 17:34:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rbkx34R72M/Listeners', 17:34:38 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 17:34:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:38 INFO - 'USER': 'cltbld', 17:34:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:38 INFO - 'XPC_FLAGS': '0x0', 17:34:38 INFO - 'XPC_SERVICE_NAME': '0', 17:34:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:34:38 INFO - Cleaning up... 17:34:38 INFO - Return code: 0 17:34:38 INFO - Running post-action listener: _resource_record_post_action 17:34:38 INFO - Running post-action listener: _start_resource_monitoring 17:34:38 INFO - Starting resource monitoring. 17:34:38 INFO - [mozharness: 2016-09-10 00:34:38.843727Z] Finished create-virtualenv step (success) 17:34:38 INFO - [mozharness: 2016-09-10 00:34:38.844690Z] Running install step. 17:34:38 INFO - Running pre-action listener: _resource_record_pre_action 17:34:38 INFO - Running main action method: install 17:34:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:34:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:34:39 INFO - Reading from file tmpfile_stdout 17:34:39 INFO - Detecting whether we're running mozinstall >=1.0... 17:34:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:34:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:34:39 INFO - Reading from file tmpfile_stdout 17:34:39 INFO - Output received: 17:34:39 INFO - Usage: mozinstall [options] installer 17:34:39 INFO - Options: 17:34:39 INFO - -h, --help show this help message and exit 17:34:39 INFO - -d DEST, --destination=DEST 17:34:39 INFO - Directory to install application into. [default: 17:34:39 INFO - "/builds/slave/test"] 17:34:39 INFO - --app=APP Application being installed. [default: firefox] 17:34:39 INFO - mkdir: /builds/slave/test/build/application 17:34:39 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'] 17:34:39 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 17:35:14 INFO - Reading from file tmpfile_stdout 17:35:14 INFO - Output received: 17:35:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:35:14 INFO - Running post-action listener: _resource_record_post_action 17:35:14 INFO - [mozharness: 2016-09-10 00:35:14.001653Z] Finished install step (success) 17:35:14 INFO - [mozharness: 2016-09-10 00:35:14.001807Z] Running run-tests step. 17:35:14 INFO - Running pre-action listener: _resource_record_pre_action 17:35:14 INFO - Running main action method: run_tests 17:35:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:35:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:35:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:35:14 INFO - 2016-09-09 17:35:14.016 screenresolution[1641:7840] starting screenresolution argv=screenresolution get 17:35:14 INFO - 2016-09-09 17:35:14.019 screenresolution[1641:7840] Display 0: 1600x1200x32@60 17:35:14 INFO - 2016-09-09 17:35:14.026 screenresolution[1642:7842] starting screenresolution argv=screenresolution list 17:35:14 INFO - Available Modes on Display 0 17:35:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:35:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:35:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:35:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:35:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:35:14 INFO - Intel Iris: 17:35:14 INFO - Chipset Model: Intel Iris 17:35:14 INFO - Type: GPU 17:35:14 INFO - Bus: Built-In 17:35:14 INFO - VRAM (Dynamic, Max): 1536 MB 17:35:14 INFO - Vendor: Intel (0x8086) 17:35:14 INFO - Device ID: 0x0a2e 17:35:14 INFO - Revision ID: 0x0009 17:35:14 INFO - Displays: 17:35:14 INFO - Display: 17:35:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:35:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:35:14 INFO - Display Serial Number: 1600x1200 60 17:35:14 INFO - Main Display: Yes 17:35:14 INFO - Mirror: Off 17:35:14 INFO - Online: Yes 17:35:14 INFO - Rotation: Supported 17:35:14 INFO - Return code: 0 17:35:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:35:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:35:14 INFO - Python 2.7.3 17:35:14 INFO - Return code: 0 17:35:14 INFO - grabbing minidump binary from tooltool 17:35:14 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')]} 17:35:14 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 0x1015c6d30>, '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 0x1013ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015beb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:35:14 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 17:35:14 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 17:35:14 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 17:35:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:35:14 INFO - Return code: 0 17:35:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:35:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:35:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:35:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:35:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:35:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:35:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0195 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:35:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyPNeIzKBC/Render', 17:35:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:14 INFO - 'HOME': '/Users/cltbld', 17:35:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:14 INFO - 'LOGNAME': 'cltbld', 17:35:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:35:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:35:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:14 INFO - 'MOZ_NO_REMOTE': '1', 17:35:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:35:14 INFO - 'NO_EM_RESTART': '1', 17:35:14 INFO - 'PAGER': '/bin/cat', 17:35:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:14 INFO - 'PWD': '/builds/slave/test', 17:35:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:35:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:14 INFO - 'SHELL': '/bin/bash', 17:35:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rbkx34R72M/Listeners', 17:35:14 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 17:35:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:14 INFO - 'USER': 'cltbld', 17:35:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:14 INFO - 'XPC_FLAGS': '0x0', 17:35:14 INFO - 'XPC_SERVICE_NAME': '0', 17:35:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:35:14 INFO - mozversion application_buildid: 20160909154019 17:35:14 INFO - mozversion application_changeset: 57647be72e76cb9c8b8ea6069c6d53362c9892d9 17:35:14 INFO - mozversion application_display_name: Nightly 17:35:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:35:14 INFO - mozversion application_name: Firefox 17:35:14 INFO - mozversion application_remotingname: firefox 17:35:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 17:35:14 INFO - mozversion application_vendor: Mozilla 17:35:14 INFO - mozversion application_version: 51.0a1 17:35:14 INFO - mozversion platform_buildid: 20160909154019 17:35:14 INFO - mozversion platform_changeset: 57647be72e76cb9c8b8ea6069c6d53362c9892d9 17:35:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 17:35:14 INFO - mozversion platform_version: 51.0a1 17:35:14 INFO - using testdate: 1473467714 17:35:14 INFO - actual date: 1473467714 17:35:14 INFO - starting webserver on 'localhost:49271' 17:35:14 INFO - SUITE-START | Running 2 tests 17:35:14 INFO - TEST-START | dromaeo_css 17:35:14 INFO - operating with platform_type : mac_ 17:35:14 INFO - Initialising browser for dromaeo_css test... 17:35:14 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpKO5eWZ/profile http://localhost:49271/getInfo.html) 17:35:17 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 17:35:17 INFO - PROCESS | 1652 | colorDepth:24 17:35:17 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 17:35:17 INFO - PROCESS | 1652 | __metrics 17:35:17 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 17:35:18 INFO - TEST-INFO | 1652: exit 0 17:35:18 INFO - Browser initialized. 17:35:18 INFO - Running cycle 1/1 for dromaeo_css test... 17:35:18 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpKO5eWZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:35:30 INFO - PROCESS | 1655 | RSS: Main: 244310016 17:35:30 INFO - PROCESS | 1655 | 17:36:19 INFO - PROCESS | 1655 | 1473467779812 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] 17:38:32 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 17:38:33 INFO - PROCESS | 1655 | RSS: Main: 264224768 17:38:33 INFO - PROCESS | 1655 | 17:41:35 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 17:41:36 INFO - PROCESS | 1655 | RSS: Main: 268242944 17:41:36 INFO - PROCESS | 1655 | 17:44:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 17:44:39 INFO - PROCESS | 1655 | RSS: Main: 270868480 17:44:39 INFO - PROCESS | 1655 | 17:47:40 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 17:47:41 INFO - PROCESS | 1655 | RSS: Main: 268091392 17:47:41 INFO - PROCESS | 1655 | 17:50:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 17:50:45 INFO - PROCESS | 1655 | RSS: Main: 273420288 17:50:45 INFO - PROCESS | 1655 | 17:53:47 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 17:53:47 INFO - PROCESS | 1655 | RSS: Main: 276164608 17:53:47 INFO - PROCESS | 1655 | 17:53:47 INFO - PROCESS | 1655 | __start_tp_report 17:53:47 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 17:53:47 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 17:53:47 INFO - PROCESS | 1655 | |i|pagename|runs| 17:53:47 INFO - PROCESS | 1655 | |0;dojo.html;3024.880794106181;3361.2101156228287;3372.359251742169;3383.3571621391948;3427.8628854845815;5550.222968784374;5574.0802767543355;5584.385717571074;5623.465770751783;5747;5587.189857470665;5625.52183064439;5640.576956728251;5710.343310519292;5752.050911599592;5092.134337162673;5117.437081920985;5217.191335343028;5225.268462415265;5293.02079115363;5307.310049693534;5309;5316.122839731459;5336.226247194155;5359.330083739533;4097.528784189826;5212.4266330703595;5225.608079394023;5227.1113910635095;5278.973605131969;4207.863876753259;5026.673266237683;5128.871778205575;5177.171652535614;5249.580033597341;5126.6923984561045;5128.153854614022;5145.15105007677;5161.896762064737;5169.379674439091;5506.366767821712;5721.6853073080965;5763;5766.740496677659;5792.160136780143;6051.092336149569;6080.878382432326;6101.217066730177;6110.572259941806;6115.940154142385;6181.938180618226;6188.783392581283;6191.87616247677;6198;6207.193064901579;5744.569157313218;5752.252207213078;5752.856178595569;5757.337906140806;5794.884102317972;6129.267100814801;6151.9692401538605;6160.568760186834;6185.567010309235;6279.367364485267;5108.540231379194;6142.078688196806;6156.753729850756;6169.475594574422;6176.969115154486;5607.495325420732;6252.5935814171935;6257.9687101564205;6258.749650013949;6261.8747625047245;6001.789937352171;6064.666443345658;6109.816705498753;6131.009681329197;6131.651934473353;5985.16207730918;6210.068489726486;6219.222597175353;6225.470834979077;6244.969580019246;4039.2931237033404;4177.749335039907;4690.9765451172525;4924.1875090610365;4960.851174464772;3486.093615659947;3627.6009638939695;4240.575942405796;4339.7396156230725;4430.113977204507;5581.441855814467;5591.944080559142;5604.747786349624;5623.662580245198;5627.43725627434;5112.693238405707;6175.660338681416;6205.069239614004;6217.253929528486;6230.190075290274;5111.3355263815465;5266.131088370376;5541.2796336476;5617.8595535111135;5621.5221706155435;5394.78420863161;5445.264889239902;5453.072977593819;5475.151351540518;5485.4788795064405;6252.468540174136;6275;6288.056791481223;6305.148804911279;6340.809775706736;6109.131392048963;6175.166352542349;6275.748970041147;6278.937210628018;6294.370562943669;6184.81445556634;6231.875362492634;6276.6547839869245;6313.684315784284;6335.683215839282;5921.526277897672;5968.641881486951;5995.1906492624685;6007.88854062;6057.545684073801;5178.689278643294;5690.146478028329;6036.245469316335;6222.315545289924;6252.968735156477;4752.382190315375;5783.7397317119985;5799.710014499343;5848.093545500455;5852.473277405054;5797.507211887037;5856.560757943086;5868.559858010581;5926.4666180044005;5940.376260492586;5393.164059570773;5601.131824567198;5639.774409023593;5655.981923253854;5710.65736055838;5738.1679656450415;5758.452946970031;5822.679752613647;5840.948629246776;5851.531877449709;5162.767675454538;5420.7560659769615;5540.1135818270495;5625.662460252398;5631.465010823965 17:53:47 INFO - PROCESS | 1655 | |1;ext.html;479.7265558631581;488.2846629687509;489.5031542983872;524.9081909627974;546.6173678425101;35856.46215306769;39812;39877.60122398775;39893.202135957334;40001.59998400015;40895.38656920141;41333.173336533255;41387.793061034725;41412;41544.58455415445;8217.260446559809;8896.421732587361;9668.661596844095;9761.316707830454;9858.16205622523;8167.95916020422;9592.280578956606;9679.70960871175;9702.417854928692;9702.563384647709;9609.279304052188;9656.951715241414;9691.90308096917;9749.927507974153;9750;9632.181264592518;9707.271954603397;9716;9727.367721098106;9728.41629502225;8730.956345218297;9631.518424078839;9690.56392462341;9709.757256068655;9723.222142228604;39264.21471570581;39491.01272468182;39571.80214098911;39788;39827.8008609958;3255.641879393265;3258.7230085442766;3402.1494626343415;3473.7915725056323;3534.32847758925;30255.394892102035;30299.091027268998;30441.23896902573;30864.38271234563;30880.38239235225;3184.6178458584886;3190.2502911815704;3192.6488086310487;3192.776505644606;3303.4549299365576;493973.0122939554;501081;502081;502907;503461;51735.706607335116;56299.71850140805;56844.14733778997;56871.431285686616;56893.86212275731;33146;34150.48774268388;58382;58458;58494.4150558489;15866.52400427989;16315.42895998658;16317.939333943255;16388.508344749676;16393.83606163947;12627.179233349987;12703.301318427575;12716.936415317865;12718.236905785683;12719.982401407866;18623.906880465784;18639.720404193948;18687.43937681872;18720.719189212174;18743.343982960803;7891;8002.879956800536;8057.274845263954;8080;8132.430729848972;852.2542775071812;938.957746901391;982.6118683120128;995.64654547636;1045.3884477580548;29041.967062305437;29378.21505268915;29424.705752942624;29954.101376958726;30222;835.4486039214089;1019.1999280564721;1085.9837102443469;1103.2994048779083;1116.9500669370766;14186.787198192036;15197.164155971306;15377.692446151013;15420.383184672488;15577.065376077471;679747;685516;685979;686400;688036.5981700895;52687.736561316946;53845.73077134667;54124;54550;54824.72587637116;1309.941052652614;1339.6316013096477;1363.400103954257;1365.4196966289287;1368.7330970460864;28171.71828281773;29113.98101066537;29243.41513169767;29458.263543411587;29775.55336669995;47660.76169619129;47804.28293575669;48058.03883922372;48083;48201.75899120482;472560;473057;473493;474986;475534;25054.123105691215;25306.240812775646;25624.487510249317;25724.842382092476;25737.227883163534;44726.776366119266;45066;45232.64302070943;45956;46475.535244648476;37130;37397.81301093477;37921.2415751689;38008.865186482595;38042.80978595089;37074.25851483009;37122.44316335203;37610;38245.92450396685;38328.808355959154;29800.850995744884;31614.36771264608;36014.27971440504;36373.27253454969;36731.81634091919;30330.84834575813;31316.686833132288;33329.83335083309;34011.48982765211;34524.82737586296;24060;24432.877835610707;24712.50574988454;25044.248672539852;25123.623145652466 17:53:47 INFO - PROCESS | 1655 | |2;jquery.html;370.313069256529;372.47481051717074;379.9981000095;385.50077649443966;396.96427321541057;16250.918745406287;19088.522786930316;20572.69140962885;20671.689924651142;20754;20573.588528229462;20587.897060514675;20851.374458766262;20876.37370878876;21015.369538913856;5097.464766199551;5992.19105420768;6183.041628547582;6224.595401298924;6229;5359.330083739533;6105.145279660851;6170.352113028135;6216.347283535231;6228.906566401508;5999.940000599981;6016.639001659914;6047.3650266722025;6080.543959203077;6103.694815259241;5901.616394934315;6000.2199714037115;6031.547633927473;6050.818475445766;6069.119977603267;5991.880162396727;6012.819615411545;6071.908921366183;6114.449699527019;6120.173776540155;12905.161164524276;15767.605809854846;25073.87463062673;25082.623760643604;25120.246392608253;2178.9564208715738;2185.7049298344677;2227.4208705736464;2358.4575547624145;2967.9018763057593;9669.081437263449;9886.110250077456;10027.247956403298;10042.949785251027;10315.43265120418;1831.8076601956736;1865.3005123078844;1875.40001091502;1912.6748452763063;1940.5827747034314;86022.56988715079;109583;120213.79786202114;122456;123439.3828030854;10995;11364.318140911406;11447.19869609136;11519.596814111466;11697.134202120365;14373.56879293602;14399.208043557705;14406.279686015658;14422.990390672552;14431.783523247159;8225.670973161126;8477.618507167192;8512.957435212784;8562.78593035179;8621.870671939925;8417.789555261168;8419.200175983271;8432.957835210786;8574.142585741376;8618.913810861812;4383.802728877208;4446.377507149002;4454.153710794939;4524.366588677588;4548.613367863769;1887.8017808130223;1916.9712454312917;1918.7601549806275;1928.2961718972754;1972.7632684078003;823.6664151018728;854.3336197765752;936.9812603747887;941.5198248893114;957.8027465667915;15105.352650471632;21075.894620527106;21097.050632721257;21155.788442115383;21222.363329100153;896.8834051573253;934.3039435620507;945.1540870920625;976.7411635916487;990.2968892086699;10588.629397971034;11058.776964179631;11099.500522476346;11105;11148;487029;587717;600978;603108;610841;70804.64597677081;77859.61070194613;89792;110483;128101;953.8378475659016;986.1716158426956;997.8652881860855;999.8600195972424;1002.1081237698454;16650.75023874667;19594.412167634993;19896.60206795885;20007.299744508873;20124.29564965219;43134;49415.50584494109;51121.744391279295;51259.71850703613;57233.42766572281;74722.25277747153;77168.6141569311;77480.61259693665;77690.22309776829;77826;7659.004329437137;7762.146163922077;7910.406719496176;8172.83654326922;8233.876491852747;12164.695882603006;12934.482620695444;12986.220826750425;13183.736325273248;13256.469741210243;11106.777864442827;11213.887861121611;11335.943320283346;11514.82727759101;11596.594119205787;8910.910890891268;9007.819843603222;9928.503574821374;11202.495887685236;11395.829062563902;9454.716358509255;11361.602343917923;11524.539018439169;11619.709507262387;11625.5349786011;6525.575837570543;7907.051153861574;7945.927299814683;8002.7199048033035;8100.83798324042;10271.691849244535;10528.947355263175;10911.781764364827;11011.119110470983;11148.219624626523 17:53:47 INFO - PROCESS | 1655 | |3;mootools.html;1338.156961114498;1519.2555647732615;1613.887027908046;1761.2778760708104;1878.3238034307656;7923.088844782852;8162.102168761431;8481.406301558893;9072.183503484694;9090.681826136086;8477.91522084779;8756.69351572695;8764.342674299438;8771.736847894574;8887.866681999774;2392.533455976081;2406.181898154627;2415.335782659765;2422.9151979680714;2424.3696638873844;2373.762623737621;2401.891914863835;2425.684660994077;2436.3543660929868;2437.585610446228;2400.8919598618018;2404.3868813452546;2404.483036147228;2405.0500052479347;2423.2851308863837;2102;2328.4178955261186;2342.7891489765916;2359.1271229645054;2368.0764501844296;2377.7859992600575;2380.1312520929737;2387.510560335127;2391.7727815857465;2394;8806.471611703255;8965.820683586357;8985.326100542485;8998.325125615605;9016.36885418021;5278.683279003234;5439.211314359435;5439.972800136014;5449.373322067973;5450.618456708032;3594.4788005739283;3607.0261029522144;3653.671169594723;3691.53855768029;3704.759190652599;934.3973137326386;965.4545181972163;1070.1118072000222;1073.7798751255973;1080.222239987208;9743.707688769347;11655.708607284801;11702.765944681143;11800.88199118015;11832.88167118335;4070.715049946475;4601.010782681739;4755.619550435957;4825.348577942003;4858.975705121523;8941.776455588531;9463.810723785486;10118.443485608215;10123.03831136041;10124.949375253076;5402.567794576503;5415.025295446779;5422.566194704415;5428.267183930119;5447.264619276347;4480.775961201927;4589.151007063704;4807.663463557587;4908.705477671351;4980.352554167935;3561.3589553880033;3902.785346805896;3939.2515422069714;3958.524977002778;4059.330210515232;1304.1197191895433;1312.5143696832042;1312.527490103562;1322.2463195978391;1327.6747196936813;793.5794029164553;826.3719573124348;870.0038455968008;885.8848349714625;950.2160717408166;5639.351474580394;5666.858328541819;5977.491913187428;6059.787907423219;6271.96864015677;430.9956900430956;434.4851351148836;445.69469913109623;448.12839028090053;448.39018934249395;3977.76133431995;3979.343408337592;4050.3721923101966;4079.755214687128;4100.487439070116;9036.502992335354;10621.734456638645;10836.67489975302;10860.72848178786;10949.83575246372;10684.145268378512;10861.619843305447;10919.017288444078;10921.508532116073;10927.508262128224;8240.258376746122;8264.504129752235;8303.418760686694;8313.709020184264;8330.166983301742;5915.70421478933;5952.732127054206;6096.542759292982;6113.327533971171;6123.357047509948;9786.85319720219;9926.652567160114;10097.5456104474;10133.290669653348;10260.281780275607;10093.041161089684;10273.178145748472;10403.56682294856;10405.531751071068;10471.214658900766;6880.415164711056;7544.358729507834;7584.051993500812;7612.352949999312;7715.035620547432;8483;8503.574821258788;8560.015598206286;8560.743177704679;8565.48607083577;7501.174870764321;7605.543667379975;7627.656755446129;7661.310482056641;7772.572508512086;7329.670164842488;7490.887636685563;7493.925060749323;7497.962510187414;7645.388368930583;6884.449244060564;7010.649467526501;7014.088168538057;7019.122609673791;7021.648917554204;4952.950470495393;5058.371589995113;5118.539331460186;5130.435652078194;5174.4566820484815;4975;4991.276264941491;5133.383993920754;5165.560927321219;5207.71357575337 17:53:47 INFO - PROCESS | 1655 | |4;prototype.html;273.4012512597412;290.64105829300826;301.38968588608077;308.2232773411004;311.82381954195876;4308.870733877989;4631.907361852761;5228.320318358613;5470.206820011096;5651.265335506384;5310.946890531093;5473.343198816147;5485.424030476803;5535.25274087998;5563.554915606752;3718.7768733876055;4216.5572614875455;4246.80889359978;4269.252880745873;4285.292926667096;3091.6290045194496;4133.359329303963;4191.874243772691;4248.707251879802;4298.333758267458;4104.583918548096;4109.691773117013;4135.73117747347;4136.420901073852;4180.728252663583;4123.505179378464;4138.668906487474;4161.898241388109;4164.3961625564425;4200.600942910419;3569.7679650822874;3602.477640742104;3606.9179246226026;3607.6392360763975;3608.855645774166;4369.759663218521;5220.399654039758;5278.313819203479;5296.708681022542;5305.442928492491;4675.340254209775;4695.826043489128;4733.431988161408;4739.691920025188;4753.595944344735;5060.823845746364;5348.491893270133;5802.651840889518;5817.080363480041;5852.385499522531;3623.365910965597;3845.8463260574167;3884.495015647976;3891.7859517726515;3893.357595996657;5321.707306098163;6059.030555111205;6169.969150154266;6181.300142460815;6238.064290356392;4002.199560087994;4002.9799851000557;4003.3594624860466;4008.5590585036207;4011.29802284599;3353.6814002669707;3867.671247943956;4005.7997100144876;4051.4327994080854;4066.6340029397497;3956.911849241424;3978.522577290744;4765.571098601074;4781.784819683123;4823.541763532458;5201.817936372285;5960.791372301948;5964.3617393389995;5979.431953964367;6027.57806953509;1199.3643369014437;1264.2035517623838;1297.4356155072574;1374.2853716067789;1376.348381941674;724.8803947348733;729.4857125726351;808.5391326943703;829.8506268871661;841.9494830310201;3834.559025712079;3965.6034396560112;4072.5609086096297;4470.463544374631;4626.149540183953;4369.75966321849;4843.370361853007;5108.055009823194;5158.948410515847;5180.818671346485;3750.774953502799;3877.0695033192396;4203.117345357448;4668.766561671903;4721.6458765592715;2615.365396627108;2665.9333516662236;2756.28336632477;2841.602175695404;3029.636443626735;5407.891842163135;6109.236345456818;6358.904616430757;6366.853966286113;6422.871542569216;5098.337216161875;5281.39263984639;5803.303603567599;5981.37195594465;6205.503559715212;4992.226204938241;5196.922046169386;5746.994276001683;6048.939510604926;6311.463525600284;5123.769430375568;5372.113601255905;6130.754769809158;6183.721732522137;6219.689015549295;5611.681254905013;5951.494122999594;6023.608465449732;6070.332263451105;6081.543884208613;5882.3529411764775;6371.617702937839;6426.228852537539;6448.322926092693;6449.7097630605795;3342.7158691511495;3790.5451345838674;3937.192875460581;3945.349017412095;3963.30642137619;5945.227120474311;6016;6030.336662966983;6048.546359023003;6075.1446819994535;5484.917726234189;5655.688937108499;5735.71321433935;5749.568782341257;5779.370386474228;5276.920846187233;5778.393268706725;5837.970810145922;5851.180834683233;5860.2967643883;5078.263651770399;5293.708846013506;5521.641093328921;5683.914741278967;5704.914426283692;5791.710414479175;5802.303723553201;5823.970880145572;5853.268341457318;5880.529557635465;4688.671792974459;4771.332013518179;5269.604779641466;5697.487226149667;5755.503569071988 17:53:47 INFO - PROCESS | 1655 | |5;yui.html;705.717712914834;705.8588282343533;764.3312101910828;772.3357912195507;811.6307080278474;12145.271283722983;14962.259435141215;15732.37070517178;15732.764008539882;15773.290201940912;14110.788338174936;14196.077254978445;14196.29018549071;14234.217118058503;14234.857651423483;667.1293961380396;684.8869936460478;829.1294141151767;836.0928392693935;848.6096395658004;703.5989485992986;826.1531929771971;843.1399972028527;843.439112989861;846.5005646668465;886.0519244408486;892.2371372476537;896.274018045382;897.4166791585488;897.4794619120927;884.5444596033049;886.0165216609558;888.9066648001964;889.2707979456848;890.1054440287502;650.8307839961597;660.4815220052236;661.4377778887955;661.7121552124842;662.6421732264571;14646.194459304734;14809.851901480955;14824.703505929821;14851.851481485262;14871.47949821751;11465.770684586261;14466;14473.27633618315;14521.564353069425;14599.416023359054;11636.709082272926;11790.587329443513;11841.645798206171;11884.940575297154;11916.880831191664;3760.548734151892;3791.0699241786124;3801.486799282117;3828.444875493066;3963.5243770747693;120394.79605203924;123517;124135.37932310281;124286;124731.37634311861;11151.776964460665;11257.662270131908;11284.887151128383;11492.712682183012;11668.76662466746;23162.53674926492;23228.187013454786;23251.88374058119;23298.06807727672;23316.53366932686;7747.418943579254;7892.329152022027;8046.959765201136;8148.796280093045;8157.2250636189465;4220.366944958281;4358.128374325148;4408.25059739845;4502.212112880233;4573.6569757268335;3423.4864770284657;3569.8750543730844;3571.357155711945;3573.8034408107283;3576.2668652926086;639.2137670665107;647.1069923505532;656.9113169722026;657.5857209957696;665.3712241931329;430.6361124849497;494.5203152941641;513.997430012855;578.0548802707603;583.5360888093976;7753.340966017839;8885;8997.055309192572;9126.36115471924;9204.493752843659;529.4414392815617;573.6873403994833;613.4448324266546;636.780310792785;684.9623270720059;4205.810738516774;4430.82276708928;4655.627549796008;4720.527947205252;5095.439501654815;108272.45863770788;134402;140485.29757351353;148172;166705.49941750884;116368.41815791036;121938;148777;149080.25459872847;152526;94726.26368681538;105657.99999999846;106311;112611.43694281476;114685;9991.750206244902;10152.644657436955;10228;10265.384076955406;10274.383536987805;61204.69397653161;63149.684251578445;65035.67482162559;65588.67205664131;67037.32962670311;76364.85452718103;97925.51037245053;99840;99886;102370;12727.681807954876;12849.357532123544;12978.351082445652;13439.126456780277;13725.519606813714;14575;14588.708225835637;14593.85406145925;14662.78005829892;15055.096694198382;13451.865481345061;13568.79646805279;13756.518521851687;13959.092658977133;14160.93792965511;13443.932228339008;13467.932660336635;13643.727125457634;13699.041067125605;13877.514287000311;12958.352082395653;13328.333583320602;13357.200911488786;13446.327683615584;13736.862631373559;10862.782744344911;11022.559097636005;11359.943200283946;11399;11636.883631163919;9113.498757568132;9602.759931001781;11130.777384452427;11272.830907536558;11352.716182095513 17:53:47 INFO - PROCESS | 1655 | __end_tp_report 17:53:47 INFO - PROCESS | 1655 | __start_cc_report 17:53:47 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,383 17:53:47 INFO - PROCESS | 1655 | __end_cc_report 17:53:47 INFO - PROCESS | 1655 | __startTimestamp1473468827953__endTimestamp 17:53:47 INFO - PROCESS | 1655 | 17:53:47 INFO - PROCESS | 1655 | ------- Summary: start ------- 17:53:47 INFO - PROCESS | 1655 | Number of tests: 6 17:53:47 INFO - PROCESS | 1655 | 17:53:47 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5593.27 Median:5744.57 stddev:685.86 (11.9%) stddev-sans-first:657.70 17:53:47 INFO - PROCESS | 1655 | Values: 3024.9 3361.2 3372.4 3383.4 3427.9 5550.2 5574.1 5584.4 5623.5 5747.0 5587.2 5625.5 5640.6 5710.3 5752.1 5092.1 5117.4 5217.2 5225.3 5293.0 5307.3 5309.0 5316.1 5336.2 5359.3 4097.5 5212.4 5225.6 5227.1 5279.0 4207.9 5026.7 5128.9 5177.2 5249.6 5126.7 5128.2 5145.2 5161.9 5169.4 5506.4 5721.7 5763.0 5766.7 5792.2 6051.1 6080.9 6101.2 6110.6 6115.9 6181.9 6188.8 6191.9 6198.0 6207.2 5744.6 5752.3 5752.9 5757.3 5794.9 6129.3 6152.0 6160.6 6185.6 6279.4 5108.5 6142.1 6156.8 6169.5 6177.0 5607.5 6252.6 6258.0 6258.7 6261.9 6001.8 6064.7 6109.8 6131.0 6131.7 5985.2 6210.1 6219.2 6225.5 6245.0 4039.3 4177.7 4691.0 4924.2 4960.9 3486.1 3627.6 4240.6 4339.7 4430.1 5581.4 5591.9 5604.7 5623.7 5627.4 5112.7 6175.7 6205.1 6217.3 6230.2 5111.3 5266.1 5541.3 5617.9 5621.5 5394.8 5445.3 5453.1 5475.2 5485.5 6252.5 6275.0 6288.1 6305.1 6340.8 6109.1 6175.2 6275.7 6278.9 6294.4 6184.8 6231.9 6276.7 6313.7 6335.7 5921.5 5968.6 5995.2 6007.9 6057.5 5178.7 5690.1 6036.2 6222.3 6253.0 4752.4 5783.7 5799.7 5848.1 5852.5 5797.5 5856.6 5868.6 5926.5 5940.4 5393.2 5601.1 5639.8 5656.0 5710.7 5738.2 5758.5 5822.7 5840.9 5851.5 5162.8 5420.8 5540.1 5625.7 5631.5 17:53:47 INFO - PROCESS | 1655 | 17:53:47 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:67831.47 Median:39679.90 stddev:150172.62 (378.5%) stddev-sans-first:150508.75 17:53:47 INFO - PROCESS | 1655 | Values: 479.7 488.3 489.5 524.9 546.6 35856.5 39812.0 39877.6 39893.2 40001.6 40895.4 41333.2 41387.8 41412.0 41544.6 8217.3 8896.4 9668.7 9761.3 9858.2 8168.0 9592.3 9679.7 9702.4 9702.6 9609.3 9657.0 9691.9 9749.9 9750.0 9632.2 9707.3 9716.0 9727.4 9728.4 8731.0 9631.5 9690.6 9709.8 9723.2 39264.2 39491.0 39571.8 39788.0 39827.8 3255.6 3258.7 3402.1 3473.8 3534.3 30255.4 30299.1 30441.2 30864.4 30880.4 3184.6 3190.3 3192.6 3192.8 3303.5 493973.0 501081.0 502081.0 502907.0 503461.0 51735.7 56299.7 56844.1 56871.4 56893.9 33146.0 34150.5 58382.0 58458.0 58494.4 15866.5 16315.4 16317.9 16388.5 16393.8 12627.2 12703.3 12716.9 12718.2 12720.0 18623.9 18639.7 18687.4 18720.7 18743.3 7891.0 8002.9 8057.3 8080.0 8132.4 852.3 939.0 982.6 995.6 1045.4 29042.0 29378.2 29424.7 29954.1 30222.0 835.4 1019.2 1086.0 1103.3 1117.0 14186.8 15197.2 15377.7 15420.4 15577.1 679747.0 685516.0 685979.0 686400.0 688036.6 52687.7 53845.7 54124.0 54550.0 54824.7 1309.9 1339.6 1363.4 1365.4 1368.7 28171.7 29114.0 29243.4 29458.3 29775.6 47660.8 47804.3 48058.0 48083.0 48201.8 472560.0 473057.0 473493.0 474986.0 475534.0 25054.1 25306.2 25624.5 25724.8 25737.2 44726.8 45066.0 45232.6 45956.0 46475.5 37130.0 37397.8 37921.2 38008.9 38042.8 37074.3 37122.4 37610.0 38245.9 38328.8 29800.9 31614.4 36014.3 36373.3 36731.8 30330.8 31316.7 33329.8 34011.5 34524.8 24060.0 24432.9 24712.5 25044.2 25123.6 17:53:47 INFO - PROCESS | 1655 | 17:53:47 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:33137.38 Median:21765.48 stddev:96182.80 (441.9%) stddev-sans-first:96421.15 17:53:47 INFO - PROCESS | 1655 | Values: 370.3 372.5 380.0 385.5 397.0 16250.9 19088.5 20572.7 20671.7 20754.0 20573.6 20587.9 20851.4 20876.4 21015.4 5097.5 5992.2 6183.0 6224.6 6229.0 5359.3 6105.1 6170.4 6216.3 6228.9 5999.9 6016.6 6047.4 6080.5 6103.7 5901.6 6000.2 6031.5 6050.8 6069.1 5991.9 6012.8 6071.9 6114.4 6120.2 12905.2 15767.6 25073.9 25082.6 25120.2 2179.0 2185.7 2227.4 2358.5 2967.9 9669.1 9886.1 10027.2 10042.9 10315.4 1831.8 1865.3 1875.4 1912.7 1940.6 86022.6 109583.0 120213.8 122456.0 123439.4 10995.0 11364.3 11447.2 11519.6 11697.1 14373.6 14399.2 14406.3 14423.0 14431.8 8225.7 8477.6 8513.0 8562.8 8621.9 8417.8 8419.2 8433.0 8574.1 8618.9 4383.8 4446.4 4454.2 4524.4 4548.6 1887.8 1917.0 1918.8 1928.3 1972.8 823.7 854.3 937.0 941.5 957.8 15105.4 21075.9 21097.1 21155.8 21222.4 896.9 934.3 945.2 976.7 990.3 10588.6 11058.8 11099.5 11105.0 11148.0 487029.0 587717.0 600978.0 603108.0 610841.0 70804.6 77859.6 89792.0 110483.0 128101.0 953.8 986.2 997.9 999.9 1002.1 16650.8 19594.4 19896.6 20007.3 20124.3 43134.0 49415.5 51121.7 51259.7 57233.4 74722.3 77168.6 77480.6 77690.2 77826.0 7659.0 7762.1 7910.4 8172.8 8233.9 12164.7 12934.5 12986.2 13183.7 13256.5 11106.8 11213.9 11335.9 11514.8 11596.6 8910.9 9007.8 9928.5 11202.5 11395.8 9454.7 11361.6 11524.5 11619.7 11625.5 6525.6 7907.1 7945.9 8002.7 8100.8 10271.7 10528.9 10911.8 11011.1 11148.2 17:53:47 INFO - PROCESS | 1655 | 17:53:47 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5737.59 Median:4930.83 stddev:3221.08 (65.3%) stddev-sans-first:3213.15 17:53:47 INFO - PROCESS | 1655 | Values: 1338.2 1519.3 1613.9 1761.3 1878.3 7923.1 8162.1 8481.4 9072.2 9090.7 8477.9 8756.7 8764.3 8771.7 8887.9 2392.5 2406.2 2415.3 2422.9 2424.4 2373.8 2401.9 2425.7 2436.4 2437.6 2400.9 2404.4 2404.5 2405.1 2423.3 2102.0 2328.4 2342.8 2359.1 2368.1 2377.8 2380.1 2387.5 2391.8 2394.0 8806.5 8965.8 8985.3 8998.3 9016.4 5278.7 5439.2 5440.0 5449.4 5450.6 3594.5 3607.0 3653.7 3691.5 3704.8 934.4 965.5 1070.1 1073.8 1080.2 9743.7 11655.7 11702.8 11800.9 11832.9 4070.7 4601.0 4755.6 4825.3 4859.0 8941.8 9463.8 10118.4 10123.0 10124.9 5402.6 5415.0 5422.6 5428.3 5447.3 4480.8 4589.2 4807.7 4908.7 4980.4 3561.4 3902.8 3939.3 3958.5 4059.3 1304.1 1312.5 1312.5 1322.2 1327.7 793.6 826.4 870.0 885.9 950.2 5639.4 5666.9 5977.5 6059.8 6272.0 431.0 434.5 445.7 448.1 448.4 3977.8 3979.3 4050.4 4079.8 4100.5 9036.5 10621.7 10836.7 10860.7 10949.8 10684.1 10861.6 10919.0 10921.5 10927.5 8240.3 8264.5 8303.4 8313.7 8330.2 5915.7 5952.7 6096.5 6113.3 6123.4 9786.9 9926.7 10097.5 10133.3 10260.3 10093.0 10273.2 10403.6 10405.5 10471.2 6880.4 7544.4 7584.1 7612.4 7715.0 8483.0 8503.6 8560.0 8560.7 8565.5 7501.2 7605.5 7627.7 7661.3 7772.6 7329.7 7490.9 7493.9 7498.0 7645.4 6884.4 7010.6 7014.1 7019.1 7021.6 4953.0 5058.4 5118.5 5130.4 5174.5 4975.0 4991.3 5133.4 5165.6 5207.7 17:53:47 INFO - PROCESS | 1655 | 17:53:47 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4604.32 Median:5103.20 stddev:1477.71 (29.0%) stddev-sans-first:1445.66 17:53:47 INFO - PROCESS | 1655 | Values: 273.4 290.6 301.4 308.2 311.8 4308.9 4631.9 5228.3 5470.2 5651.3 5310.9 5473.3 5485.4 5535.3 5563.6 3718.8 4216.6 4246.8 4269.3 4285.3 3091.6 4133.4 4191.9 4248.7 4298.3 4104.6 4109.7 4135.7 4136.4 4180.7 4123.5 4138.7 4161.9 4164.4 4200.6 3569.8 3602.5 3606.9 3607.6 3608.9 4369.8 5220.4 5278.3 5296.7 5305.4 4675.3 4695.8 4733.4 4739.7 4753.6 5060.8 5348.5 5802.7 5817.1 5852.4 3623.4 3845.8 3884.5 3891.8 3893.4 5321.7 6059.0 6170.0 6181.3 6238.1 4002.2 4003.0 4003.4 4008.6 4011.3 3353.7 3867.7 4005.8 4051.4 4066.6 3956.9 3978.5 4765.6 4781.8 4823.5 5201.8 5960.8 5964.4 5979.4 6027.6 1199.4 1264.2 1297.4 1374.3 1376.3 724.9 729.5 808.5 829.9 841.9 3834.6 3965.6 4072.6 4470.5 4626.1 4369.8 4843.4 5108.1 5158.9 5180.8 3750.8 3877.1 4203.1 4668.8 4721.6 2615.4 2665.9 2756.3 2841.6 3029.6 5407.9 6109.2 6358.9 6366.9 6422.9 5098.3 5281.4 5803.3 5981.4 6205.5 4992.2 5196.9 5747.0 6048.9 6311.5 5123.8 5372.1 6130.8 6183.7 6219.7 5611.7 5951.5 6023.6 6070.3 6081.5 5882.4 6371.6 6426.2 6448.3 6449.7 3342.7 3790.5 3937.2 3945.3 3963.3 5945.2 6016.0 6030.3 6048.5 6075.1 5484.9 5655.7 5735.7 5749.6 5779.4 5276.9 5778.4 5838.0 5851.2 5860.3 5078.3 5293.7 5521.6 5683.9 5704.9 5791.7 5802.3 5824.0 5853.3 5880.5 4688.7 4771.3 5269.6 5697.5 5755.5 17:53:47 INFO - PROCESS | 1655 | 17:53:47 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:25326.25 Median:23307.30 stddev:40639.32 (174.4%) stddev-sans-first:40711.28 17:53:47 INFO - PROCESS | 1655 | Values: 705.7 705.9 764.3 772.3 811.6 12145.3 14962.3 15732.4 15732.8 15773.3 14110.8 14196.1 14196.3 14234.2 14234.9 667.1 684.9 829.1 836.1 848.6 703.6 826.2 843.1 843.4 846.5 886.1 892.2 896.3 897.4 897.5 884.5 886.0 888.9 889.3 890.1 650.8 660.5 661.4 661.7 662.6 14646.2 14809.9 14824.7 14851.9 14871.5 11465.8 14466.0 14473.3 14521.6 14599.4 11636.7 11790.6 11841.6 11884.9 11916.9 3760.5 3791.1 3801.5 3828.4 3963.5 120394.8 123517.0 124135.4 124286.0 124731.4 11151.8 11257.7 11284.9 11492.7 11668.8 23162.5 23228.2 23251.9 23298.1 23316.5 7747.4 7892.3 8047.0 8148.8 8157.2 4220.4 4358.1 4408.3 4502.2 4573.7 3423.5 3569.9 3571.4 3573.8 3576.3 639.2 647.1 656.9 657.6 665.4 430.6 494.5 514.0 578.1 583.5 7753.3 8885.0 8997.1 9126.4 9204.5 529.4 573.7 613.4 636.8 685.0 4205.8 4430.8 4655.6 4720.5 5095.4 108272.5 134402.0 140485.3 148172.0 166705.5 116368.4 121938.0 148777.0 149080.3 152526.0 94726.3 105658.0 106311.0 112611.4 114685.0 9991.8 10152.6 10228.0 10265.4 10274.4 61204.7 63149.7 65035.7 65588.7 67037.3 76364.9 97925.5 99840.0 99886.0 102370.0 12727.7 12849.4 12978.4 13439.1 13725.5 14575.0 14588.7 14593.9 14662.8 15055.1 13451.9 13568.8 13756.5 13959.1 14160.9 13443.9 13467.9 13643.7 13699.0 13877.5 12958.4 13328.3 13357.2 13446.3 13736.9 10862.8 11022.6 11359.9 11399.0 11636.9 9113.5 9602.8 11130.8 11272.8 11352.7 17:53:47 INFO - PROCESS | 1655 | -------- Summary: end -------- 17:53:47 INFO - PROCESS | 1655 | 17:53:48 INFO - TEST-INFO | 1655: exit 0 17:53:48 INFO - TEST-OK | dromaeo_css | took 1113921ms 17:53:48 INFO - TEST-START | kraken 17:53:48 INFO - operating with platform_type : mac_ 17:53:48 INFO - Initialising browser for kraken test... 17:53:48 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpLetOTU/profile http://localhost:49271/getInfo.html) 17:53:51 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 17:53:51 INFO - PROCESS | 1663 | colorDepth:24 17:53:51 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 17:53:51 INFO - PROCESS | 1663 | __metrics 17:53:51 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 17:53:52 INFO - TEST-INFO | 1663: exit 0 17:53:52 INFO - Browser initialized. 17:53:52 INFO - Running cycle 1/1 for kraken test... 17:53:52 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpLetOTU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:54:04 INFO - PROCESS | 1665 | RSS: Main: 243769344 17:54:04 INFO - PROCESS | 1665 | 17:54:06 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 17:54:07 INFO - PROCESS | 1665 | RSS: Main: 256618496 17:54:07 INFO - PROCESS | 1665 | 17:54:10 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 17:54:11 INFO - PROCESS | 1665 | RSS: Main: 265265152 17:54:11 INFO - PROCESS | 1665 | 17:54:14 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 17:54:15 INFO - PROCESS | 1665 | RSS: Main: 263884800 17:54:15 INFO - PROCESS | 1665 | 17:54:17 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 17:54:17 INFO - PROCESS | 1665 | RSS: Main: 266289152 17:54:17 INFO - PROCESS | 1665 | 17:54:19 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:54:20 INFO - PROCESS | 1665 | RSS: Main: 266911744 17:54:20 INFO - PROCESS | 1665 | 17:54:23 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 17:54:24 INFO - PROCESS | 1665 | RSS: Main: 267530240 17:54:24 INFO - PROCESS | 1665 | 17:54:27 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 17:54:27 INFO - PROCESS | 1665 | RSS: Main: 267747328 17:54:27 INFO - PROCESS | 1665 | 17:54:30 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 17:54:31 INFO - PROCESS | 1665 | RSS: Main: 267898880 17:54:31 INFO - PROCESS | 1665 | 17:54:33 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:54:33 INFO - PROCESS | 1665 | RSS: Main: 267268096 17:54:33 INFO - PROCESS | 1665 | 17:54:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:54:36 INFO - PROCESS | 1665 | RSS: Main: 267444224 17:54:36 INFO - PROCESS | 1665 | 17:54:38 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:54:39 INFO - PROCESS | 1665 | RSS: Main: 267718656 17:54:39 INFO - PROCESS | 1665 | 17:54:42 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:54:42 INFO - PROCESS | 1665 | RSS: Main: 268132352 17:54:42 INFO - PROCESS | 1665 | 17:54:45 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:54:46 INFO - PROCESS | 1665 | RSS: Main: 268185600 17:54:46 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 17:54:48 INFO - PROCESS | 1665 | RSS: Main: 269967360 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | __start_tp_report 17:54:48 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 17:54:48 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 17:54:48 INFO - PROCESS | 1665 | |i|pagename|runs| 17:54:48 INFO - PROCESS | 1665 | |0;ai-astar;116;112;116;115;116;101;117;117;112;117 17:54:48 INFO - PROCESS | 1665 | |1;audio-beat-detection;146;118;168;162;112;131;130;114;163;111 17:54:48 INFO - PROCESS | 1665 | |2;audio-dft;201;203;206;235;199;199;203;202;198;206 17:54:48 INFO - PROCESS | 1665 | |3;audio-fft;71;82;76;152;61;59;60;60;136;63 17:54:48 INFO - PROCESS | 1665 | |4;audio-oscillator;71;73;121;71;75;83;119;95;104;145 17:54:48 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;90;133;110;88;110;84;139;132;90;87 17:54:48 INFO - PROCESS | 1665 | |6;imaging-darkroom;100;99;105;97;173;142;102;101;100;101 17:54:48 INFO - PROCESS | 1665 | |7;imaging-desaturate;77;139;142;91;119;145;110;92;91;89 17:54:48 INFO - PROCESS | 1665 | |8;json-parse-financial;56;60;59;59;58;59;57;59;58;58 17:54:48 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;69;69;78;81;79;73;70;70;72;71 17:54:48 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;91;98;112;82;93;82;107;89;84;85 17:54:48 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;136;140;140;137;141;138;136;147;190;162 17:54:48 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;206;244;201;174;173;176;175;177;177;174 17:54:48 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;60;91;89;70;65;60;89;65;84;94 17:54:48 INFO - PROCESS | 1665 | __end_tp_report 17:54:48 INFO - PROCESS | 1665 | __start_cc_report 17:54:48 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,979 17:54:48 INFO - PROCESS | 1665 | __end_cc_report 17:54:48 INFO - PROCESS | 1665 | __startTimestamp1473468888405__endTimestamp 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | ------- Summary: start ------- 17:54:48 INFO - PROCESS | 1665 | Number of tests: 14 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:113.90 Median:116.00 stddev:4.91 (4.2%) stddev-sans-first:5.15 17:54:48 INFO - PROCESS | 1665 | Values: 116.0 112.0 116.0 115.0 116.0 101.0 117.0 117.0 112.0 117.0 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:138.50 stddev:22.60 (16.3%) stddev-sans-first:23.65 17:54:48 INFO - PROCESS | 1665 | Values: 146.0 118.0 168.0 162.0 112.0 131.0 130.0 114.0 163.0 111.0 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:205.20 Median:203.00 stddev:10.83 (5.3%) stddev-sans-first:11.38 17:54:48 INFO - PROCESS | 1665 | Values: 201.0 203.0 206.0 235.0 199.0 199.0 203.0 202.0 198.0 206.0 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:82.00 Median:62.00 stddev:33.78 (54.5%) stddev-sans-first:35.60 17:54:48 INFO - PROCESS | 1665 | Values: 71.0 82.0 76.0 152.0 61.0 59.0 60.0 60.0 136.0 63.0 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:72.00 stddev:25.84 (35.9%) stddev-sans-first:25.81 17:54:48 INFO - PROCESS | 1665 | Values: 71.0 73.0 121.0 71.0 75.0 83.0 119.0 95.0 104.0 145.0 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:106.30 Median:85.50 stddev:21.62 (25.3%) stddev-sans-first:22.11 17:54:48 INFO - PROCESS | 1665 | Values: 90.0 133.0 110.0 88.0 110.0 84.0 139.0 132.0 90.0 87.0 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:112.00 Median:123.50 stddev:25.15 (20.4%) stddev-sans-first:26.30 17:54:48 INFO - PROCESS | 1665 | Values: 100.0 99.0 105.0 97.0 173.0 142.0 102.0 101.0 100.0 101.0 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:109.50 Median:83.00 stddev:25.26 (30.4%) stddev-sans-first:23.90 17:54:48 INFO - PROCESS | 1665 | Values: 77.0 139.0 142.0 91.0 119.0 145.0 110.0 92.0 91.0 89.0 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:59.00 stddev:1.16 (2.0%) stddev-sans-first:0.88 17:54:48 INFO - PROCESS | 1665 | Values: 56.0 60.0 59.0 59.0 58.0 59.0 57.0 59.0 58.0 58.0 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:73.20 Median:72.50 stddev:4.47 (6.2%) stddev-sans-first:4.47 17:54:48 INFO - PROCESS | 1665 | Values: 69.0 69.0 78.0 81.0 79.0 73.0 70.0 70.0 72.0 71.0 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:92.30 Median:87.00 stddev:10.46 (12.0%) stddev-sans-first:11.08 17:54:48 INFO - PROCESS | 1665 | Values: 91.0 98.0 112.0 82.0 93.0 82.0 107.0 89.0 84.0 85.0 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:140.50 stddev:17.09 (12.2%) stddev-sans-first:17.69 17:54:48 INFO - PROCESS | 1665 | Values: 136.0 140.0 140.0 137.0 141.0 138.0 136.0 147.0 190.0 162.0 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.70 Median:177.00 stddev:23.09 (13.0%) stddev-sans-first:23.53 17:54:48 INFO - PROCESS | 1665 | Values: 206.0 244.0 201.0 174.0 173.0 176.0 175.0 177.0 177.0 174.0 17:54:48 INFO - PROCESS | 1665 | 17:54:48 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.70 Median:86.50 stddev:13.89 (16.1%) stddev-sans-first:13.35 17:54:48 INFO - PROCESS | 1665 | Values: 60.0 91.0 89.0 70.0 65.0 60.0 89.0 65.0 84.0 94.0 17:54:48 INFO - PROCESS | 1665 | -------- Summary: end -------- 17:54:48 INFO - PROCESS | 1665 | 17:54:49 INFO - TEST-INFO | 1665: exit 0 17:54:49 INFO - TEST-OK | kraken | took 60682ms 17:54:49 INFO - SUITE-END | took 1174s 17:54:49 INFO - Completed test suite (00:19:34) 17:54:49 INFO - javascript benchmark 17:54:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7266.29638598269, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3024.880794106181, 3361.2101156228287, 3372.359251742169, 3383.3571621391948, 3427.8628854845815, 5550.222968784374, 5574.0802767543355, 5584.385717571074, 5623.465770751783, 5747.0, 5587.189857470665, 5625.52183064439, 5640.576956728251, 5710.343310519292, 5752.050911599592, 5092.134337162673, 5117.437081920985, 5217.191335343028, 5225.268462415265, 5293.02079115363, 5307.310049693534, 5309.0, 5316.122839731459, 5336.226247194155, 5359.330083739533, 4097.528784189826, 5212.4266330703595, 5225.608079394023, 5227.1113910635095, 5278.973605131969, 4207.863876753259, 5026.673266237683, 5128.871778205575, 5177.171652535614, 5249.580033597341, 5126.6923984561045, 5128.153854614022, 5145.15105007677, 5161.896762064737, 5169.379674439091, 5506.366767821712, 5721.6853073080965, 5763.0, 5766.740496677659, 5792.160136780143, 6051.092336149569, 6080.878382432326, 6101.217066730177, 6110.572259941806, 6115.940154142385, 6181.938180618226, 6188.783392581283, 6191.87616247677, 6198.0, 6207.193064901579, 5744.569157313218, 5752.252207213078, 5752.856178595569, 5757.337906140806, 5794.884102317972, 6129.267100814801, 6151.9692401538605, 6160.568760186834, 6185.567010309235, 6279.367364485267, 5108.540231379194, 6142.078688196806, 6156.753729850756, 6169.475594574422, 6176.969115154486, 5607.495325420732, 6252.5935814171935, 6257.9687101564205, 6258.749650013949, 6261.8747625047245, 6001.789937352171, 6064.666443345658, 6109.816705498753, 6131.009681329197, 6131.651934473353, 5985.16207730918, 6210.068489726486, 6219.222597175353, 6225.470834979077, 6244.969580019246, 4039.2931237033404, 4177.749335039907, 4690.9765451172525, 4924.1875090610365, 4960.851174464772, 3486.093615659947, 3627.6009638939695, 4240.575942405796, 4339.7396156230725, 4430.113977204507, 5581.441855814467, 5591.944080559142, 5604.747786349624, 5623.662580245198, 5627.43725627434, 5112.693238405707, 6175.660338681416, 6205.069239614004, 6217.253929528486, 6230.190075290274, 5111.3355263815465, 5266.131088370376, 5541.2796336476, 5617.8595535111135, 5621.5221706155435, 5394.78420863161, 5445.264889239902, 5453.072977593819, 5475.151351540518, 5485.4788795064405, 6252.468540174136, 6275.0, 6288.056791481223, 6305.148804911279, 6340.809775706736, 6109.131392048963, 6175.166352542349, 6275.748970041147, 6278.937210628018, 6294.370562943669, 6184.81445556634, 6231.875362492634, 6276.6547839869245, 6313.684315784284, 6335.683215839282, 5921.526277897672, 5968.641881486951, 5995.1906492624685, 6007.88854062, 6057.545684073801, 5178.689278643294, 5690.146478028329, 6036.245469316335, 6222.315545289924, 6252.968735156477, 4752.382190315375, 5783.7397317119985, 5799.710014499343, 5848.093545500455, 5852.473277405054, 5797.507211887037, 5856.560757943086, 5868.559858010581, 5926.4666180044005, 5940.376260492586, 5393.164059570773, 5601.131824567198, 5639.774409023593, 5655.981923253854, 5710.65736055838, 5738.1679656450415, 5758.452946970031, 5822.679752613647, 5840.948629246776, 5851.531877449709, 5162.767675454538, 5420.7560659769615, 5540.1135818270495, 5625.662460252398, 5631.465010823965], "value": 5548.839648006866, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.7265558631581, 488.2846629687509, 489.5031542983872, 524.9081909627974, 546.6173678425101, 35856.46215306769, 39812.0, 39877.60122398775, 39893.202135957334, 40001.59998400015, 40895.38656920141, 41333.173336533255, 41387.793061034725, 41412.0, 41544.58455415445, 8217.260446559809, 8896.421732587361, 9668.661596844095, 9761.316707830454, 9858.16205622523, 8167.95916020422, 9592.280578956606, 9679.70960871175, 9702.417854928692, 9702.563384647709, 9609.279304052188, 9656.951715241414, 9691.90308096917, 9749.927507974153, 9750.0, 9632.181264592518, 9707.271954603397, 9716.0, 9727.367721098106, 9728.41629502225, 8730.956345218297, 9631.518424078839, 9690.56392462341, 9709.757256068655, 9723.222142228604, 39264.21471570581, 39491.01272468182, 39571.80214098911, 39788.0, 39827.8008609958, 3255.641879393265, 3258.7230085442766, 3402.1494626343415, 3473.7915725056323, 3534.32847758925, 30255.394892102035, 30299.091027268998, 30441.23896902573, 30864.38271234563, 30880.38239235225, 3184.6178458584886, 3190.2502911815704, 3192.6488086310487, 3192.776505644606, 3303.4549299365576, 493973.0122939554, 501081.0, 502081.0, 502907.0, 503461.0, 51735.706607335116, 56299.71850140805, 56844.14733778997, 56871.431285686616, 56893.86212275731, 33146.0, 34150.48774268388, 58382.0, 58458.0, 58494.4150558489, 15866.52400427989, 16315.42895998658, 16317.939333943255, 16388.508344749676, 16393.83606163947, 12627.179233349987, 12703.301318427575, 12716.936415317865, 12718.236905785683, 12719.982401407866, 18623.906880465784, 18639.720404193948, 18687.43937681872, 18720.719189212174, 18743.343982960803, 7891.0, 8002.879956800536, 8057.274845263954, 8080.0, 8132.430729848972, 852.2542775071812, 938.957746901391, 982.6118683120128, 995.64654547636, 1045.3884477580548, 29041.967062305437, 29378.21505268915, 29424.705752942624, 29954.101376958726, 30222.0, 835.4486039214089, 1019.1999280564721, 1085.9837102443469, 1103.2994048779083, 1116.9500669370766, 14186.787198192036, 15197.164155971306, 15377.692446151013, 15420.383184672488, 15577.065376077471, 679747.0, 685516.0, 685979.0, 686400.0, 688036.5981700895, 52687.736561316946, 53845.73077134667, 54124.0, 54550.0, 54824.72587637116, 1309.941052652614, 1339.6316013096477, 1363.400103954257, 1365.4196966289287, 1368.7330970460864, 28171.71828281773, 29113.98101066537, 29243.41513169767, 29458.263543411587, 29775.55336669995, 47660.76169619129, 47804.28293575669, 48058.03883922372, 48083.0, 48201.75899120482, 472560.0, 473057.0, 473493.0, 474986.0, 475534.0, 25054.123105691215, 25306.240812775646, 25624.487510249317, 25724.842382092476, 25737.227883163534, 44726.776366119266, 45066.0, 45232.64302070943, 45956.0, 46475.535244648476, 37130.0, 37397.81301093477, 37921.2415751689, 38008.865186482595, 38042.80978595089, 37074.25851483009, 37122.44316335203, 37610.0, 38245.92450396685, 38328.808355959154, 29800.850995744884, 31614.36771264608, 36014.27971440504, 36373.27253454969, 36731.81634091919, 30330.84834575813, 31316.686833132288, 33329.83335083309, 34011.48982765211, 34524.82737586296, 24060.0, 24432.877835610707, 24712.50574988454, 25044.248672539852, 25123.623145652466], "value": 19412.877939494636, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.313069256529, 372.47481051717074, 379.9981000095, 385.50077649443966, 396.96427321541057, 16250.918745406287, 19088.522786930316, 20572.69140962885, 20671.689924651142, 20754.0, 20573.588528229462, 20587.897060514675, 20851.374458766262, 20876.37370878876, 21015.369538913856, 5097.464766199551, 5992.19105420768, 6183.041628547582, 6224.595401298924, 6229.0, 5359.330083739533, 6105.145279660851, 6170.352113028135, 6216.347283535231, 6228.906566401508, 5999.940000599981, 6016.639001659914, 6047.3650266722025, 6080.543959203077, 6103.694815259241, 5901.616394934315, 6000.2199714037115, 6031.547633927473, 6050.818475445766, 6069.119977603267, 5991.880162396727, 6012.819615411545, 6071.908921366183, 6114.449699527019, 6120.173776540155, 12905.161164524276, 15767.605809854846, 25073.87463062673, 25082.623760643604, 25120.246392608253, 2178.9564208715738, 2185.7049298344677, 2227.4208705736464, 2358.4575547624145, 2967.9018763057593, 9669.081437263449, 9886.110250077456, 10027.247956403298, 10042.949785251027, 10315.43265120418, 1831.8076601956736, 1865.3005123078844, 1875.40001091502, 1912.6748452763063, 1940.5827747034314, 86022.56988715079, 109583.0, 120213.79786202114, 122456.0, 123439.3828030854, 10995.0, 11364.318140911406, 11447.19869609136, 11519.596814111466, 11697.134202120365, 14373.56879293602, 14399.208043557705, 14406.279686015658, 14422.990390672552, 14431.783523247159, 8225.670973161126, 8477.618507167192, 8512.957435212784, 8562.78593035179, 8621.870671939925, 8417.789555261168, 8419.200175983271, 8432.957835210786, 8574.142585741376, 8618.913810861812, 4383.802728877208, 4446.377507149002, 4454.153710794939, 4524.366588677588, 4548.613367863769, 1887.8017808130223, 1916.9712454312917, 1918.7601549806275, 1928.2961718972754, 1972.7632684078003, 823.6664151018728, 854.3336197765752, 936.9812603747887, 941.5198248893114, 957.8027465667915, 15105.352650471632, 21075.894620527106, 21097.050632721257, 21155.788442115383, 21222.363329100153, 896.8834051573253, 934.3039435620507, 945.1540870920625, 976.7411635916487, 990.2968892086699, 10588.629397971034, 11058.776964179631, 11099.500522476346, 11105.0, 11148.0, 487029.0, 587717.0, 600978.0, 603108.0, 610841.0, 70804.64597677081, 77859.61070194613, 89792.0, 110483.0, 128101.0, 953.8378475659016, 986.1716158426956, 997.8652881860855, 999.8600195972424, 1002.1081237698454, 16650.75023874667, 19594.412167634993, 19896.60206795885, 20007.299744508873, 20124.29564965219, 43134.0, 49415.50584494109, 51121.744391279295, 51259.71850703613, 57233.42766572281, 74722.25277747153, 77168.6141569311, 77480.61259693665, 77690.22309776829, 77826.0, 7659.004329437137, 7762.146163922077, 7910.406719496176, 8172.83654326922, 8233.876491852747, 12164.695882603006, 12934.482620695444, 12986.220826750425, 13183.736325273248, 13256.469741210243, 11106.777864442827, 11213.887861121611, 11335.943320283346, 11514.82727759101, 11596.594119205787, 8910.910890891268, 9007.819843603222, 9928.503574821374, 11202.495887685236, 11395.829062563902, 9454.716358509255, 11361.602343917923, 11524.539018439169, 11619.709507262387, 11625.5349786011, 6525.575837570543, 7907.051153861574, 7945.927299814683, 8002.7199048033035, 8100.83798324042, 10271.691849244535, 10528.947355263175, 10911.781764364827, 11011.119110470983, 11148.219624626523], "value": 9400.810387817668, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.156961114498, 1519.2555647732615, 1613.887027908046, 1761.2778760708104, 1878.3238034307656, 7923.088844782852, 8162.102168761431, 8481.406301558893, 9072.183503484694, 9090.681826136086, 8477.91522084779, 8756.69351572695, 8764.342674299438, 8771.736847894574, 8887.866681999774, 2392.533455976081, 2406.181898154627, 2415.335782659765, 2422.9151979680714, 2424.3696638873844, 2373.762623737621, 2401.891914863835, 2425.684660994077, 2436.3543660929868, 2437.585610446228, 2400.8919598618018, 2404.3868813452546, 2404.483036147228, 2405.0500052479347, 2423.2851308863837, 2102.0, 2328.4178955261186, 2342.7891489765916, 2359.1271229645054, 2368.0764501844296, 2377.7859992600575, 2380.1312520929737, 2387.510560335127, 2391.7727815857465, 2394.0, 8806.471611703255, 8965.820683586357, 8985.326100542485, 8998.325125615605, 9016.36885418021, 5278.683279003234, 5439.211314359435, 5439.972800136014, 5449.373322067973, 5450.618456708032, 3594.4788005739283, 3607.0261029522144, 3653.671169594723, 3691.53855768029, 3704.759190652599, 934.3973137326386, 965.4545181972163, 1070.1118072000222, 1073.7798751255973, 1080.222239987208, 9743.707688769347, 11655.708607284801, 11702.765944681143, 11800.88199118015, 11832.88167118335, 4070.715049946475, 4601.010782681739, 4755.619550435957, 4825.348577942003, 4858.975705121523, 8941.776455588531, 9463.810723785486, 10118.443485608215, 10123.03831136041, 10124.949375253076, 5402.567794576503, 5415.025295446779, 5422.566194704415, 5428.267183930119, 5447.264619276347, 4480.775961201927, 4589.151007063704, 4807.663463557587, 4908.705477671351, 4980.352554167935, 3561.3589553880033, 3902.785346805896, 3939.2515422069714, 3958.524977002778, 4059.330210515232, 1304.1197191895433, 1312.5143696832042, 1312.527490103562, 1322.2463195978391, 1327.6747196936813, 793.5794029164553, 826.3719573124348, 870.0038455968008, 885.8848349714625, 950.2160717408166, 5639.351474580394, 5666.858328541819, 5977.491913187428, 6059.787907423219, 6271.96864015677, 430.9956900430956, 434.4851351148836, 445.69469913109623, 448.12839028090053, 448.39018934249395, 3977.76133431995, 3979.343408337592, 4050.3721923101966, 4079.755214687128, 4100.487439070116, 9036.502992335354, 10621.734456638645, 10836.67489975302, 10860.72848178786, 10949.83575246372, 10684.145268378512, 10861.619843305447, 10919.017288444078, 10921.508532116073, 10927.508262128224, 8240.258376746122, 8264.504129752235, 8303.418760686694, 8313.709020184264, 8330.166983301742, 5915.70421478933, 5952.732127054206, 6096.542759292982, 6113.327533971171, 6123.357047509948, 9786.85319720219, 9926.652567160114, 10097.5456104474, 10133.290669653348, 10260.281780275607, 10093.041161089684, 10273.178145748472, 10403.56682294856, 10405.531751071068, 10471.214658900766, 6880.415164711056, 7544.358729507834, 7584.051993500812, 7612.352949999312, 7715.035620547432, 8483.0, 8503.574821258788, 8560.015598206286, 8560.743177704679, 8565.48607083577, 7501.174870764321, 7605.543667379975, 7627.656755446129, 7661.310482056641, 7772.572508512086, 7329.670164842488, 7490.887636685563, 7493.925060749323, 7497.962510187414, 7645.388368930583, 6884.449244060564, 7010.649467526501, 7014.088168538057, 7019.122609673791, 7021.648917554204, 4952.950470495393, 5058.371589995113, 5118.539331460186, 5130.435652078194, 5174.4566820484815, 4975.0, 4991.276264941491, 5133.383993920754, 5165.560927321219, 5207.71357575337], "value": 4527.101350543459, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [273.4012512597412, 290.64105829300826, 301.38968588608077, 308.2232773411004, 311.82381954195876, 4308.870733877989, 4631.907361852761, 5228.320318358613, 5470.206820011096, 5651.265335506384, 5310.946890531093, 5473.343198816147, 5485.424030476803, 5535.25274087998, 5563.554915606752, 3718.7768733876055, 4216.5572614875455, 4246.80889359978, 4269.252880745873, 4285.292926667096, 3091.6290045194496, 4133.359329303963, 4191.874243772691, 4248.707251879802, 4298.333758267458, 4104.583918548096, 4109.691773117013, 4135.73117747347, 4136.420901073852, 4180.728252663583, 4123.505179378464, 4138.668906487474, 4161.898241388109, 4164.3961625564425, 4200.600942910419, 3569.7679650822874, 3602.477640742104, 3606.9179246226026, 3607.6392360763975, 3608.855645774166, 4369.759663218521, 5220.399654039758, 5278.313819203479, 5296.708681022542, 5305.442928492491, 4675.340254209775, 4695.826043489128, 4733.431988161408, 4739.691920025188, 4753.595944344735, 5060.823845746364, 5348.491893270133, 5802.651840889518, 5817.080363480041, 5852.385499522531, 3623.365910965597, 3845.8463260574167, 3884.495015647976, 3891.7859517726515, 3893.357595996657, 5321.707306098163, 6059.030555111205, 6169.969150154266, 6181.300142460815, 6238.064290356392, 4002.199560087994, 4002.9799851000557, 4003.3594624860466, 4008.5590585036207, 4011.29802284599, 3353.6814002669707, 3867.671247943956, 4005.7997100144876, 4051.4327994080854, 4066.6340029397497, 3956.911849241424, 3978.522577290744, 4765.571098601074, 4781.784819683123, 4823.541763532458, 5201.817936372285, 5960.791372301948, 5964.3617393389995, 5979.431953964367, 6027.57806953509, 1199.3643369014437, 1264.2035517623838, 1297.4356155072574, 1374.2853716067789, 1376.348381941674, 724.8803947348733, 729.4857125726351, 808.5391326943703, 829.8506268871661, 841.9494830310201, 3834.559025712079, 3965.6034396560112, 4072.5609086096297, 4470.463544374631, 4626.149540183953, 4369.75966321849, 4843.370361853007, 5108.055009823194, 5158.948410515847, 5180.818671346485, 3750.774953502799, 3877.0695033192396, 4203.117345357448, 4668.766561671903, 4721.6458765592715, 2615.365396627108, 2665.9333516662236, 2756.28336632477, 2841.602175695404, 3029.636443626735, 5407.891842163135, 6109.236345456818, 6358.904616430757, 6366.853966286113, 6422.871542569216, 5098.337216161875, 5281.39263984639, 5803.303603567599, 5981.37195594465, 6205.503559715212, 4992.226204938241, 5196.922046169386, 5746.994276001683, 6048.939510604926, 6311.463525600284, 5123.769430375568, 5372.113601255905, 6130.754769809158, 6183.721732522137, 6219.689015549295, 5611.681254905013, 5951.494122999594, 6023.608465449732, 6070.332263451105, 6081.543884208613, 5882.3529411764775, 6371.617702937839, 6426.228852537539, 6448.322926092693, 6449.7097630605795, 3342.7158691511495, 3790.5451345838674, 3937.192875460581, 3945.349017412095, 3963.30642137619, 5945.227120474311, 6016.0, 6030.336662966983, 6048.546359023003, 6075.1446819994535, 5484.917726234189, 5655.688937108499, 5735.71321433935, 5749.568782341257, 5779.370386474228, 5276.920846187233, 5778.393268706725, 5837.970810145922, 5851.180834683233, 5860.2967643883, 5078.263651770399, 5293.708846013506, 5521.641093328921, 5683.914741278967, 5704.914426283692, 5791.710414479175, 5802.303723553201, 5823.970880145572, 5853.268341457318, 5880.529557635465, 4688.671792974459, 4771.332013518179, 5269.604779641466, 5697.487226149667, 5755.503569071988], "value": 4122.131951569863, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.717712914834, 705.8588282343533, 764.3312101910828, 772.3357912195507, 811.6307080278474, 12145.271283722983, 14962.259435141215, 15732.37070517178, 15732.764008539882, 15773.290201940912, 14110.788338174936, 14196.077254978445, 14196.29018549071, 14234.217118058503, 14234.857651423483, 667.1293961380396, 684.8869936460478, 829.1294141151767, 836.0928392693935, 848.6096395658004, 703.5989485992986, 826.1531929771971, 843.1399972028527, 843.439112989861, 846.5005646668465, 886.0519244408486, 892.2371372476537, 896.274018045382, 897.4166791585488, 897.4794619120927, 884.5444596033049, 886.0165216609558, 888.9066648001964, 889.2707979456848, 890.1054440287502, 650.8307839961597, 660.4815220052236, 661.4377778887955, 661.7121552124842, 662.6421732264571, 14646.194459304734, 14809.851901480955, 14824.703505929821, 14851.851481485262, 14871.47949821751, 11465.770684586261, 14466.0, 14473.27633618315, 14521.564353069425, 14599.416023359054, 11636.709082272926, 11790.587329443513, 11841.645798206171, 11884.940575297154, 11916.880831191664, 3760.548734151892, 3791.0699241786124, 3801.486799282117, 3828.444875493066, 3963.5243770747693, 120394.79605203924, 123517.0, 124135.37932310281, 124286.0, 124731.37634311861, 11151.776964460665, 11257.662270131908, 11284.887151128383, 11492.712682183012, 11668.76662466746, 23162.53674926492, 23228.187013454786, 23251.88374058119, 23298.06807727672, 23316.53366932686, 7747.418943579254, 7892.329152022027, 8046.959765201136, 8148.796280093045, 8157.2250636189465, 4220.366944958281, 4358.128374325148, 4408.25059739845, 4502.212112880233, 4573.6569757268335, 3423.4864770284657, 3569.8750543730844, 3571.357155711945, 3573.8034408107283, 3576.2668652926086, 639.2137670665107, 647.1069923505532, 656.9113169722026, 657.5857209957696, 665.3712241931329, 430.6361124849497, 494.5203152941641, 513.997430012855, 578.0548802707603, 583.5360888093976, 7753.340966017839, 8885.0, 8997.055309192572, 9126.36115471924, 9204.493752843659, 529.4414392815617, 573.6873403994833, 613.4448324266546, 636.780310792785, 684.9623270720059, 4205.810738516774, 4430.82276708928, 4655.627549796008, 4720.527947205252, 5095.439501654815, 108272.45863770788, 134402.0, 140485.29757351353, 148172.0, 166705.49941750884, 116368.41815791036, 121938.0, 148777.0, 149080.25459872847, 152526.0, 94726.26368681538, 105657.99999999846, 106311.0, 112611.43694281476, 114685.0, 9991.750206244902, 10152.644657436955, 10228.0, 10265.384076955406, 10274.383536987805, 61204.69397653161, 63149.684251578445, 65035.67482162559, 65588.67205664131, 67037.32962670311, 76364.85452718103, 97925.51037245053, 99840.0, 99886.0, 102370.0, 12727.681807954876, 12849.357532123544, 12978.351082445652, 13439.126456780277, 13725.519606813714, 14575.0, 14588.708225835637, 14593.85406145925, 14662.78005829892, 15055.096694198382, 13451.865481345061, 13568.79646805279, 13756.518521851687, 13959.092658977133, 14160.93792965511, 13443.932228339008, 13467.932660336635, 13643.727125457634, 13699.041067125605, 13877.514287000311, 12958.352082395653, 13328.333583320602, 13357.200911488786, 13446.327683615584, 13736.862631373559, 10862.782744344911, 11022.559097636005, 11359.943200283946, 11399.0, 11636.883631163919, 9113.498757568132, 9602.759931001781, 11130.777384452427, 11272.830907536558, 11352.716182095513], "value": 7788.14242328674, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1595.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 112.0, 116.0, 115.0, 116.0, 101.0, 117.0, 117.0, 112.0, 117.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 168.0, 162.0, 112.0, 131.0, 130.0, 114.0, 163.0, 111.0], "value": 135.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 203.0, 206.0, 235.0, 199.0, 199.0, 203.0, 202.0, 198.0, 206.0], "value": 205.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 82.0, 76.0, 152.0, 61.0, 59.0, 60.0, 60.0, 136.0, 63.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 121.0, 71.0, 75.0, 83.0, 119.0, 95.0, 104.0, 145.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 133.0, 110.0, 88.0, 110.0, 84.0, 139.0, 132.0, 90.0, 87.0], "value": 106.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 99.0, 105.0, 97.0, 173.0, 142.0, 102.0, 101.0, 100.0, 101.0], "value": 112.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 139.0, 142.0, 91.0, 119.0, 145.0, 110.0, 92.0, 91.0, 89.0], "value": 109.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 59.0, 59.0, 58.0, 59.0, 57.0, 59.0, 58.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 78.0, 81.0, 79.0, 73.0, 70.0, 70.0, 72.0, 71.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 98.0, 112.0, 82.0, 93.0, 82.0, 107.0, 89.0, 84.0, 85.0], "value": 92.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 140.0, 140.0, 137.0, 141.0, 138.0, 136.0, 147.0, 190.0, 162.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 244.0, 201.0, 174.0, 173.0, 176.0, 175.0, 177.0, 177.0, 174.0], "value": 187.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 91.0, 89.0, 70.0, 65.0, 60.0, 89.0, 65.0, 84.0, 94.0], "value": 76.7, "unit": "score"}]}]} 17:54:49 INFO - Return code: 0 17:54:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:54:49 ERROR - # TBPL SUCCESS # 17:54:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:54:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:54:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:54:49 INFO - 2016-09-09 17:54:49.748 screenresolution[1670:10731] starting screenresolution argv=screenresolution get 17:54:49 INFO - 2016-09-09 17:54:49.750 screenresolution[1670:10731] Display 0: 1600x1200x32@60 17:54:49 INFO - 2016-09-09 17:54:49.756 screenresolution[1671:10734] starting screenresolution argv=screenresolution list 17:54:49 INFO - Available Modes on Display 0 17:54:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:54:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:54:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:54:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:54:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:54:49 INFO - Intel Iris: 17:54:49 INFO - Chipset Model: Intel Iris 17:54:49 INFO - Type: GPU 17:54:49 INFO - Bus: Built-In 17:54:49 INFO - VRAM (Dynamic, Max): 1536 MB 17:54:49 INFO - Vendor: Intel (0x8086) 17:54:49 INFO - Device ID: 0x0a2e 17:54:49 INFO - Revision ID: 0x0009 17:54:49 INFO - Displays: 17:54:49 INFO - Display: 17:54:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:54:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:54:49 INFO - Display Serial Number: 1600x1200 60 17:54:49 INFO - Main Display: Yes 17:54:49 INFO - Mirror: Off 17:54:49 INFO - Online: Yes 17:54:49 INFO - Rotation: Supported 17:54:49 INFO - Return code: 0 17:54:49 INFO - Running post-action listener: _resource_record_post_action 17:54:49 INFO - [mozharness: 2016-09-10 00:54:49.992658Z] Finished run-tests step (success) 17:54:49 INFO - Running post-run listener: _resource_record_post_run 17:54:50 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 38122496; Write bytes: 708637696; Read time: 412; Write time: 2015 17:54:50 INFO - TinderboxPrint: CPU usage
54.3% 17:54:50 INFO - TinderboxPrint: I/O read bytes / time
38,122,496 / 412 17:54:50 INFO - TinderboxPrint: I/O write bytes / time
708,637,696 / 2,015 17:54:50 INFO - TinderboxPrint: CPU idle
2,212.9 (45.7%) 17:54:50 INFO - TinderboxPrint: CPU system
973.9 (20.1%) 17:54:50 INFO - TinderboxPrint: CPU user
1,654.9 (34.2%) 17:54:50 INFO - TinderboxPrint: Swap in / out
443,543,552 / 0 17:54:50 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 411150336; Write bytes: 408954880; Read time: 31197; Write time: 765 17:54:50 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 29174784; Write bytes: 298204160; Read time: 298; Write time: 1244 17:54:50 INFO - Running post-run listener: _upload_blobber_files 17:54:50 INFO - Blob upload gear active. 17:54:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:54:50 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 17:54:50 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'] 17:54:50 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 17:54:50 INFO - (blobuploader) - INFO - Open directory for files ... 17:54:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:54:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:54:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:54:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:54:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:54:52 INFO - (blobuploader) - INFO - Done attempting. 17:54:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:54:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:54:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:54:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:54:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:54:53 INFO - (blobuploader) - INFO - Done attempting. 17:54:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:54:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:54:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:54:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:54:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:54:55 INFO - (blobuploader) - INFO - Done attempting. 17:54:55 INFO - (blobuploader) - INFO - Iteration through files over. 17:54:55 INFO - Return code: 0 17:54:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:54:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:54:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3517f2e4dccceab9f5ab382a1f7ff17656b8e982cb44dc992cbd0154fee4c2d298f693a1e87f42c678df141bdfb0175092a9a525ff5f58cef94988378ff7087f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dfd98fe3f0e1d4cf16f145d48dbf1127e71ac9f5cb23c86737d4ae80266d634d0632c4cd0eef8247e1b07db07662ad11ae3b6804dd1a87268c19d5c00400f852", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9f0a9c493750be74f5af8ee1d33e367ce55a79bb2b51677ee642178b2c935d55b8e7a961e639cffe4007e57d9d51424ab8d8fc0c1fb3ebfee6f553a25c300b2f"} 17:54:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:54:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:54:55 INFO - Contents: 17:54:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3517f2e4dccceab9f5ab382a1f7ff17656b8e982cb44dc992cbd0154fee4c2d298f693a1e87f42c678df141bdfb0175092a9a525ff5f58cef94988378ff7087f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dfd98fe3f0e1d4cf16f145d48dbf1127e71ac9f5cb23c86737d4ae80266d634d0632c4cd0eef8247e1b07db07662ad11ae3b6804dd1a87268c19d5c00400f852", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9f0a9c493750be74f5af8ee1d33e367ce55a79bb2b51677ee642178b2c935d55b8e7a961e639cffe4007e57d9d51424ab8d8fc0c1fb3ebfee6f553a25c300b2f"} 17:54:55 INFO - Running post-run listener: copy_logs_to_upload_dir 17:54:55 INFO - Copying logs to upload dir... 17:54:55 INFO - mkdir: /builds/slave/test/build/upload/logs 17:54:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.461644 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-09 17:54:55.408249) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 17:54:55.411902) ========= 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.MyPNeIzKBC/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.Rbkx34R72M/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3517f2e4dccceab9f5ab382a1f7ff17656b8e982cb44dc992cbd0154fee4c2d298f693a1e87f42c678df141bdfb0175092a9a525ff5f58cef94988378ff7087f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dfd98fe3f0e1d4cf16f145d48dbf1127e71ac9f5cb23c86737d4ae80266d634d0632c4cd0eef8247e1b07db07662ad11ae3b6804dd1a87268c19d5c00400f852", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9f0a9c493750be74f5af8ee1d33e367ce55a79bb2b51677ee642178b2c935d55b8e7a961e639cffe4007e57d9d51424ab8d8fc0c1fb3ebfee6f553a25c300b2f"} build_url:https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010976 build_url: 'https://queue.taskcluster.net/v1/task/Xgkmu95wTp-EgisCFkGetw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3517f2e4dccceab9f5ab382a1f7ff17656b8e982cb44dc992cbd0154fee4c2d298f693a1e87f42c678df141bdfb0175092a9a525ff5f58cef94988378ff7087f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dfd98fe3f0e1d4cf16f145d48dbf1127e71ac9f5cb23c86737d4ae80266d634d0632c4cd0eef8247e1b07db07662ad11ae3b6804dd1a87268c19d5c00400f852", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9f0a9c493750be74f5af8ee1d33e367ce55a79bb2b51677ee642178b2c935d55b8e7a961e639cffe4007e57d9d51424ab8d8fc0c1fb3ebfee6f553a25c300b2f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 17:54:55.444860) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 17:54:55.445181) ========= 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.MyPNeIzKBC/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.Rbkx34R72M/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.004972 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 17:54:55.493376) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 17:54:55.493705) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 17:54:55.494019) ========= ========= Total master_lag: 1.45 =========