builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0317 starttime: 1464140772.98 results: success (0) buildid: 20160524172038 builduid: 9fc0ea4b20ba4ca598a90d24ce8c98d6 revision: 14079be66b19b1fa8c60fa34239bcff57ca51ab0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:12.982057) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:12.982797) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:12.983335) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:13.101486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:13.101940) ========= 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.P8DsNyBSAD/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.HdXcDGfoaq/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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-05-24 18:46:13-- 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% 55.9M=0s 2016-05-24 18:46:13 (55.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.422047 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:13.548792) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:13.549160) ========= 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.P8DsNyBSAD/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.HdXcDGfoaq/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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.025765 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:13.597716) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 18:46:13.601205) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 14079be66b19b1fa8c60fa34239bcff57ca51ab0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 14079be66b19b1fa8c60fa34239bcff57ca51ab0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P8DsNyBSAD/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.HdXcDGfoaq/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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-05-24 18:46:13,683 truncating revision to first 12 chars 2016-05-24 18:46:13,683 Setting DEBUG logging. 2016-05-24 18:46:13,683 attempt 1/10 2016-05-24 18:46:13,683 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/14079be66b19?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 18:46:15,411 unpacking tar archive at: mozilla-aurora-14079be66b19/testing/mozharness/ program finished with exit code 0 elapsedTime=1.973223 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 18:46:15.597004) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:15.597583) ========= script_repo_revision: 14079be66b19b1fa8c60fa34239bcff57ca51ab0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:15.598341) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:15.598742) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 18:46:15.622568) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 26 secs) (at 2016-05-24 18:46:15.622963) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P8DsNyBSAD/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.HdXcDGfoaq/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:46:15 INFO - MultiFileLogger online at 20160524 18:46:15 in /builds/slave/test 18:46:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:46:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:46:15 INFO - {'append_to_log': False, 18:46:15 INFO - 'base_work_dir': '/builds/slave/test', 18:46:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:46:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:46:15 INFO - 'branch': 'Mozilla-Aurora', 18:46:15 INFO - 'buildbot_json_path': 'buildprops.json', 18:46:15 INFO - 'config_files': ('talos/mac_config.py',), 18:46:15 INFO - 'default_actions': ('clobber', 18:46:15 INFO - 'read-buildbot-config', 18:46:15 INFO - 'download-and-extract', 18:46:15 INFO - 'populate-webroot', 18:46:15 INFO - 'create-virtualenv', 18:46:15 INFO - 'install', 18:46:15 INFO - 'run-tests'), 18:46:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:46:15 INFO - 'download_minidump_stackwalk': True, 18:46:15 INFO - 'download_symbols': 'ondemand', 18:46:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:46:15 INFO - 'tooltool.py': '/tools/tooltool.py', 18:46:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:46:15 INFO - '/tools/misc-python/virtualenv.py')}, 18:46:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:46:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:46:15 INFO - 'installer_path': 'installer.exe', 18:46:15 INFO - 'log_level': 'info', 18:46:15 INFO - 'log_name': 'talos', 18:46:15 INFO - 'log_to_console': True, 18:46:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:46:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:46:15 INFO - 'opt_config_files': (), 18:46:15 INFO - 'pip_index': False, 18:46:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:46:15 INFO - 'cmd': ('bash', 18:46:15 INFO - '-c', 18:46:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:46:15 INFO - 'enabled': True, 18:46:15 INFO - 'halt_on_failure': False, 18:46:15 INFO - 'name': 'check_screen_resolution'},), 18:46:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:46:15 INFO - 'cmd': ('bash', 18:46:15 INFO - '-c', 18:46:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:46:15 INFO - 'enabled': True, 18:46:15 INFO - 'halt_on_failure': False, 18:46:15 INFO - 'name': 'check_screen_resolution'},), 18:46:15 INFO - 'run_cmd_checks_enabled': True, 18:46:15 INFO - 'sps_profile': False, 18:46:15 INFO - 'sps_profile_interval': 0, 18:46:15 INFO - 'suite': 'dromaeojs', 18:46:15 INFO - 'system_bits': '32', 18:46:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:46:15 INFO - 'title': 't-yosemite-r7-0317', 18:46:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:46:15 INFO - 'use_talos_json': True, 18:46:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:46:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:46:15 INFO - 'work_dir': 'build'} 18:46:15 INFO - ##### 18:46:15 INFO - ##### Running clobber step. 18:46:15 INFO - ##### 18:46:15 INFO - Running pre-action listener: _resource_record_pre_action 18:46:15 INFO - Running main action method: clobber 18:46:15 INFO - rmtree: /builds/slave/test/build 18:46:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:46:17 INFO - Running post-action listener: _resource_record_post_action 18:46:17 INFO - ##### 18:46:17 INFO - ##### Running read-buildbot-config step. 18:46:17 INFO - ##### 18:46:17 INFO - Running pre-action listener: _resource_record_pre_action 18:46:17 INFO - Running main action method: read_buildbot_config 18:46:17 INFO - Using buildbot properties: 18:46:17 INFO - { 18:46:17 INFO - "project": "", 18:46:17 INFO - "product": "firefox", 18:46:17 INFO - "script_repo_revision": "production", 18:46:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 18:46:17 INFO - "repository": "", 18:46:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 18:46:17 INFO - "buildid": "20160524172038", 18:46:17 INFO - "pgo_build": "False", 18:46:17 INFO - "basedir": "/builds/slave/test", 18:46:17 INFO - "buildnumber": 357, 18:46:17 INFO - "slavename": "t-yosemite-r7-0317", 18:46:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:46:17 INFO - "platform": "yosemite_r7", 18:46:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:46:17 INFO - "branch": "mozilla-aurora", 18:46:17 INFO - "repo_path": "releases/mozilla-aurora", 18:46:17 INFO - "revision": "14079be66b19b1fa8c60fa34239bcff57ca51ab0", 18:46:17 INFO - "stage_platform": "macosx64", 18:46:17 INFO - "builduid": "9fc0ea4b20ba4ca598a90d24ce8c98d6", 18:46:17 INFO - "slavebuilddir": "test" 18:46:17 INFO - } 18:46:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 18:46:17 INFO - Running post-action listener: _resource_record_post_action 18:46:17 INFO - ##### 18:46:17 INFO - ##### Running download-and-extract step. 18:46:17 INFO - ##### 18:46:17 INFO - Running pre-action listener: _resource_record_pre_action 18:46:17 INFO - Running main action method: download_and_extract 18:46:17 INFO - mkdir: /builds/slave/test/build/tests 18:46:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:46:17 INFO - https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:46:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:46:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:46:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 18:46:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 18:46:48 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 18:46:48 INFO - retry: attempt #1 caught exception: timed out 18:46:48 INFO - retry: Failed, sleeping 30 seconds before retrying 18:47:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #2 18:47:28 INFO - Downloaded 1199 bytes. 18:47:28 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 18:47:28 INFO - Using the following test package requirements: 18:47:28 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:47:28 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:47:28 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 18:47:28 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:47:28 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 18:47:28 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:47:28 INFO - u'jsshell-mac.zip'], 18:47:28 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:47:28 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 18:47:28 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:47:28 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:47:28 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 18:47:28 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:47:28 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 18:47:28 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:47:28 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 18:47:28 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:47:28 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 18:47:28 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:47:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:28 INFO - https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:47:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:47:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:47:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:47:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:47:42 INFO - Downloaded 18425352 bytes. 18:47:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:47:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:47:45 INFO - Return code: 0 18:47:45 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:45 INFO - https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:47:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:47:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:47:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:47:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:48:15 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip: timed out 18:48:15 INFO - retry: attempt #1 caught exception: timed out 18:48:15 INFO - retry: Failed, sleeping 30 seconds before retrying 18:48:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #2 18:48:48 INFO - Downloaded 18425352 bytes. 18:48:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:48:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:48:51 INFO - Return code: 0 18:48:51 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:48:51 INFO - https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:48:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:48:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:48:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:48:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:48:52 INFO - Downloaded 10840145 bytes. 18:48:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:48:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:48:52 INFO - Return code: 0 18:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:48:52 INFO - https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 18:48:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:48:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:48:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 18:48:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 18:48:55 INFO - Downloaded 114137712 bytes. 18:48:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:48:55 INFO - mkdir: /builds/slave/test/properties 18:48:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:48:55 INFO - Writing to file /builds/slave/test/properties/build_url 18:48:55 INFO - Contents: 18:48:55 INFO - build_url:https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:48:56 INFO - Running post-action listener: _resource_record_post_action 18:48:56 INFO - Running post-action listener: set_extra_try_arguments 18:48:56 INFO - ##### 18:48:56 INFO - ##### Running populate-webroot step. 18:48:56 INFO - ##### 18:48:56 INFO - Running pre-action listener: _resource_record_pre_action 18:48:56 INFO - Running main action method: populate_webroot 18:48:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:48:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:48:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:48:56 INFO - '--tppagecycles', 18:48:56 INFO - '7'], 18:48:56 INFO - 'tests': ['tsvgm']}}, 18:48:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:48:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:48:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:48:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:48:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:48:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:48:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:56 INFO - 'talos_options': ['--disable-e10s'], 18:48:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:48:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:48:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:56 INFO - 'talos_options': ['--disable-e10s'], 18:48:56 INFO - 'tests': ['damp', 'tps']}, 18:48:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:56 INFO - 'tests': ['damp', 'tps']}, 18:48:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:48:56 INFO - 'tests': ['dromaeo_dom']}, 18:48:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:48:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:48:56 INFO - 'tests': ['a11yr', 18:48:56 INFO - 'ts_paint', 18:48:56 INFO - 'tpaint', 18:48:56 INFO - 'sessionrestore', 18:48:56 INFO - 'sessionrestore_no_auto_restore', 18:48:56 INFO - 'tabpaint']}, 18:48:56 INFO - 'other-e10s': {'tests': ['a11yr', 18:48:56 INFO - 'ts_paint', 18:48:56 INFO - 'tpaint', 18:48:56 INFO - 'sessionrestore', 18:48:56 INFO - 'sessionrestore_no_auto_restore', 18:48:56 INFO - 'tabpaint']}, 18:48:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:48:56 INFO - 'ts_paint', 18:48:56 INFO - 'tpaint', 18:48:56 INFO - 'sessionrestore', 18:48:56 INFO - 'sessionrestore_no_auto_restore', 18:48:56 INFO - 'tabpaint']}, 18:48:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:48:56 INFO - 'ts_paint', 18:48:56 INFO - 'tpaint', 18:48:56 INFO - 'sessionrestore', 18:48:56 INFO - 'sessionrestore_no_auto_restore', 18:48:56 INFO - 'tabpaint']}, 18:48:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:48:56 INFO - 'tests': ['a11yr', 18:48:56 INFO - 'ts_paint', 18:48:56 INFO - 'tpaint', 18:48:56 INFO - 'sessionrestore', 18:48:56 INFO - 'sessionrestore_no_auto_restore', 18:48:56 INFO - 'tabpaint']}, 18:48:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:48:56 INFO - 'tests': ['a11yr', 18:48:56 INFO - 'ts_paint', 18:48:56 INFO - 'tpaint', 18:48:56 INFO - 'sessionrestore', 18:48:56 INFO - 'sessionrestore_no_auto_restore', 18:48:56 INFO - 'tabpaint']}, 18:48:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:48:56 INFO - 'tests': ['tsvgx', 18:48:56 INFO - 'tsvgr_opacity', 18:48:56 INFO - 'tart', 18:48:56 INFO - 'tscrollx', 18:48:56 INFO - 'cart']}, 18:48:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:48:56 INFO - 'tsvgr_opacity', 18:48:56 INFO - 'tart', 18:48:56 INFO - 'tscrollx', 18:48:56 INFO - 'cart']}, 18:48:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:56 INFO - 'talos_options': ['--disable-e10s'], 18:48:56 INFO - 'tests': ['tp5o']}, 18:48:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:56 INFO - 'tests': ['tp5o']}, 18:48:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:56 INFO - 'talos_options': ['--disable-e10s', 18:48:56 INFO - '--xperf_path', 18:48:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:48:56 INFO - 'tests': ['tp5n']}, 18:48:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:56 INFO - 'talos_options': ['--xperf_path', 18:48:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:48:56 INFO - 'tests': ['tp5n']}}, 18:48:56 INFO - 'talos.zip': {'path': '', 18:48:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:48:56 INFO - Running post-action listener: _resource_record_post_action 18:48:56 INFO - ##### 18:48:56 INFO - ##### Running create-virtualenv step. 18:48:56 INFO - ##### 18:48:56 INFO - Running pre-action listener: _resource_record_pre_action 18:48:56 INFO - Running main action method: create_virtualenv 18:48:56 INFO - Creating virtualenv /builds/slave/test/build/venv 18:48:56 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 18:48:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:48:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:48:57 INFO - Using real prefix '/tools/python27' 18:48:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:48:57 INFO - Installing distribute.............................................................................................................................................................................................done. 18:49:00 INFO - Installing pip.................done. 18:49:00 INFO - Return code: 0 18:49:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:49:00 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:00 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:00 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 0x10ee27a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c42590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc6e00>, '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 0x10eb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc1b20>, '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.P8DsNyBSAD/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.HdXcDGfoaq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:49:00 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 18:49:00 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 18:49:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P8DsNyBSAD/Render', 18:49:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:49:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:49:00 INFO - 'HOME': '/Users/cltbld', 18:49:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:49:00 INFO - 'LOGNAME': 'cltbld', 18:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:00 INFO - 'MOZ_NO_REMOTE': '1', 18:49:00 INFO - 'NO_EM_RESTART': '1', 18:49:00 INFO - 'PAGER': '/bin/cat', 18:49:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:00 INFO - 'PWD': '/builds/slave/test', 18:49:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:49:00 INFO - 'SHELL': '/bin/bash', 18:49:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdXcDGfoaq/Listeners', 18:49:00 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:49:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:49:00 INFO - 'USER': 'cltbld', 18:49:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:49:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:00 INFO - 'XPC_FLAGS': '0x0', 18:49:00 INFO - 'XPC_SERVICE_NAME': '0', 18:49:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:00 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 18:49:00 INFO - Cleaning up... 18:49:00 INFO - Return code: 0 18:49:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:49:00 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:00 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:00 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee27a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c42590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc6e00>, '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 0x10eb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc1b20>, '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.P8DsNyBSAD/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.HdXcDGfoaq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:49:00 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>=0.7.1'] in /builds/slave/test/build 18:49:00 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>=0.7.1 18:49:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P8DsNyBSAD/Render', 18:49:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:49:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:49:00 INFO - 'HOME': '/Users/cltbld', 18:49:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:49:00 INFO - 'LOGNAME': 'cltbld', 18:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:00 INFO - 'MOZ_NO_REMOTE': '1', 18:49:00 INFO - 'NO_EM_RESTART': '1', 18:49:00 INFO - 'PAGER': '/bin/cat', 18:49:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:00 INFO - 'PWD': '/builds/slave/test', 18:49:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:49:00 INFO - 'SHELL': '/bin/bash', 18:49:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdXcDGfoaq/Listeners', 18:49:00 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:49:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:49:00 INFO - 'USER': 'cltbld', 18:49:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:49:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:00 INFO - 'XPC_FLAGS': '0x0', 18:49:00 INFO - 'XPC_SERVICE_NAME': '0', 18:49:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:49:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:01 INFO - Downloading/unpacking psutil>=0.7.1 18:49:01 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 18:49:01 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 18:49:01 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 18:49:01 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 18:49:01 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 18:49:01 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 18:49:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:49:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:49:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:49:03 INFO - Installing collected packages: psutil 18:49:03 INFO - Running setup.py install for psutil 18:49:03 INFO - building 'psutil._psutil_osx' extension 18:49:03 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 18:49:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:49:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:49:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:49:04 INFO - building 'psutil._psutil_posix' extension 18:49:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:49:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:49:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:49:04 INFO - ^ 18:49:04 INFO - 1 warning generated. 18:49:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:49:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:49:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:49:04 INFO - Successfully installed psutil 18:49:04 INFO - Cleaning up... 18:49:04 INFO - Return code: 0 18:49:04 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:49: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee27a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c42590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc6e00>, '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 0x10eb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc1b20>, '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.P8DsNyBSAD/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.HdXcDGfoaq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:49:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:49:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P8DsNyBSAD/Render', 18:49:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:49:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:49:04 INFO - 'HOME': '/Users/cltbld', 18:49:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:49:04 INFO - 'LOGNAME': 'cltbld', 18:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:04 INFO - 'MOZ_NO_REMOTE': '1', 18:49:04 INFO - 'NO_EM_RESTART': '1', 18:49:04 INFO - 'PAGER': '/bin/cat', 18:49:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:04 INFO - 'PWD': '/builds/slave/test', 18:49:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:49:04 INFO - 'SHELL': '/bin/bash', 18:49:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdXcDGfoaq/Listeners', 18:49:04 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:49:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:49:04 INFO - 'USER': 'cltbld', 18:49:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:49:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:04 INFO - 'XPC_FLAGS': '0x0', 18:49:04 INFO - 'XPC_SERVICE_NAME': '0', 18:49:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:49:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:49:07 INFO - Installing collected packages: mozsystemmonitor 18:49:07 INFO - Running setup.py install for mozsystemmonitor 18:49:07 INFO - Successfully installed mozsystemmonitor 18:49:07 INFO - Cleaning up... 18:49:07 INFO - Return code: 0 18:49:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ee27a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c42590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc6e00>, '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 0x10eb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc1b20>, '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.P8DsNyBSAD/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.HdXcDGfoaq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:49:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P8DsNyBSAD/Render', 18:49:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:49:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:49:07 INFO - 'HOME': '/Users/cltbld', 18:49:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:49:07 INFO - 'LOGNAME': 'cltbld', 18:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:07 INFO - 'MOZ_NO_REMOTE': '1', 18:49:07 INFO - 'NO_EM_RESTART': '1', 18:49:07 INFO - 'PAGER': '/bin/cat', 18:49:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:07 INFO - 'PWD': '/builds/slave/test', 18:49:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:49:07 INFO - 'SHELL': '/bin/bash', 18:49:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdXcDGfoaq/Listeners', 18:49:07 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:49:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:49:07 INFO - 'USER': 'cltbld', 18:49:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:49:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:07 INFO - 'XPC_FLAGS': '0x0', 18:49:07 INFO - 'XPC_SERVICE_NAME': '0', 18:49:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:08 INFO - Downloading/unpacking blobuploader==1.2.4 18:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:10 INFO - Downloading blobuploader-1.2.4.tar.gz 18:49:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:49:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:49:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:49: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 18:49: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 18:49: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 18:49: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 18:49: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 18:49: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 18:49:11 INFO - Downloading docopt-0.6.1.tar.gz 18:49:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:49:12 INFO - Installing collected packages: blobuploader, requests, docopt 18:49:12 INFO - Running setup.py install for blobuploader 18:49:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:49:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:49:12 INFO - Running setup.py install for requests 18:49:12 INFO - Running setup.py install for docopt 18:49:12 INFO - Successfully installed blobuploader requests docopt 18:49:12 INFO - Cleaning up... 18:49:12 INFO - Return code: 0 18:49:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:12 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 0x10ee27a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c42590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc6e00>, '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 0x10eb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc1b20>, '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.P8DsNyBSAD/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.HdXcDGfoaq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:49:12 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 18:49:12 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 18:49:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P8DsNyBSAD/Render', 18:49:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:49:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:49:12 INFO - 'HOME': '/Users/cltbld', 18:49:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:49:12 INFO - 'LOGNAME': 'cltbld', 18:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:12 INFO - 'MOZ_NO_REMOTE': '1', 18:49:12 INFO - 'NO_EM_RESTART': '1', 18:49:12 INFO - 'PAGER': '/bin/cat', 18:49:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:12 INFO - 'PWD': '/builds/slave/test', 18:49:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:49:12 INFO - 'SHELL': '/bin/bash', 18:49:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdXcDGfoaq/Listeners', 18:49:12 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:49:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:49:12 INFO - 'USER': 'cltbld', 18:49:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:49:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:12 INFO - 'XPC_FLAGS': '0x0', 18:49:12 INFO - 'XPC_SERVICE_NAME': '0', 18:49:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:49:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:49:13 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-7MPUrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:49:13 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-EX4n1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:49:13 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-ZR59SO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:49:13 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-RNOqLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:49:13 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-hvHlev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:49:13 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-TIRJyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:49:13 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-8_kilT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:49:13 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-0tNVTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:49:13 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-ipPc_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:49:14 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-eLdP3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:49:14 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-8bpj31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:49:14 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-I7Iz7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:49:14 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-12QqNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:49:14 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-twaKmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:49:14 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-uwFGCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:49:14 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-N2Z0vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:49:14 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-3lVqtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:49:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:49:14 INFO - Running setup.py install for manifestparser 18:49:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:49:15 INFO - Running setup.py install for mozcrash 18:49:15 INFO - Running setup.py install for mozdebug 18:49:15 INFO - Running setup.py install for mozdevice 18:49:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:49:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:49:15 INFO - Running setup.py install for mozfile 18:49:15 INFO - Running setup.py install for mozhttpd 18:49:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:49:15 INFO - Running setup.py install for mozinfo 18:49:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:49:15 INFO - Running setup.py install for mozInstall 18:49:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:49:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:49:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:49:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:49:16 INFO - Running setup.py install for mozleak 18:49:16 INFO - Running setup.py install for mozlog 18:49:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:49:16 INFO - Running setup.py install for moznetwork 18:49:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:49:16 INFO - Running setup.py install for mozprocess 18:49:16 INFO - Running setup.py install for mozprofile 18:49:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:49:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:49:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:49:16 INFO - Running setup.py install for mozrunner 18:49:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:49:17 INFO - Running setup.py install for mozscreenshot 18:49:17 INFO - Running setup.py install for moztest 18:49:17 INFO - Running setup.py install for mozversion 18:49:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:49:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:49:17 INFO - Cleaning up... 18:49:17 INFO - Return code: 0 18:49:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:49:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:17 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 0x10ee27a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c42590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc6e00>, '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 0x10eb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc1b20>, '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.P8DsNyBSAD/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.HdXcDGfoaq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:49:17 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 18:49:17 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 18:49:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P8DsNyBSAD/Render', 18:49:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:49:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:49:17 INFO - 'HOME': '/Users/cltbld', 18:49:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:49:17 INFO - 'LOGNAME': 'cltbld', 18:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:17 INFO - 'MOZ_NO_REMOTE': '1', 18:49:17 INFO - 'NO_EM_RESTART': '1', 18:49:17 INFO - 'PAGER': '/bin/cat', 18:49:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:17 INFO - 'PWD': '/builds/slave/test', 18:49:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:49:17 INFO - 'SHELL': '/bin/bash', 18:49:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdXcDGfoaq/Listeners', 18:49:17 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:49:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:49:17 INFO - 'USER': 'cltbld', 18:49:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:49:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:17 INFO - 'XPC_FLAGS': '0x0', 18:49:17 INFO - 'XPC_SERVICE_NAME': '0', 18:49:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:49:17 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-q4zDWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:49:17 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)) 18:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:49:17 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-lVigun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:49:17 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)) 18:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:49:17 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-vVGMC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:49:18 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)) 18:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:49:18 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-Ct_JER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:49:18 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)) 18:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:49:18 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-WvNVJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:49:18 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)) 18:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:49:18 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-8TO8Nd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:49:18 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)) 18:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:49:18 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-zFRAwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:49:18 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)) 18:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:49:18 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-vpVb01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:49:18 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)) 18:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:49:18 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-PillW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:49:18 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)) 18:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:49:18 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-uY9hZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 18:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:49:18 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-sQzPv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:49:18 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)) 18:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:49:18 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-nV0Nxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 18:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:49:18 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-lNaI3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:49:19 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)) 18:49:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:49:19 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-xnHVjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:49:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 18:49:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:49:19 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-XXLhn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:49:19 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)) 18:49:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:49:19 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-LwREMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:49:19 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)) 18:49:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:49:19 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-bIyU9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:49:19 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)) 18:49:19 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)) 18:49:19 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)) 18:49:19 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)) 18:49:19 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)) 18:49:19 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)) 18:49:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:49: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 18:49: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 18:49: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 18:49: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 18:49: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 18:49: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 18:49:21 INFO - Downloading blessings-1.6.tar.gz 18:49:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:49:22 INFO - Installing collected packages: blessings 18:49:22 INFO - Running setup.py install for blessings 18:49:22 INFO - Successfully installed blessings 18:49:22 INFO - Cleaning up... 18:49:22 INFO - Return code: 0 18:49:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:49:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:49:22 INFO - Reading from file tmpfile_stdout 18:49:22 INFO - Current package versions: 18:49:22 INFO - blessings == 1.6 18:49:22 INFO - blobuploader == 1.2.4 18:49:22 INFO - docopt == 0.6.1 18:49:22 INFO - manifestparser == 1.1 18:49:22 INFO - mozInstall == 1.12 18:49:22 INFO - mozcrash == 0.17 18:49:22 INFO - mozdebug == 0.1 18:49:22 INFO - mozdevice == 0.48 18:49:22 INFO - mozfile == 1.2 18:49:22 INFO - mozhttpd == 0.7 18:49:22 INFO - mozinfo == 0.9 18:49:22 INFO - mozleak == 0.1 18:49:22 INFO - mozlog == 3.1 18:49:22 INFO - moznetwork == 0.27 18:49:22 INFO - mozprocess == 0.22 18:49:22 INFO - mozprofile == 0.28 18:49:22 INFO - mozrunner == 6.11 18:49:22 INFO - mozscreenshot == 0.1 18:49:22 INFO - mozsystemmonitor == 0.0 18:49:22 INFO - moztest == 0.7 18:49:22 INFO - mozversion == 1.4 18:49:22 INFO - psutil == 3.1.1 18:49:22 INFO - requests == 1.2.3 18:49:22 INFO - wsgiref == 0.1.2 18:49:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:49: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:22 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 0x10ee27a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c42590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc6e00>, '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 0x10eb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc1b20>, '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.P8DsNyBSAD/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.HdXcDGfoaq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:49:22 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 18:49:22 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 18:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P8DsNyBSAD/Render', 18:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:49:22 INFO - 'HOME': '/Users/cltbld', 18:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:49:22 INFO - 'LOGNAME': 'cltbld', 18:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:22 INFO - 'MOZ_NO_REMOTE': '1', 18:49:22 INFO - 'NO_EM_RESTART': '1', 18:49:22 INFO - 'PAGER': '/bin/cat', 18:49:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:22 INFO - 'PWD': '/builds/slave/test', 18:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:49:22 INFO - 'SHELL': '/bin/bash', 18:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdXcDGfoaq/Listeners', 18:49:22 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:49:22 INFO - 'USER': 'cltbld', 18:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:22 INFO - 'XPC_FLAGS': '0x0', 18:49:22 INFO - 'XPC_SERVICE_NAME': '0', 18:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:49:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:22 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)) 18:49:22 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)) 18:49:22 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)) 18:49:22 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)) 18:49:22 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)) 18:49:22 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)) 18:49:22 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)) 18:49:22 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)) 18:49:22 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)) 18:49:22 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)) 18:49:22 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)) 18:49:22 INFO - Cleaning up... 18:49:22 INFO - Return code: 0 18:49:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:49: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee27a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c42590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc6e00>, '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 0x10eb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc1b20>, '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.P8DsNyBSAD/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.HdXcDGfoaq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:49: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', 'jsonschema'] in /builds/slave/test/build 18:49: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 jsonschema 18:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P8DsNyBSAD/Render', 18:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:49:22 INFO - 'HOME': '/Users/cltbld', 18:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:49:22 INFO - 'LOGNAME': 'cltbld', 18:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:22 INFO - 'MOZ_NO_REMOTE': '1', 18:49:22 INFO - 'NO_EM_RESTART': '1', 18:49:22 INFO - 'PAGER': '/bin/cat', 18:49:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:22 INFO - 'PWD': '/builds/slave/test', 18:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:49:22 INFO - 'SHELL': '/bin/bash', 18:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdXcDGfoaq/Listeners', 18:49:22 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:49:22 INFO - 'USER': 'cltbld', 18:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:22 INFO - 'XPC_FLAGS': '0x0', 18:49:22 INFO - 'XPC_SERVICE_NAME': '0', 18:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:49:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:23 INFO - Downloading/unpacking jsonschema 18:49: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 18:49: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 18:49: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 18:49: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 18:49: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 18:49: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 18:49:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:49:25 INFO - Downloading/unpacking functools32 (from jsonschema) 18:49: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 18:49: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 18:49: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 18:49: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 18:49: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 18:49: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 18:49:25 INFO - Downloading functools32-3.2.3-2.tar.gz 18:49:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:49:26 INFO - warning: no files found matching '*.txt' 18:49:26 INFO - no previously-included directories found matching 'build' 18:49:26 INFO - no previously-included directories found matching 'dist' 18:49:26 INFO - no previously-included directories found matching '.git*' 18:49:26 INFO - Installing collected packages: jsonschema, functools32 18:49:26 INFO - Running setup.py install for functools32 18:49:26 INFO - warning: no files found matching '*.txt' 18:49:26 INFO - no previously-included directories found matching 'build' 18:49:26 INFO - no previously-included directories found matching 'dist' 18:49:26 INFO - no previously-included directories found matching '.git*' 18:49:26 INFO - Successfully installed jsonschema functools32 18:49:26 INFO - Cleaning up... 18:49:26 INFO - Return code: 0 18:49:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:49:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee27a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe349c42590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc6e00>, '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 0x10eb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc1b20>, '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.P8DsNyBSAD/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.HdXcDGfoaq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 18:49:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:49:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:49:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P8DsNyBSAD/Render', 18:49:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:49:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:49:26 INFO - 'HOME': '/Users/cltbld', 18:49:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:49:26 INFO - 'LOGNAME': 'cltbld', 18:49:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:26 INFO - 'MOZ_NO_REMOTE': '1', 18:49:26 INFO - 'NO_EM_RESTART': '1', 18:49:26 INFO - 'PAGER': '/bin/cat', 18:49:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:49:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:26 INFO - 'PWD': '/builds/slave/test', 18:49:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:49:26 INFO - 'SHELL': '/bin/bash', 18:49:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdXcDGfoaq/Listeners', 18:49:26 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:49:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:49:26 INFO - 'USER': 'cltbld', 18:49:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:49:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:49:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:26 INFO - 'XPC_FLAGS': '0x0', 18:49:26 INFO - 'XPC_SERVICE_NAME': '0', 18:49:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:49:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:26 INFO - Downloading/unpacking flake8 18:49: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 18:49: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 18:49: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 18:49: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 18:49: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 18:49: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 18:49:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:49:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:49:29 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 18:49:29 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 18:49:29 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 18:49:29 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 18:49:29 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 18:49:29 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 18:49:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:49:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:49:29 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 18:49:29 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 18:49:29 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 18:49:29 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 18:49:29 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 18:49:29 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 18:49:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:49:29 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 18:49:29 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 18:49:29 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 18:49:29 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 18:49:29 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 18:49:29 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 18:49:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:49:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:49:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:49:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:49:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:49:30 INFO - no previously-included directories found matching 'docs/_build' 18:49:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:49:30 INFO - Running setup.py install for pep8 18:49:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:49:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:49:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:49:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:49:30 INFO - no previously-included directories found matching 'docs/_build' 18:49:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:49:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:49:30 INFO - Cleaning up... 18:49:30 INFO - Return code: 0 18:49:30 INFO - Running post-action listener: _resource_record_post_action 18:49:30 INFO - Running post-action listener: _start_resource_monitoring 18:49:30 INFO - Starting resource monitoring. 18:49:30 INFO - ##### 18:49:30 INFO - ##### Running install step. 18:49:30 INFO - ##### 18:49:30 INFO - Running pre-action listener: _resource_record_pre_action 18:49:30 INFO - Running main action method: install 18:49:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:49:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:49:30 INFO - Reading from file tmpfile_stdout 18:49:30 INFO - Detecting whether we're running mozinstall >=1.0... 18:49:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:49:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:49:31 INFO - Reading from file tmpfile_stdout 18:49:31 INFO - Output received: 18:49:31 INFO - Usage: mozinstall [options] installer 18:49:31 INFO - Options: 18:49:31 INFO - -h, --help show this help message and exit 18:49:31 INFO - -d DEST, --destination=DEST 18:49:31 INFO - Directory to install application into. [default: 18:49:31 INFO - "/builds/slave/test"] 18:49:31 INFO - --app=APP Application being installed. [default: firefox] 18:49:31 INFO - mkdir: /builds/slave/test/build/application 18:49:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:49:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:50:02 INFO - Reading from file tmpfile_stdout 18:50:02 INFO - Output received: 18:50:02 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:50:02 INFO - Running post-action listener: _resource_record_post_action 18:50:02 INFO - ##### 18:50:02 INFO - ##### Running run-tests step. 18:50:02 INFO - ##### 18:50:02 INFO - Running pre-action listener: _resource_record_pre_action 18:50:02 INFO - Running main action method: run_tests 18:50:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:50:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:50:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:50:02 INFO - 2016-05-24 18:50:02.856 screenresolution[1652:8090] starting screenresolution argv=screenresolution get 18:50:02 INFO - 2016-05-24 18:50:02.859 screenresolution[1652:8090] Display 0: 1600x1200x32@60 18:50:02 INFO - 2016-05-24 18:50:02.864 screenresolution[1653:8092] starting screenresolution argv=screenresolution list 18:50:02 INFO - Available Modes on Display 0 18:50:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:50:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:50:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:50:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:50:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:50:03 INFO - Intel Iris: 18:50:03 INFO - Chipset Model: Intel Iris 18:50:03 INFO - Type: GPU 18:50:03 INFO - Bus: Built-In 18:50:03 INFO - VRAM (Dynamic, Max): 1536 MB 18:50:03 INFO - Vendor: Intel (0x8086) 18:50:03 INFO - Device ID: 0x0a2e 18:50:03 INFO - Revision ID: 0x0009 18:50:03 INFO - Displays: 18:50:03 INFO - Display: 18:50:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:50:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:50:03 INFO - Display Serial Number: 1600x1200 60 18:50:03 INFO - Main Display: Yes 18:50:03 INFO - Mirror: Off 18:50:03 INFO - Online: Yes 18:50:03 INFO - Rotation: Supported 18:50:03 INFO - Return code: 0 18:50:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:50:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:50:03 INFO - Python 2.7.3 18:50:03 INFO - Return code: 0 18:50:03 INFO - grabbing minidump binary from tooltool 18:50:03 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:03 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc6e00>, '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 0x10eb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ecc1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:50:03 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 18:50:03 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 18:50:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:50:03 INFO - Return code: 0 18:50:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:50:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:50:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:50:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:50:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:50:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:50:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:50:04 INFO - Return code: 0 18:50:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0317', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0317 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:50:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P8DsNyBSAD/Render', 18:50:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:04 INFO - 'HOME': '/Users/cltbld', 18:50:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:04 INFO - 'LOGNAME': 'cltbld', 18:50:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:50:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:04 INFO - 'MOZ_NO_REMOTE': '1', 18:50:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:50:04 INFO - 'NO_EM_RESTART': '1', 18:50:04 INFO - 'PAGER': '/bin/cat', 18:50:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:04 INFO - 'PWD': '/builds/slave/test', 18:50:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:50:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:04 INFO - 'SHELL': '/bin/bash', 18:50:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdXcDGfoaq/Listeners', 18:50:04 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 18:50:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:04 INFO - 'USER': 'cltbld', 18:50:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:04 INFO - 'XPC_FLAGS': '0x0', 18:50:04 INFO - 'XPC_SERVICE_NAME': '0', 18:50:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0317', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:50:04 INFO - mozversion application_buildid: 20160524172038 18:50:04 INFO - mozversion application_changeset: 14079be66b19b1fa8c60fa34239bcff57ca51ab0 18:50:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:50:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:50:04 INFO - mozversion application_name: Firefox 18:50:04 INFO - mozversion application_remotingname: firefox-dev 18:50:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:50:04 INFO - mozversion application_vendor: Mozilla 18:50:04 INFO - mozversion application_version: 48.0a2 18:50:04 INFO - mozversion platform_buildid: 20160524172038 18:50:04 INFO - mozversion platform_changeset: 14079be66b19b1fa8c60fa34239bcff57ca51ab0 18:50:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:50:04 INFO - mozversion platform_version: 48.0a2 18:50:04 INFO - using testdate: 1464141004 18:50:04 INFO - actual date: 1464141004 18:50:04 INFO - starting webserver on 'localhost:49279' 18:50:04 INFO - SUITE-START | Running 2 tests 18:50:04 INFO - TEST-START | dromaeo_css 18:50:04 INFO - operating with platform_type : mac_ 18:50:04 INFO - Initialising browser for dromaeo_css test... 18:50:04 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpAAXjW4/profile http://localhost:49279/getInfo.html) 18:50:06 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 18:50:06 INFO - PROCESS | 1671 | colorDepth:24 18:50:06 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 18:50:06 INFO - PROCESS | 1671 | __metrics 18:50:06 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:50:06 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:50:07 INFO - TEST-INFO | 1671: exit 0 18:50:07 INFO - Browser initialized. 18:50:07 INFO - Running cycle 1/1 for dromaeo_css test... 18:50:07 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpAAXjW4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:50:19 INFO - PROCESS | 1674 | RSS: Main: 211181568 18:50:19 INFO - PROCESS | 1674 | 18:51:10 INFO - PROCESS | 1674 | 1464141070754 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] 18:53:20 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 18:53:22 INFO - PROCESS | 1674 | RSS: Main: 373493760 18:53:22 INFO - PROCESS | 1674 | 18:56:26 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 18:56:26 INFO - PROCESS | 1674 | RSS: Main: 357580800 18:56:26 INFO - PROCESS | 1674 | 18:59:28 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 18:59:29 INFO - PROCESS | 1674 | RSS: Main: 360280064 18:59:29 INFO - PROCESS | 1674 | 19:02:31 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 19:02:32 INFO - PROCESS | 1674 | RSS: Main: 358866944 19:02:32 INFO - PROCESS | 1674 | 19:05:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 19:05:35 INFO - PROCESS | 1674 | RSS: Main: 329850880 19:05:35 INFO - PROCESS | 1674 | 19:08:38 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 19:08:38 INFO - PROCESS | 1674 | RSS: Main: 366104576 19:08:38 INFO - PROCESS | 1674 | 19:08:38 INFO - PROCESS | 1674 | __start_tp_report 19:08:38 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 19:08:38 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 19:08:38 INFO - PROCESS | 1674 | |i|pagename|runs| 19:08:38 INFO - PROCESS | 1674 | |0;dojo.html;3344.4481660526017;3404;3409.3351796399706;3413.4367829308167;3469.3234819210247;5546.722663866805;5560.916586251209;5568.665880047195;5586.41342659021;5619.157126431032;4648.604868586174;5570.6657600543995;5626;5641.971790141043;5664.660120392779;5042.84871453857;5087.49125087492;5127.846164615058;5159.613029022838;5197.922031169535;5113.284140220372;5189.455107213745;5200.531952124308;5205.635605507616;5220.164773636219;5135.51212634801;5175.8188463403785;5186.7147306898105;5190.143626301656;5198.246254293125;5164.147915593941;5200.089984252779;5206.921896171561;5214.895702085975;5264.2893209416625;5029.899402011976;5040.47075057121;5126.410462796789;5131.61512886535;5138.845834624967;5440.319960004999;5469.56243500519;5480.780768769245;5496;5542.75057622408;6105.938940610581;6108.725107370179;6134.447899689005;6140.109684095781;6141.170941922828;4995.750212489397;6017.097435384683;6105.847353816146;6193.225846769154;6194.81415557534;5676.659400435987;5693.516051135617;5705.372409035002;5722.227499287585;5743.827685169494;6353.5870168438905;6358.7456501740335;6374.298827129012;6376.776812811529;6377.681115944184;5815.505682017076;5917.349091600006;5950.9702451487465;6039.879202416015;6059.818205453844;6109.938900610937;6149.815505534841;6182.938170618237;6187.226596675416;6192.318844927055;6108.969455152695;6129.1419201311855;6130.570860039755;6132.478739307119;6133.662648554372;5854.4730974212525;6027.849303767441;6036.909446358308;6138.877222455526;6168.599041062316;4814.229723244264;4830.589399900978;4844.733539655283;4852.490488498727;4853.514648535118;4273.63674087706;4363.585459381354;4388.100439409914;4420.160169567772;4444.755538445416;5496.697681627549;5520.944790552124;5530.695811730313;5532.031894418541;5555.972220138954;6064.272287325549;6163.691815409212;6233.158523599256;6235.657038862815;6235.688215589294;5438.700871452108;5470.152126420411;5473.452654734574;5475.835724928259;5479.479449452296;5196.1166601677805;5263.894722105537;5265.6840589564745;5278.8944221116135;5280.894382112337;6316.210473690789;6334.239891213084;6346.174997250328;6353.936460635427;6355.237371515355;6196.411340922605;6223.471004964629;6232.5637205397;6237.970734828702;6249.437550620466;6236.095766113979;6241.875162496815;6330.430261276507;6338.683065846597;6340.904886426615;5164.8192313268855;6106.6946652668075;6118.388161183846;6137.232845894263;6143.385661433999;6177.2587289525545;6193.473554747897;6201.317855036033;6211.130441738251;6219.844503887439;5771.509421699035;5863.296404431325;5869.794557190648;5942.049272116614;5962.4633782959745;5848.619839710405;5876.823695289148;5886.234789477512;5899.91150132757;5902.2622172228475;5595.972020139873;5731.914021289767;5739.512141467855;5748.281464816898;5769.192313076257;5869.7945571904775;5871.6770577618645;5872.060470324727;5889.617174883619;5919.881602368015;5784.913226301524;5800.100984347433;5824.301083869793;5835.270591176103;5847.590668653153 19:08:38 INFO - PROCESS | 1674 | |1;ext.html;528.888933324002;541.4179756761482;582.9972447390488;586.2525280267658;607.7994261893575;31010;31715.52426713598;31738.523922141187;38485;39043.63347282846;42261.57738422615;42558.36162457566;42659.78670106653;42749.786251068705;42833.57166428335;9464.668736594238;9788.755281117958;9799.314048016642;9803.362781419184;9811.558479868387;8554.70058547951;8768.912310876905;9559.61761529538;11446;11656.708582285426;9790.118889299962;11391.316521008725;11403.14476414268;11479.827802582968;11496.770064598702;11348;11384.943075284653;11408.885911140907;11421.828672569918;11436.485358158903;8551.82896342076;11458.885411145866;11715;11719.765604687858;11728.17902746808;38590.61409385898;38993;39060;39101;39127.804360978014;3211.5022171563446;3264.7388208943466;3265.297960938386;3287.7862938908893;3293;27122.32194195161;27802.86098569494;28271.717282827114;28272.575911361346;28324.85837570799;2756.2144788735313;2767.1836808141647;2820.055281480686;2822.1251412062325;2847.6440444944383;482007;482743;482745;483819;484346;36859.63140368562;64950;65345;65650;66107.33892661011;35977.64022359743;36012.63987360145;36071;36082;36130.81934590363;15760.684786304439;15919.840801592067;15929.522114336589;15929.761053584205;15975;12567.994560435143;12703.174293671067;13314;13412.93293533526;13435.261060641758;8203.220694034057;8209.630566624517;8239.217274358927;8264.338852891875;8298.751037468886;3347.497875318673;3354.5974483062187;3365.175531994628;3368.915777105543;3410.7100896423585;986.7779749556292;1023.7850051489122;1026.3636545341935;1044.9059584637419;1120.7534342444649;28954.420911581652;29411.558826617616;29832.85083574568;30218.84890575533;30992.845035774677;971.8056388722142;1065.7016035510069;1107.0147568663895;1176.1120354132747;1194.964151075469;15374.1544215067;15473;15565.443455654344;15725.842741572438;15844.920775396049;605095;611704;613042;613473;614524;60879;62636.68681656654;62781;62816.05775913365;62931.68534157392;1189.79178643737;1399.0626280392162;1452.0924422236103;1529.258309719785;1541.6685412636336;26381.868090659424;26803.463930721668;29099.563506546994;29229.561556576242;29960.550591740706;48199;48725;48737.75631121822;48790.268145978545;48808.535743927736;320524;328849;328954;329144;329643;24251.757482425655;27279.318017049733;27324;27882.721172788013;28171.295717606405;43010.56989430066;43273.3508997359;43320.13359732851;43660;44417.777911110235;36219.63780362163;36642.81678591697;37596.812015939744;37885.62114378821;38617;37051;37125.814370927976;37224.255514890094;37956.24087518179;38153.236935261695;36562.8171859139;37258.62741372552;38209.808950956176;38338.6166138335;39938;30923.69076309208;31706.682933171294;33876.322473550885;34772.47841282332;34893.825530872185;23983.880080599487;24356.52623622523;24410.755892440848;24848.751512485367;25146.967975927262 19:08:38 INFO - PROCESS | 1674 | |2;jquery.html;397.8070635741665;399.42881679198746;403.4513062235361;403.73555321264547;411.755005771566;12291.815622765644;15302.005369650964;20336;21093.472663183427;21105.20605748513;19952.40142795715;20114.295999640017;20804.791952080475;21825;22079;5148.974255128738;5753.309602847654;5785.681787501686;5963.194968679216;5967.373425790294;5880.911786323209;5889.263842019747;5939.227900372945;5941.732622032019;5942.346341902387;5641.097424412095;5890.676012819294;5895;5901.4098590141075;5905.881882362351;5906.3207731110615;5925.466707996279;5934.1692163097205;5937.465628093449;5947;5133.0503856786245;5209.400918894337;5210.114280572312;5215.191645294985;5216.921746173811;26707.599386009224;26760.330991725168;27114.457710845672;27239.046633367736;27253.591196132074;2211.303439416579;2273.1362082408577;2291.129370839087;2409.4699166183414;2496.488219914913;12388.81416778749;14486.20325882076;15009.949303548756;15025.774613380809;15371.385144594204;1633.8121116071686;1712.8372804583544;1747.4932269641788;1781.9554511137196;1855.2208072609537;75980;106114.46942765237;112817;115918.42040789826;116690;11606.013488853374;11939.223950443367;11977.52089916411;11985.940070299594;12027.518899244107;15386.461473848585;15441.768373474408;15463.768043479131;15471.5358539244;15478.690426191413;9080.8637870432;9112.726618201465;9149.496777677292;9160.8625870612;9233.169014788567;9119.361644684941;9141.725748227564;9142.771430714152;9186;9230.030846761128;4765.428148622187;4831.806727730851;4835.903281934412;4841.927371089366;4877.268409738567;1726.3181043488003;1786.7587875636623;1898.9810101899081;1933.6326098041327;1978.6537355962648;842.5450256861226;904.7285814255697;917.206616276929;938.7090002099235;965.8309795785709;20400.59198816045;20734.688979665316;20740.133387995153;20912;20983.79016209849;917.2891009467716;946.8059047895165;962.2350231565922;989.1690979577189;1006.3609607899036;9164.816703665889;11197.216194866443;11210.27133236337;11234.55061797519;11248.775024499464;580179;601883;602310;603497;606112;87222.56388718016;128220.78579726676;135022.3248883769;135643.28713425677;137833.55416114678;880.7709995401113;996.5166894056376;1007.7782887764533;1012.2559918459926;1013.7313611892854;15251.923740381228;18072.09639518045;18423.81576184221;18589;19039.52401189981;52980.47019529755;53070.73464632806;53292;54620;54794.17808732952;75130.24869751233;78444.21555784598;78740.60629696815;79159.60420197863;79788.60105699435;8012.359011278967;8037.879431808645;8385.203405676451;8399.2467043315;8814.823703526023;7485.962570187115;9400.059994000545;10813.080888124283;13164.934175329445;13429.26139062361;11034.944825275823;11040.503177356877;11084.722881928017;11251.212415130862;11483.368414736935;10862.185336099667;11241.71895702614;11300.208985370946;11579.768404632028;11601.477933492844;11577.120292039039;11847.50498602897;11852.76294474123;11958.26605142232;12095.75808483843;6778.288279730557;11804;11884.584039558575;12246.44890979926;12471.563495277622;10997.945010274898;11885.762284754084;12037.337946413032;12128.93935530352;12187.75624487523 19:08:38 INFO - PROCESS | 1674 | |3;mootools.html;1432.9426822927082;1558.773977773223;1593.7928069350983;1820;1955.4817973237105;7909.525428474289;8616.095309992455;8769.166929141722;8783.341249406296;8787.25308348791;8517.403781735282;8771.692990745325;8774.122587741223;8784.297256219503;8785;2418.8790560471994;2446.351716795051;2448.52253810507;2451.044092803808;2451.3258853815164;2386.069432921162;2437.317551085699;2441.7802397784194;2445.682061332024;2447.68180136582;2397.041183526586;2428.2351059416314;2439.695038120235;2440.4996975619956;2440.694913135858;2384.892679829412;2415.830891837572;2425.6967879015033;2428.9514209715794;2439.5364880672614;2380.0598763488497;2414.2757172848073;2416.3717433467427;2417.274817554744;2417.468157005456;7398.741044063486;8438.198371154731;8649.178328058824;8700.825983480296;8730.476171429735;5185.611079169077;5330.946690533084;5372.355317361902;5389.083855744533;5414.756335964891;3486.37883384225;3504.26410453805;3510.894673159784;3543.273628906068;3566.322398744256;960.7550074730899;1009.8586197932295;1055.1242468750995;1078.1374900079904;1097.7145942055042;9823.312368134304;11471.713207169803;11723.296602203895;11730;11750.529978800838;4406.933895991563;4503.166914120898;4520.59314661682;4563.634909207255;4613.354130421743;9224.815503689888;9229.400088994194;9231.030741772152;9232.399894006869;9232.492212928353;5199.142141546678;5199.48005199477;5204.739763011834;5206.297149884793;5244.108501554745;3537.46937959303;4408.7575183365225;4413.16149931512;4421.911561768811;4824.179889418807;3550.9112272192883;3797.734158608871;3854;3857.305684976675;3863.918102931184;1009.4498498318287;1175.488662431845;1178.4284621958343;1187.631834131422;1212.9636110916686;852.1138016462933;858.1161403754144;870.7474832298562;916.5096673279763;922.1930810540777;5699;5734.455226753452;5932.199153114274;6005.6396616203165;6072.240969878765;928.9581968811419;940.5955439161089;974.9658761943298;989.0603926269929;1030.3560274828233;3806.0484878780303;3925.1953349563273;3961.267165574378;3985.70107241958;4044.4337792709043;9812.460314682618;10797.568097276178;10812.621558245424;10825.891741082489;10827.621033263798;10668.199885008655;10687.038166565046;10689.30519516229;10707.946460267805;10818.621348252773;8268.007839059232;8359.540225287546;8380.706675266336;8405.663773449116;8435.029971553227;4345.369921361448;5654.717264136859;5654.745536450787;5715.142728590745;5952.642841429355;9408.43549387039;9483.762905927408;9541.427514349161;9546.809063818546;9713.70858874235;9656.75858103553;9808.362456440309;9832.065953734384;9921.801563968824;9953.203743700631;6947.305269473012;6993.685284162327;7387.298206670358;7392.334689877936;7402.481826272109;7484.925150748423;7670.923290767244;8020.639071241691;8239.876401854097;8247.422680412554;7385.03994480714;7401.777946661608;7435.479516434014;7465.888011679721;7516.3986881048295;7219.025431566672;7231.927680723125;7289.489735718449;7344.339009489172;7400;6912.792616221522;6935;6985.301469852974;7013.964930175521;7015.649217539205;4296.312589985588;4714.882127946828;4783.9521604784895;4878.195097808822;4970.950290497049;4913.926291105565;4943.233798761198;5023.296738456692;5023.598112151092;5214.895702085862 19:08:38 INFO - PROCESS | 1674 | |4;prototype.html;322.22987060924396;324.34482345661763;328.2745133255506;331.2844256406163;336.03557789145157;6666.666666666665;6670.166229221348;6703.798886033414;6710.161229846275;6721.5630983986;6615.338466153395;6630.966845165767;6649.833754156137;6651.235107962586;6664;4675.906481870361;4778.044391121773;4819.542143496362;4843.39457567804;4897.902041959159;3894.3184942635066;4864.173090574611;4892.68197567157;4892.902141957159;4925.310456536088;4769.855234743672;4834.057358815033;4847.442544107437;4848.830290939818;4859.198232291667;3888.7666739995407;4671.11248862715;4794.736289504076;4833.613310935134;4838.564529192372;4064.390341448777;4075.32757095079;4126.216018956418;4149.668026557868;4151.792410379499;5476.17857321401;5867.559933004999;6324.652144132071;6420.903686444707;6527.510436717218;4642.210824159901;5706.714664266811;5714.599978001542;5725.2843394575675;5728.112142617901;6996.265392133803;7017.684204210772;7025.578465292047;7027.191872934627;7033.788986330418;4562.977185114087;4659.813607455698;4665.603423708989;4705.647076469244;4729.739864307462;7381.04046473955;7395.630218489054;7449.180590135081;7460.03019607453;7464.738734144279;4785.665003449794;4791.209450440708;4796.880077998008;4798.1123492154065;4800.831970881002;4857.271409288564;4871.634627402959;4878.8780280492565;4885.65800393969;4894.99652571222;5797.623154495029;5816.069428891357;5816.767329306865;5827.737751801179;5828.59199856006;6876.209235937931;7085.114360704912;7105.75129870452;7154.570725756471;7172.533785303938;1692.2215780740996;1701.8298170182882;1720.0883531728205;1722.7846519185102;1724.4223185232836;1082.5561519776854;1089.1232557790895;1114.1810769084716;1115.5259014918627;1116.2799994003822;5359.785608575613;6302.306746257999;6521.771737989147;6566.474682025427;6594.967025164843;7226.915962605546;7457.925420745832;7467.775966721007;7473.925260747432;7502.2122677119205;6648.833779155463;6661.5019367737095;6662.700178491979;6670.366315200048;6678.1318428604945;3901.7073719470586;3937.4290727844873;3952.466417033721;3953.2686453006286;3955.7428767130045;7576.469647124759;7757.689692412353;7855.978722766118;7880.172581878935;7887.132415434297;6306.243250809932;6514.348565143448;7671.424643151786;7671.501352412075;7675;7837.804054898673;7853.568053757098;7858.685652573948;7870.134285228621;7878.527288362717;6359.459445947146;7200.099987501562;7450.143233528213;7463.738769143054;7485.1766305707415;6936.7225310987;7268.745593904188;7280.235575264521;7287.8542429152185;7296.124465064014;7709.882067100351;7796.415268854745;7802.414818888493;7808.9609551954145;7814.023247094114;3857.267119247334;4620.1914664934175;4673.462551806586;4674.6026587740425;4681.882952926204;7420.109586849612;7420.740274090381;7422.443316751157;7430.2569743027425;7431.739889103856;6863.519553631198;7097.467689923174;7112.786616401516;7146.749863754743;7160.749373771893;5962.9266731988655;6998.475114366544;7072.328128827753;7133.643317834191;7147.46394020461;6907.654617269016;6919.8962015568795;6927.064846245894;6930.341617546325;6931.584104953719;6422.518311126591;6559.4752419807255;7123.323284287984;7124.430045596237;7154.35610795031;6689.866202675822;6736.808496839595;6884.311568843075;6953.930460695328;6966.1988871280455 19:08:38 INFO - PROCESS | 1674 | |5;yui.html;705.0587465733246;728.6247582495015;735.3712575747736;750.1073722270497;788.574169948228;14644.560663180095;14740.852591474082;15080.547583572483;15101.395944162234;15112.924435377821;13763.65590860229;13845.030847840655;13851.307434628254;13872;13872.237026963512;738.7119023580808;748.1844789179793;881.3081730841291;905.108468158863;907.2379201470764;914.419343716738;916.6562539047857;918.4213945214506;919.9862002069974;921.9216366608813;950.9286803489731;963.2775418436173;966.6423423333376;967.0764419978904;970.9951450242739;759.6581538307756;771.6681826814468;776.6776787633125;958.2142643032716;968.3899143539595;592.7540070870626;699.9265077166875;709.8331892005375;709.8864181730899;710.683745733149;14653.706925861423;14733.336999834926;14747.852521474755;14748.336324865406;14757.26213689322;12385.07111966597;12535;12870.22778633285;12893.161944473579;12904.290264035475;9996.950015249877;10134.594616215343;10200.0819926207;10394.16846652266;10424.270301078928;3455.9308813823836;3488.89533314001;3502.8073455960166;3532.2758834439137;3720.962790372089;77169.84245236326;78852;80552;81264;81995;9290.674826381044;9344.486053266999;9374.390664606779;9441.952790236142;9822.557984890696;20684.58630827375;21474.463138421354;21506.89246553757;21519.246826361003;21541.46146346354;7456.85086298271;7548.735794247175;7588.81027974294;7629.923700763033;7895.131535531087;4113.321301985199;4122.2786012447705;4137.54487006429;4255.212785634668;4390.07808360248;3600.6939410149907;3670.6512881276235;3676.7242456815848;3679.1170119171743;3683.5948045715486;652.0935899100255;660.8711301296297;663.575311800448;671.1509939925998;685.9039734437183;538.4211972129976;563.9464250896241;577.2841676321332;579.1775678536488;606.266417634667;7860.213978602208;8169.183081691902;8266.875996860052;8360.414770966096;8482.44864083845;515.932928719264;582.1474773609264;582.6329412470117;586.3432955090326;605.1678941455499;3273.9804709936857;3536.91157721059;3749.4750734897134;3920.921581568353;4028.1540876415693;87625.12374876297;88385.04534591918;88425.55787221022;91977;92170;78296;78379.21620783719;79202.81195782068;80329.19670803334;80889;66991;67080.65838683199;67625;67766;68651;7640.541567505968;7756.844863102593;8224.958875205586;8324.209200125979;8391.289250458754;45050;45981.31028034649;46461;47859;48639;49115.772105697644;49471.50528494669;61139;63457;64324.35675643184;11035.11719062457;11176.497057632589;11186.83219751688;11403.885961140615;11731.824022639497;11761.823572646588;12176.939115304367;12198.390080495763;12422.006239500637;12488.500459981498;9721.222302215947;11178.217524773512;11840.28958262507;11869.76260474803;11915.523379065087;10686.8931310689;11361.318320900773;11382.37396943143;11595.420228988685;11688.824667630162;10925.781484370427;10994.230403871652;11368.94315528417;11487.081033517466;11703.76592468163;9459.432434053979;9486.193673537551;9698.95150524271;9701.126898579161;10019.998000199921;9509.809803804024;9755.56099975517;9791.216702663627;9819.214462843098;9841.704748857546 19:08:38 INFO - PROCESS | 1674 | __end_tp_report 19:08:38 INFO - PROCESS | 1674 | __start_cc_report 19:08:38 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,2090 19:08:38 INFO - PROCESS | 1674 | __end_cc_report 19:08:38 INFO - PROCESS | 1674 | __startTimestamp1464142118946__endTimestamp 19:08:38 INFO - PROCESS | 1674 | 19:08:38 INFO - PROCESS | 1674 | ------- Summary: start ------- 19:08:38 INFO - PROCESS | 1674 | Number of tests: 6 19:08:38 INFO - PROCESS | 1674 | 19:08:38 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5634.23 Median:5771.51 stddev:629.89 (10.9%) stddev-sans-first:605.66 19:08:38 INFO - PROCESS | 1674 | Values: 3344.4 3404.0 3409.3 3413.4 3469.3 5546.7 5560.9 5568.7 5586.4 5619.2 4648.6 5570.7 5626.0 5642.0 5664.7 5042.8 5087.5 5127.8 5159.6 5197.9 5113.3 5189.5 5200.5 5205.6 5220.2 5135.5 5175.8 5186.7 5190.1 5198.2 5164.1 5200.1 5206.9 5214.9 5264.3 5029.9 5040.5 5126.4 5131.6 5138.8 5440.3 5469.6 5480.8 5496.0 5542.8 6105.9 6108.7 6134.4 6140.1 6141.2 4995.8 6017.1 6105.8 6193.2 6194.8 5676.7 5693.5 5705.4 5722.2 5743.8 6353.6 6358.7 6374.3 6376.8 6377.7 5815.5 5917.3 5951.0 6039.9 6059.8 6109.9 6149.8 6182.9 6187.2 6192.3 6109.0 6129.1 6130.6 6132.5 6133.7 5854.5 6027.8 6036.9 6138.9 6168.6 4814.2 4830.6 4844.7 4852.5 4853.5 4273.6 4363.6 4388.1 4420.2 4444.8 5496.7 5520.9 5530.7 5532.0 5556.0 6064.3 6163.7 6233.2 6235.7 6235.7 5438.7 5470.2 5473.5 5475.8 5479.5 5196.1 5263.9 5265.7 5278.9 5280.9 6316.2 6334.2 6346.2 6353.9 6355.2 6196.4 6223.5 6232.6 6238.0 6249.4 6236.1 6241.9 6330.4 6338.7 6340.9 5164.8 6106.7 6118.4 6137.2 6143.4 6177.3 6193.5 6201.3 6211.1 6219.8 5771.5 5863.3 5869.8 5942.0 5962.5 5848.6 5876.8 5886.2 5899.9 5902.3 5596.0 5731.9 5739.5 5748.3 5769.2 5869.8 5871.7 5872.1 5889.6 5919.9 5784.9 5800.1 5824.3 5835.3 5847.6 19:08:38 INFO - PROCESS | 1674 | 19:08:38 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:60891.10 Median:3367.05 stddev:130469.16 (3874.9%) stddev-sans-first:130756.44 19:08:38 INFO - PROCESS | 1674 | Values: 528.9 541.4 583.0 586.3 607.8 31010.0 31715.5 31738.5 38485.0 39043.6 42261.6 42558.4 42659.8 42749.8 42833.6 9464.7 9788.8 9799.3 9803.4 9811.6 8554.7 8768.9 9559.6 11446.0 11656.7 9790.1 11391.3 11403.1 11479.8 11496.8 11348.0 11384.9 11408.9 11421.8 11436.5 8551.8 11458.9 11715.0 11719.8 11728.2 38590.6 38993.0 39060.0 39101.0 39127.8 3211.5 3264.7 3265.3 3287.8 3293.0 27122.3 27802.9 28271.7 28272.6 28324.9 2756.2 2767.2 2820.1 2822.1 2847.6 482007.0 482743.0 482745.0 483819.0 484346.0 36859.6 64950.0 65345.0 65650.0 66107.3 35977.6 36012.6 36071.0 36082.0 36130.8 15760.7 15919.8 15929.5 15929.8 15975.0 12568.0 12703.2 13314.0 13412.9 13435.3 8203.2 8209.6 8239.2 8264.3 8298.8 3347.5 3354.6 3365.2 3368.9 3410.7 986.8 1023.8 1026.4 1044.9 1120.8 28954.4 29411.6 29832.9 30218.8 30992.8 971.8 1065.7 1107.0 1176.1 1195.0 15374.2 15473.0 15565.4 15725.8 15844.9 605095.0 611704.0 613042.0 613473.0 614524.0 60879.0 62636.7 62781.0 62816.1 62931.7 1189.8 1399.1 1452.1 1529.3 1541.7 26381.9 26803.5 29099.6 29229.6 29960.6 48199.0 48725.0 48737.8 48790.3 48808.5 320524.0 328849.0 328954.0 329144.0 329643.0 24251.8 27279.3 27324.0 27882.7 28171.3 43010.6 43273.4 43320.1 43660.0 44417.8 36219.6 36642.8 37596.8 37885.6 38617.0 37051.0 37125.8 37224.3 37956.2 38153.2 36562.8 37258.6 38209.8 38338.6 39938.0 30923.7 31706.7 33876.3 34772.5 34893.8 23983.9 24356.5 24410.8 24848.8 25147.0 19:08:38 INFO - PROCESS | 1674 | 19:08:38 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:34881.83 Median:2350.30 stddev:99629.00 (4239.0%) stddev-sans-first:99874.84 19:08:38 INFO - PROCESS | 1674 | Values: 397.8 399.4 403.5 403.7 411.8 12291.8 15302.0 20336.0 21093.5 21105.2 19952.4 20114.3 20804.8 21825.0 22079.0 5149.0 5753.3 5785.7 5963.2 5967.4 5880.9 5889.3 5939.2 5941.7 5942.3 5641.1 5890.7 5895.0 5901.4 5905.9 5906.3 5925.5 5934.2 5937.5 5947.0 5133.1 5209.4 5210.1 5215.2 5216.9 26707.6 26760.3 27114.5 27239.0 27253.6 2211.3 2273.1 2291.1 2409.5 2496.5 12388.8 14486.2 15009.9 15025.8 15371.4 1633.8 1712.8 1747.5 1782.0 1855.2 75980.0 106114.5 112817.0 115918.4 116690.0 11606.0 11939.2 11977.5 11985.9 12027.5 15386.5 15441.8 15463.8 15471.5 15478.7 9080.9 9112.7 9149.5 9160.9 9233.2 9119.4 9141.7 9142.8 9186.0 9230.0 4765.4 4831.8 4835.9 4841.9 4877.3 1726.3 1786.8 1899.0 1933.6 1978.7 842.5 904.7 917.2 938.7 965.8 20400.6 20734.7 20740.1 20912.0 20983.8 917.3 946.8 962.2 989.2 1006.4 9164.8 11197.2 11210.3 11234.6 11248.8 580179.0 601883.0 602310.0 603497.0 606112.0 87222.6 128220.8 135022.3 135643.3 137833.6 880.8 996.5 1007.8 1012.3 1013.7 15251.9 18072.1 18423.8 18589.0 19039.5 52980.5 53070.7 53292.0 54620.0 54794.2 75130.2 78444.2 78740.6 79159.6 79788.6 8012.4 8037.9 8385.2 8399.2 8814.8 7486.0 9400.1 10813.1 13164.9 13429.3 11034.9 11040.5 11084.7 11251.2 11483.4 10862.2 11241.7 11300.2 11579.8 11601.5 11577.1 11847.5 11852.8 11958.3 12095.8 6778.3 11804.0 11884.6 12246.4 12471.6 10997.9 11885.8 12037.3 12128.9 12187.8 19:08:38 INFO - PROCESS | 1674 | 19:08:38 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5604.36 Median:5202.11 stddev:3114.18 (59.9%) stddev-sans-first:3107.14 19:08:38 INFO - PROCESS | 1674 | Values: 1432.9 1558.8 1593.8 1820.0 1955.5 7909.5 8616.1 8769.2 8783.3 8787.3 8517.4 8771.7 8774.1 8784.3 8785.0 2418.9 2446.4 2448.5 2451.0 2451.3 2386.1 2437.3 2441.8 2445.7 2447.7 2397.0 2428.2 2439.7 2440.5 2440.7 2384.9 2415.8 2425.7 2429.0 2439.5 2380.1 2414.3 2416.4 2417.3 2417.5 7398.7 8438.2 8649.2 8700.8 8730.5 5185.6 5330.9 5372.4 5389.1 5414.8 3486.4 3504.3 3510.9 3543.3 3566.3 960.8 1009.9 1055.1 1078.1 1097.7 9823.3 11471.7 11723.3 11730.0 11750.5 4406.9 4503.2 4520.6 4563.6 4613.4 9224.8 9229.4 9231.0 9232.4 9232.5 5199.1 5199.5 5204.7 5206.3 5244.1 3537.5 4408.8 4413.2 4421.9 4824.2 3550.9 3797.7 3854.0 3857.3 3863.9 1009.4 1175.5 1178.4 1187.6 1213.0 852.1 858.1 870.7 916.5 922.2 5699.0 5734.5 5932.2 6005.6 6072.2 929.0 940.6 975.0 989.1 1030.4 3806.0 3925.2 3961.3 3985.7 4044.4 9812.5 10797.6 10812.6 10825.9 10827.6 10668.2 10687.0 10689.3 10707.9 10818.6 8268.0 8359.5 8380.7 8405.7 8435.0 4345.4 5654.7 5654.7 5715.1 5952.6 9408.4 9483.8 9541.4 9546.8 9713.7 9656.8 9808.4 9832.1 9921.8 9953.2 6947.3 6993.7 7387.3 7392.3 7402.5 7484.9 7670.9 8020.6 8239.9 8247.4 7385.0 7401.8 7435.5 7465.9 7516.4 7219.0 7231.9 7289.5 7344.3 7400.0 6912.8 6935.0 6985.3 7014.0 7015.6 4296.3 4714.9 4784.0 4878.2 4971.0 4913.9 4943.2 5023.3 5023.6 5214.9 19:08:38 INFO - PROCESS | 1674 | 19:08:38 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:5796.14 Median:6605.15 stddev:1863.52 (28.2%) stddev-sans-first:1822.90 19:08:38 INFO - PROCESS | 1674 | Values: 322.2 324.3 328.3 331.3 336.0 6666.7 6670.2 6703.8 6710.2 6721.6 6615.3 6631.0 6649.8 6651.2 6664.0 4675.9 4778.0 4819.5 4843.4 4897.9 3894.3 4864.2 4892.7 4892.9 4925.3 4769.9 4834.1 4847.4 4848.8 4859.2 3888.8 4671.1 4794.7 4833.6 4838.6 4064.4 4075.3 4126.2 4149.7 4151.8 5476.2 5867.6 6324.7 6420.9 6527.5 4642.2 5706.7 5714.6 5725.3 5728.1 6996.3 7017.7 7025.6 7027.2 7033.8 4563.0 4659.8 4665.6 4705.6 4729.7 7381.0 7395.6 7449.2 7460.0 7464.7 4785.7 4791.2 4796.9 4798.1 4800.8 4857.3 4871.6 4878.9 4885.7 4895.0 5797.6 5816.1 5816.8 5827.7 5828.6 6876.2 7085.1 7105.8 7154.6 7172.5 1692.2 1701.8 1720.1 1722.8 1724.4 1082.6 1089.1 1114.2 1115.5 1116.3 5359.8 6302.3 6521.8 6566.5 6595.0 7226.9 7457.9 7467.8 7473.9 7502.2 6648.8 6661.5 6662.7 6670.4 6678.1 3901.7 3937.4 3952.5 3953.3 3955.7 7576.5 7757.7 7856.0 7880.2 7887.1 6306.2 6514.3 7671.4 7671.5 7675.0 7837.8 7853.6 7858.7 7870.1 7878.5 6359.5 7200.1 7450.1 7463.7 7485.2 6936.7 7268.7 7280.2 7287.9 7296.1 7709.9 7796.4 7802.4 7809.0 7814.0 3857.3 4620.2 4673.5 4674.6 4681.9 7420.1 7420.7 7422.4 7430.3 7431.7 6863.5 7097.5 7112.8 7146.7 7160.7 5962.9 6998.5 7072.3 7133.6 7147.5 6907.7 6919.9 6927.1 6930.3 6931.6 6422.5 6559.5 7123.3 7124.4 7154.4 6689.9 6736.8 6884.3 6953.9 6966.2 19:08:38 INFO - PROCESS | 1674 | 19:08:38 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:17805.19 Median:605.72 stddev:24795.88 (4093.6%) stddev-sans-first:24832.19 19:08:38 INFO - PROCESS | 1674 | Values: 705.1 728.6 735.4 750.1 788.6 14644.6 14740.9 15080.5 15101.4 15112.9 13763.7 13845.0 13851.3 13872.0 13872.2 738.7 748.2 881.3 905.1 907.2 914.4 916.7 918.4 920.0 921.9 950.9 963.3 966.6 967.1 971.0 759.7 771.7 776.7 958.2 968.4 592.8 699.9 709.8 709.9 710.7 14653.7 14733.3 14747.9 14748.3 14757.3 12385.1 12535.0 12870.2 12893.2 12904.3 9997.0 10134.6 10200.1 10394.2 10424.3 3455.9 3488.9 3502.8 3532.3 3721.0 77169.8 78852.0 80552.0 81264.0 81995.0 9290.7 9344.5 9374.4 9442.0 9822.6 20684.6 21474.5 21506.9 21519.2 21541.5 7456.9 7548.7 7588.8 7629.9 7895.1 4113.3 4122.3 4137.5 4255.2 4390.1 3600.7 3670.7 3676.7 3679.1 3683.6 652.1 660.9 663.6 671.2 685.9 538.4 563.9 577.3 579.2 606.3 7860.2 8169.2 8266.9 8360.4 8482.4 515.9 582.1 582.6 586.3 605.2 3274.0 3536.9 3749.5 3920.9 4028.2 87625.1 88385.0 88425.6 91977.0 92170.0 78296.0 78379.2 79202.8 80329.2 80889.0 66991.0 67080.7 67625.0 67766.0 68651.0 7640.5 7756.8 8225.0 8324.2 8391.3 45050.0 45981.3 46461.0 47859.0 48639.0 49115.8 49471.5 61139.0 63457.0 64324.4 11035.1 11176.5 11186.8 11403.9 11731.8 11761.8 12176.9 12198.4 12422.0 12488.5 9721.2 11178.2 11840.3 11869.8 11915.5 10686.9 11361.3 11382.4 11595.4 11688.8 10925.8 10994.2 11368.9 11487.1 11703.8 9459.4 9486.2 9699.0 9701.1 10020.0 9509.8 9755.6 9791.2 9819.2 9841.7 19:08:38 INFO - PROCESS | 1674 | -------- Summary: end -------- 19:08:38 INFO - PROCESS | 1674 | 19:08:39 INFO - TEST-INFO | 1674: exit 0 19:08:39 INFO - TEST-OK | dromaeo_css | took 1115010ms 19:08:39 INFO - TEST-START | kraken 19:08:39 INFO - operating with platform_type : mac_ 19:08:39 INFO - Initialising browser for kraken test... 19:08:39 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpab26kk/profile http://localhost:49279/getInfo.html) 19:08:41 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 19:08:41 INFO - PROCESS | 1679 | colorDepth:24 19:08:41 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 19:08:41 INFO - PROCESS | 1679 | __metrics 19:08:41 INFO - PROCESS | 1679 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:08:41 ERROR - PROCESS | 1679 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:08:42 INFO - TEST-INFO | 1679: exit 0 19:08:42 INFO - Browser initialized. 19:08:42 INFO - Running cycle 1/1 for kraken test... 19:08:42 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpab26kk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:08:54 INFO - PROCESS | 1681 | RSS: Main: 210952192 19:08:54 INFO - PROCESS | 1681 | 19:08:56 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 19:08:57 INFO - PROCESS | 1681 | RSS: Main: 271532032 19:08:57 INFO - PROCESS | 1681 | 19:09:00 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 19:09:01 INFO - PROCESS | 1681 | RSS: Main: 285532160 19:09:01 INFO - PROCESS | 1681 | 19:09:04 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 19:09:04 INFO - PROCESS | 1681 | RSS: Main: 372322304 19:09:04 INFO - PROCESS | 1681 | 19:09:06 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 19:09:07 INFO - PROCESS | 1681 | RSS: Main: 278040576 19:09:07 INFO - PROCESS | 1681 | 19:09:09 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:09:10 INFO - PROCESS | 1681 | RSS: Main: 280424448 19:09:10 INFO - PROCESS | 1681 | 19:09:12 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 19:09:13 INFO - PROCESS | 1681 | RSS: Main: 317984768 19:09:13 INFO - PROCESS | 1681 | 19:09:16 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 19:09:17 INFO - PROCESS | 1681 | RSS: Main: 318160896 19:09:17 INFO - PROCESS | 1681 | 19:09:19 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 19:09:20 INFO - PROCESS | 1681 | RSS: Main: 317161472 19:09:20 INFO - PROCESS | 1681 | 19:09:22 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:09:22 INFO - PROCESS | 1681 | RSS: Main: 308891648 19:09:22 INFO - PROCESS | 1681 | 19:09:24 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:09:25 INFO - PROCESS | 1681 | RSS: Main: 316076032 19:09:25 INFO - PROCESS | 1681 | 19:09:27 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:09:28 INFO - PROCESS | 1681 | RSS: Main: 318722048 19:09:28 INFO - PROCESS | 1681 | 19:09:30 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:09:31 INFO - PROCESS | 1681 | RSS: Main: 323760128 19:09:31 INFO - PROCESS | 1681 | 19:09:34 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:09:34 INFO - PROCESS | 1681 | RSS: Main: 321966080 19:09:34 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 19:09:36 INFO - PROCESS | 1681 | RSS: Main: 386154496 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | __start_tp_report 19:09:36 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 19:09:36 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 19:09:36 INFO - PROCESS | 1681 | |i|pagename|runs| 19:09:36 INFO - PROCESS | 1681 | |0;ai-astar;115;114;119;114;114;113;113;113;114;132 19:09:36 INFO - PROCESS | 1681 | |1;audio-beat-detection;155;124;155;156;117;140;119;152;162;104 19:09:36 INFO - PROCESS | 1681 | |2;audio-dft;184;184;184;184;182;187;186;185;183;183 19:09:36 INFO - PROCESS | 1681 | |3;audio-fft;70;79;72;151;55;56;56;67;132;56 19:09:36 INFO - PROCESS | 1681 | |4;audio-oscillator;96;97;138;78;81;90;123;79;80;126 19:09:36 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;90;86;90;86;88;86;89;87;89;85 19:09:36 INFO - PROCESS | 1681 | |6;imaging-darkroom;94;95;94;95;93;93;94;95;94;94 19:09:36 INFO - PROCESS | 1681 | |7;imaging-desaturate;84;86;86;84;85;84;87;86;84;86 19:09:36 INFO - PROCESS | 1681 | |8;json-parse-financial;61;63;63;64;66;66;64;83;61;59 19:09:36 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;63;65;68;75;75;70;67;67;68;66 19:09:36 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;96;94;102;101;82;78;81;93;103;106 19:09:36 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;134;128;126;129;126;129;128;128;148;136 19:09:36 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;165;166;168;164;165;165;169;172;166;164 19:09:36 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;69;69;69;67;69;68;69;68;68;66 19:09:36 INFO - PROCESS | 1681 | __end_tp_report 19:09:36 INFO - PROCESS | 1681 | __start_cc_report 19:09:36 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,2083 19:09:36 INFO - PROCESS | 1681 | __end_cc_report 19:09:36 INFO - PROCESS | 1681 | __startTimestamp1464142176915__endTimestamp 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | ------- Summary: start ------- 19:09:36 INFO - PROCESS | 1681 | Number of tests: 14 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:116.10 Median:114.00 stddev:5.86 (5.1%) stddev-sans-first:6.20 19:09:36 INFO - PROCESS | 1681 | Values: 115.0 114.0 119.0 114.0 114.0 113.0 113.0 113.0 114.0 132.0 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:153.50 stddev:20.63 (13.4%) stddev-sans-first:20.99 19:09:36 INFO - PROCESS | 1681 | Values: 155.0 124.0 155.0 156.0 117.0 140.0 119.0 152.0 162.0 104.0 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.00 stddev:1.48 (0.8%) stddev-sans-first:1.56 19:09:36 INFO - PROCESS | 1681 | Values: 184.0 184.0 184.0 184.0 182.0 187.0 186.0 185.0 183.0 183.0 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:79.40 Median:61.50 stddev:34.04 (55.3%) stddev-sans-first:35.93 19:09:36 INFO - PROCESS | 1681 | Values: 70.0 79.0 72.0 151.0 55.0 56.0 56.0 67.0 132.0 56.0 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:98.80 Median:80.50 stddev:22.23 (27.6%) stddev-sans-first:23.55 19:09:36 INFO - PROCESS | 1681 | Values: 96.0 97.0 138.0 78.0 81.0 90.0 123.0 79.0 80.0 126.0 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.50 stddev:1.84 (2.1%) stddev-sans-first:1.73 19:09:36 INFO - PROCESS | 1681 | Values: 90.0 86.0 90.0 86.0 88.0 86.0 89.0 87.0 89.0 85.0 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 19:09:36 INFO - PROCESS | 1681 | Values: 94.0 95.0 94.0 95.0 93.0 93.0 94.0 95.0 94.0 94.0 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:86.00 stddev:1.14 (1.3%) stddev-sans-first:1.12 19:09:36 INFO - PROCESS | 1681 | Values: 84.0 86.0 86.0 84.0 85.0 84.0 87.0 86.0 84.0 86.0 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:64.00 stddev:6.70 (10.5%) stddev-sans-first:6.95 19:09:36 INFO - PROCESS | 1681 | Values: 61.0 63.0 63.0 64.0 66.0 66.0 64.0 83.0 61.0 59.0 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.00 stddev:3.95 (5.8%) stddev-sans-first:3.67 19:09:36 INFO - PROCESS | 1681 | Values: 63.0 65.0 68.0 75.0 75.0 70.0 67.0 67.0 68.0 66.0 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:93.60 Median:81.50 stddev:10.06 (12.3%) stddev-sans-first:10.63 19:09:36 INFO - PROCESS | 1681 | Values: 96.0 94.0 102.0 101.0 82.0 78.0 81.0 93.0 103.0 106.0 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:131.20 Median:129.00 stddev:6.73 (5.2%) stddev-sans-first:7.06 19:09:36 INFO - PROCESS | 1681 | Values: 134.0 128.0 126.0 129.0 126.0 129.0 128.0 128.0 148.0 136.0 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.40 Median:166.00 stddev:2.55 (1.5%) stddev-sans-first:2.65 19:09:36 INFO - PROCESS | 1681 | Values: 165.0 166.0 168.0 164.0 165.0 165.0 169.0 172.0 166.0 164.0 19:09:36 INFO - PROCESS | 1681 | 19:09:36 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:69.00 stddev:1.03 (1.5%) stddev-sans-first:1.05 19:09:36 INFO - PROCESS | 1681 | Values: 69.0 69.0 69.0 67.0 69.0 68.0 69.0 68.0 68.0 66.0 19:09:36 INFO - PROCESS | 1681 | -------- Summary: end -------- 19:09:36 INFO - PROCESS | 1681 | 19:09:37 INFO - TEST-INFO | 1681: exit 0 19:09:37 INFO - TEST-OK | kraken | took 58168ms 19:09:37 INFO - SUITE-END | took 1173s 19:09:38 INFO - Completed test suite (00:19:33) 19:09:38 INFO - javascript benchmark 19:09:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3344.4481660526017, 3404.0, 3409.3351796399706, 3413.4367829308167, 3469.3234819210247, 5546.722663866805, 5560.916586251209, 5568.665880047195, 5586.41342659021, 5619.157126431032, 4648.604868586174, 5570.6657600543995, 5626.0, 5641.971790141043, 5664.660120392779, 5042.84871453857, 5087.49125087492, 5127.846164615058, 5159.613029022838, 5197.922031169535, 5113.284140220372, 5189.455107213745, 5200.531952124308, 5205.635605507616, 5220.164773636219, 5135.51212634801, 5175.8188463403785, 5186.7147306898105, 5190.143626301656, 5198.246254293125, 5164.147915593941, 5200.089984252779, 5206.921896171561, 5214.895702085975, 5264.2893209416625, 5029.899402011976, 5040.47075057121, 5126.410462796789, 5131.61512886535, 5138.845834624967, 5440.319960004999, 5469.56243500519, 5480.780768769245, 5496.0, 5542.75057622408, 6105.938940610581, 6108.725107370179, 6134.447899689005, 6140.109684095781, 6141.170941922828, 4995.750212489397, 6017.097435384683, 6105.847353816146, 6193.225846769154, 6194.81415557534, 5676.659400435987, 5693.516051135617, 5705.372409035002, 5722.227499287585, 5743.827685169494, 6353.5870168438905, 6358.7456501740335, 6374.298827129012, 6376.776812811529, 6377.681115944184, 5815.505682017076, 5917.349091600006, 5950.9702451487465, 6039.879202416015, 6059.818205453844, 6109.938900610937, 6149.815505534841, 6182.938170618237, 6187.226596675416, 6192.318844927055, 6108.969455152695, 6129.1419201311855, 6130.570860039755, 6132.478739307119, 6133.662648554372, 5854.4730974212525, 6027.849303767441, 6036.909446358308, 6138.877222455526, 6168.599041062316, 4814.229723244264, 4830.589399900978, 4844.733539655283, 4852.490488498727, 4853.514648535118, 4273.63674087706, 4363.585459381354, 4388.100439409914, 4420.160169567772, 4444.755538445416, 5496.697681627549, 5520.944790552124, 5530.695811730313, 5532.031894418541, 5555.972220138954, 6064.272287325549, 6163.691815409212, 6233.158523599256, 6235.657038862815, 6235.688215589294, 5438.700871452108, 5470.152126420411, 5473.452654734574, 5475.835724928259, 5479.479449452296, 5196.1166601677805, 5263.894722105537, 5265.6840589564745, 5278.8944221116135, 5280.894382112337, 6316.210473690789, 6334.239891213084, 6346.174997250328, 6353.936460635427, 6355.237371515355, 6196.411340922605, 6223.471004964629, 6232.5637205397, 6237.970734828702, 6249.437550620466, 6236.095766113979, 6241.875162496815, 6330.430261276507, 6338.683065846597, 6340.904886426615, 5164.8192313268855, 6106.6946652668075, 6118.388161183846, 6137.232845894263, 6143.385661433999, 6177.2587289525545, 6193.473554747897, 6201.317855036033, 6211.130441738251, 6219.844503887439, 5771.509421699035, 5863.296404431325, 5869.794557190648, 5942.049272116614, 5962.4633782959745, 5848.619839710405, 5876.823695289148, 5886.234789477512, 5899.91150132757, 5902.2622172228475, 5595.972020139873, 5731.914021289767, 5739.512141467855, 5748.281464816898, 5769.192313076257, 5869.7945571904775, 5871.6770577618645, 5872.060470324727, 5889.617174883619, 5919.881602368015, 5784.913226301524, 5800.100984347433, 5824.301083869793, 5835.270591176103, 5847.590668653153], "value": 5595.3509913213675, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [528.888933324002, 541.4179756761482, 582.9972447390488, 586.2525280267658, 607.7994261893575, 31010.0, 31715.52426713598, 31738.523922141187, 38485.0, 39043.63347282846, 42261.57738422615, 42558.36162457566, 42659.78670106653, 42749.786251068705, 42833.57166428335, 9464.668736594238, 9788.755281117958, 9799.314048016642, 9803.362781419184, 9811.558479868387, 8554.70058547951, 8768.912310876905, 9559.61761529538, 11446.0, 11656.708582285426, 9790.118889299962, 11391.316521008725, 11403.14476414268, 11479.827802582968, 11496.770064598702, 11348.0, 11384.943075284653, 11408.885911140907, 11421.828672569918, 11436.485358158903, 8551.82896342076, 11458.885411145866, 11715.0, 11719.765604687858, 11728.17902746808, 38590.61409385898, 38993.0, 39060.0, 39101.0, 39127.804360978014, 3211.5022171563446, 3264.7388208943466, 3265.297960938386, 3287.7862938908893, 3293.0, 27122.32194195161, 27802.86098569494, 28271.717282827114, 28272.575911361346, 28324.85837570799, 2756.2144788735313, 2767.1836808141647, 2820.055281480686, 2822.1251412062325, 2847.6440444944383, 482007.0, 482743.0, 482745.0, 483819.0, 484346.0, 36859.63140368562, 64950.0, 65345.0, 65650.0, 66107.33892661011, 35977.64022359743, 36012.63987360145, 36071.0, 36082.0, 36130.81934590363, 15760.684786304439, 15919.840801592067, 15929.522114336589, 15929.761053584205, 15975.0, 12567.994560435143, 12703.174293671067, 13314.0, 13412.93293533526, 13435.261060641758, 8203.220694034057, 8209.630566624517, 8239.217274358927, 8264.338852891875, 8298.751037468886, 3347.497875318673, 3354.5974483062187, 3365.175531994628, 3368.915777105543, 3410.7100896423585, 986.7779749556292, 1023.7850051489122, 1026.3636545341935, 1044.9059584637419, 1120.7534342444649, 28954.420911581652, 29411.558826617616, 29832.85083574568, 30218.84890575533, 30992.845035774677, 971.8056388722142, 1065.7016035510069, 1107.0147568663895, 1176.1120354132747, 1194.964151075469, 15374.1544215067, 15473.0, 15565.443455654344, 15725.842741572438, 15844.920775396049, 605095.0, 611704.0, 613042.0, 613473.0, 614524.0, 60879.0, 62636.68681656654, 62781.0, 62816.05775913365, 62931.68534157392, 1189.79178643737, 1399.0626280392162, 1452.0924422236103, 1529.258309719785, 1541.6685412636336, 26381.868090659424, 26803.463930721668, 29099.563506546994, 29229.561556576242, 29960.550591740706, 48199.0, 48725.0, 48737.75631121822, 48790.268145978545, 48808.535743927736, 320524.0, 328849.0, 328954.0, 329144.0, 329643.0, 24251.757482425655, 27279.318017049733, 27324.0, 27882.721172788013, 28171.295717606405, 43010.56989430066, 43273.3508997359, 43320.13359732851, 43660.0, 44417.777911110235, 36219.63780362163, 36642.81678591697, 37596.812015939744, 37885.62114378821, 38617.0, 37051.0, 37125.814370927976, 37224.255514890094, 37956.24087518179, 38153.236935261695, 36562.8171859139, 37258.62741372552, 38209.808950956176, 38338.6166138335, 39938.0, 30923.69076309208, 31706.682933171294, 33876.322473550885, 34772.47841282332, 34893.825530872185, 23983.880080599487, 24356.52623622523, 24410.755892440848, 24848.751512485367, 25146.967975927262], "value": 18550.99626923869, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.8070635741665, 399.42881679198746, 403.4513062235361, 403.73555321264547, 411.755005771566, 12291.815622765644, 15302.005369650964, 20336.0, 21093.472663183427, 21105.20605748513, 19952.40142795715, 20114.295999640017, 20804.791952080475, 21825.0, 22079.0, 5148.974255128738, 5753.309602847654, 5785.681787501686, 5963.194968679216, 5967.373425790294, 5880.911786323209, 5889.263842019747, 5939.227900372945, 5941.732622032019, 5942.346341902387, 5641.097424412095, 5890.676012819294, 5895.0, 5901.4098590141075, 5905.881882362351, 5906.3207731110615, 5925.466707996279, 5934.1692163097205, 5937.465628093449, 5947.0, 5133.0503856786245, 5209.400918894337, 5210.114280572312, 5215.191645294985, 5216.921746173811, 26707.599386009224, 26760.330991725168, 27114.457710845672, 27239.046633367736, 27253.591196132074, 2211.303439416579, 2273.1362082408577, 2291.129370839087, 2409.4699166183414, 2496.488219914913, 12388.81416778749, 14486.20325882076, 15009.949303548756, 15025.774613380809, 15371.385144594204, 1633.8121116071686, 1712.8372804583544, 1747.4932269641788, 1781.9554511137196, 1855.2208072609537, 75980.0, 106114.46942765237, 112817.0, 115918.42040789826, 116690.0, 11606.013488853374, 11939.223950443367, 11977.52089916411, 11985.940070299594, 12027.518899244107, 15386.461473848585, 15441.768373474408, 15463.768043479131, 15471.5358539244, 15478.690426191413, 9080.8637870432, 9112.726618201465, 9149.496777677292, 9160.8625870612, 9233.169014788567, 9119.361644684941, 9141.725748227564, 9142.771430714152, 9186.0, 9230.030846761128, 4765.428148622187, 4831.806727730851, 4835.903281934412, 4841.927371089366, 4877.268409738567, 1726.3181043488003, 1786.7587875636623, 1898.9810101899081, 1933.6326098041327, 1978.6537355962648, 842.5450256861226, 904.7285814255697, 917.206616276929, 938.7090002099235, 965.8309795785709, 20400.59198816045, 20734.688979665316, 20740.133387995153, 20912.0, 20983.79016209849, 917.2891009467716, 946.8059047895165, 962.2350231565922, 989.1690979577189, 1006.3609607899036, 9164.816703665889, 11197.216194866443, 11210.27133236337, 11234.55061797519, 11248.775024499464, 580179.0, 601883.0, 602310.0, 603497.0, 606112.0, 87222.56388718016, 128220.78579726676, 135022.3248883769, 135643.28713425677, 137833.55416114678, 880.7709995401113, 996.5166894056376, 1007.7782887764533, 1012.2559918459926, 1013.7313611892854, 15251.923740381228, 18072.09639518045, 18423.81576184221, 18589.0, 19039.52401189981, 52980.47019529755, 53070.73464632806, 53292.0, 54620.0, 54794.17808732952, 75130.24869751233, 78444.21555784598, 78740.60629696815, 79159.60420197863, 79788.60105699435, 8012.359011278967, 8037.879431808645, 8385.203405676451, 8399.2467043315, 8814.823703526023, 7485.962570187115, 9400.059994000545, 10813.080888124283, 13164.934175329445, 13429.26139062361, 11034.944825275823, 11040.503177356877, 11084.722881928017, 11251.212415130862, 11483.368414736935, 10862.185336099667, 11241.71895702614, 11300.208985370946, 11579.768404632028, 11601.477933492844, 11577.120292039039, 11847.50498602897, 11852.76294474123, 11958.26605142232, 12095.75808483843, 6778.288279730557, 11804.0, 11884.584039558575, 12246.44890979926, 12471.563495277622, 10997.945010274898, 11885.762284754084, 12037.337946413032, 12128.93935530352, 12187.75624487523], "value": 9763.938424404094, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.9426822927082, 1558.773977773223, 1593.7928069350983, 1820.0, 1955.4817973237105, 7909.525428474289, 8616.095309992455, 8769.166929141722, 8783.341249406296, 8787.25308348791, 8517.403781735282, 8771.692990745325, 8774.122587741223, 8784.297256219503, 8785.0, 2418.8790560471994, 2446.351716795051, 2448.52253810507, 2451.044092803808, 2451.3258853815164, 2386.069432921162, 2437.317551085699, 2441.7802397784194, 2445.682061332024, 2447.68180136582, 2397.041183526586, 2428.2351059416314, 2439.695038120235, 2440.4996975619956, 2440.694913135858, 2384.892679829412, 2415.830891837572, 2425.6967879015033, 2428.9514209715794, 2439.5364880672614, 2380.0598763488497, 2414.2757172848073, 2416.3717433467427, 2417.274817554744, 2417.468157005456, 7398.741044063486, 8438.198371154731, 8649.178328058824, 8700.825983480296, 8730.476171429735, 5185.611079169077, 5330.946690533084, 5372.355317361902, 5389.083855744533, 5414.756335964891, 3486.37883384225, 3504.26410453805, 3510.894673159784, 3543.273628906068, 3566.322398744256, 960.7550074730899, 1009.8586197932295, 1055.1242468750995, 1078.1374900079904, 1097.7145942055042, 9823.312368134304, 11471.713207169803, 11723.296602203895, 11730.0, 11750.529978800838, 4406.933895991563, 4503.166914120898, 4520.59314661682, 4563.634909207255, 4613.354130421743, 9224.815503689888, 9229.400088994194, 9231.030741772152, 9232.399894006869, 9232.492212928353, 5199.142141546678, 5199.48005199477, 5204.739763011834, 5206.297149884793, 5244.108501554745, 3537.46937959303, 4408.7575183365225, 4413.16149931512, 4421.911561768811, 4824.179889418807, 3550.9112272192883, 3797.734158608871, 3854.0, 3857.305684976675, 3863.918102931184, 1009.4498498318287, 1175.488662431845, 1178.4284621958343, 1187.631834131422, 1212.9636110916686, 852.1138016462933, 858.1161403754144, 870.7474832298562, 916.5096673279763, 922.1930810540777, 5699.0, 5734.455226753452, 5932.199153114274, 6005.6396616203165, 6072.240969878765, 928.9581968811419, 940.5955439161089, 974.9658761943298, 989.0603926269929, 1030.3560274828233, 3806.0484878780303, 3925.1953349563273, 3961.267165574378, 3985.70107241958, 4044.4337792709043, 9812.460314682618, 10797.568097276178, 10812.621558245424, 10825.891741082489, 10827.621033263798, 10668.199885008655, 10687.038166565046, 10689.30519516229, 10707.946460267805, 10818.621348252773, 8268.007839059232, 8359.540225287546, 8380.706675266336, 8405.663773449116, 8435.029971553227, 4345.369921361448, 5654.717264136859, 5654.745536450787, 5715.142728590745, 5952.642841429355, 9408.43549387039, 9483.762905927408, 9541.427514349161, 9546.809063818546, 9713.70858874235, 9656.75858103553, 9808.362456440309, 9832.065953734384, 9921.801563968824, 9953.203743700631, 6947.305269473012, 6993.685284162327, 7387.298206670358, 7392.334689877936, 7402.481826272109, 7484.925150748423, 7670.923290767244, 8020.639071241691, 8239.876401854097, 8247.422680412554, 7385.03994480714, 7401.777946661608, 7435.479516434014, 7465.888011679721, 7516.3986881048295, 7219.025431566672, 7231.927680723125, 7289.489735718449, 7344.339009489172, 7400.0, 6912.792616221522, 6935.0, 6985.301469852974, 7013.964930175521, 7015.649217539205, 4296.312589985588, 4714.882127946828, 4783.9521604784895, 4878.195097808822, 4970.950290497049, 4913.926291105565, 4943.233798761198, 5023.296738456692, 5023.598112151092, 5214.895702085862], "value": 4525.801823511735, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.22987060924396, 324.34482345661763, 328.2745133255506, 331.2844256406163, 336.03557789145157, 6666.666666666665, 6670.166229221348, 6703.798886033414, 6710.161229846275, 6721.5630983986, 6615.338466153395, 6630.966845165767, 6649.833754156137, 6651.235107962586, 6664.0, 4675.906481870361, 4778.044391121773, 4819.542143496362, 4843.39457567804, 4897.902041959159, 3894.3184942635066, 4864.173090574611, 4892.68197567157, 4892.902141957159, 4925.310456536088, 4769.855234743672, 4834.057358815033, 4847.442544107437, 4848.830290939818, 4859.198232291667, 3888.7666739995407, 4671.11248862715, 4794.736289504076, 4833.613310935134, 4838.564529192372, 4064.390341448777, 4075.32757095079, 4126.216018956418, 4149.668026557868, 4151.792410379499, 5476.17857321401, 5867.559933004999, 6324.652144132071, 6420.903686444707, 6527.510436717218, 4642.210824159901, 5706.714664266811, 5714.599978001542, 5725.2843394575675, 5728.112142617901, 6996.265392133803, 7017.684204210772, 7025.578465292047, 7027.191872934627, 7033.788986330418, 4562.977185114087, 4659.813607455698, 4665.603423708989, 4705.647076469244, 4729.739864307462, 7381.04046473955, 7395.630218489054, 7449.180590135081, 7460.03019607453, 7464.738734144279, 4785.665003449794, 4791.209450440708, 4796.880077998008, 4798.1123492154065, 4800.831970881002, 4857.271409288564, 4871.634627402959, 4878.8780280492565, 4885.65800393969, 4894.99652571222, 5797.623154495029, 5816.069428891357, 5816.767329306865, 5827.737751801179, 5828.59199856006, 6876.209235937931, 7085.114360704912, 7105.75129870452, 7154.570725756471, 7172.533785303938, 1692.2215780740996, 1701.8298170182882, 1720.0883531728205, 1722.7846519185102, 1724.4223185232836, 1082.5561519776854, 1089.1232557790895, 1114.1810769084716, 1115.5259014918627, 1116.2799994003822, 5359.785608575613, 6302.306746257999, 6521.771737989147, 6566.474682025427, 6594.967025164843, 7226.915962605546, 7457.925420745832, 7467.775966721007, 7473.925260747432, 7502.2122677119205, 6648.833779155463, 6661.5019367737095, 6662.700178491979, 6670.366315200048, 6678.1318428604945, 3901.7073719470586, 3937.4290727844873, 3952.466417033721, 3953.2686453006286, 3955.7428767130045, 7576.469647124759, 7757.689692412353, 7855.978722766118, 7880.172581878935, 7887.132415434297, 6306.243250809932, 6514.348565143448, 7671.424643151786, 7671.501352412075, 7675.0, 7837.804054898673, 7853.568053757098, 7858.685652573948, 7870.134285228621, 7878.527288362717, 6359.459445947146, 7200.099987501562, 7450.143233528213, 7463.738769143054, 7485.1766305707415, 6936.7225310987, 7268.745593904188, 7280.235575264521, 7287.8542429152185, 7296.124465064014, 7709.882067100351, 7796.415268854745, 7802.414818888493, 7808.9609551954145, 7814.023247094114, 3857.267119247334, 4620.1914664934175, 4673.462551806586, 4674.6026587740425, 4681.882952926204, 7420.109586849612, 7420.740274090381, 7422.443316751157, 7430.2569743027425, 7431.739889103856, 6863.519553631198, 7097.467689923174, 7112.786616401516, 7146.749863754743, 7160.749373771893, 5962.9266731988655, 6998.475114366544, 7072.328128827753, 7133.643317834191, 7147.46394020461, 6907.654617269016, 6919.8962015568795, 6927.064846245894, 6930.341617546325, 6931.584104953719, 6422.518311126591, 6559.4752419807255, 7123.323284287984, 7124.430045596237, 7154.35610795031, 6689.866202675822, 6736.808496839595, 6884.311568843075, 6953.930460695328, 6966.1988871280455], "value": 5179.291604493387, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.0587465733246, 728.6247582495015, 735.3712575747736, 750.1073722270497, 788.574169948228, 14644.560663180095, 14740.852591474082, 15080.547583572483, 15101.395944162234, 15112.924435377821, 13763.65590860229, 13845.030847840655, 13851.307434628254, 13872.0, 13872.237026963512, 738.7119023580808, 748.1844789179793, 881.3081730841291, 905.108468158863, 907.2379201470764, 914.419343716738, 916.6562539047857, 918.4213945214506, 919.9862002069974, 921.9216366608813, 950.9286803489731, 963.2775418436173, 966.6423423333376, 967.0764419978904, 970.9951450242739, 759.6581538307756, 771.6681826814468, 776.6776787633125, 958.2142643032716, 968.3899143539595, 592.7540070870626, 699.9265077166875, 709.8331892005375, 709.8864181730899, 710.683745733149, 14653.706925861423, 14733.336999834926, 14747.852521474755, 14748.336324865406, 14757.26213689322, 12385.07111966597, 12535.0, 12870.22778633285, 12893.161944473579, 12904.290264035475, 9996.950015249877, 10134.594616215343, 10200.0819926207, 10394.16846652266, 10424.270301078928, 3455.9308813823836, 3488.89533314001, 3502.8073455960166, 3532.2758834439137, 3720.962790372089, 77169.84245236326, 78852.0, 80552.0, 81264.0, 81995.0, 9290.674826381044, 9344.486053266999, 9374.390664606779, 9441.952790236142, 9822.557984890696, 20684.58630827375, 21474.463138421354, 21506.89246553757, 21519.246826361003, 21541.46146346354, 7456.85086298271, 7548.735794247175, 7588.81027974294, 7629.923700763033, 7895.131535531087, 4113.321301985199, 4122.2786012447705, 4137.54487006429, 4255.212785634668, 4390.07808360248, 3600.6939410149907, 3670.6512881276235, 3676.7242456815848, 3679.1170119171743, 3683.5948045715486, 652.0935899100255, 660.8711301296297, 663.575311800448, 671.1509939925998, 685.9039734437183, 538.4211972129976, 563.9464250896241, 577.2841676321332, 579.1775678536488, 606.266417634667, 7860.213978602208, 8169.183081691902, 8266.875996860052, 8360.414770966096, 8482.44864083845, 515.932928719264, 582.1474773609264, 582.6329412470117, 586.3432955090326, 605.1678941455499, 3273.9804709936857, 3536.91157721059, 3749.4750734897134, 3920.921581568353, 4028.1540876415693, 87625.12374876297, 88385.04534591918, 88425.55787221022, 91977.0, 92170.0, 78296.0, 78379.21620783719, 79202.81195782068, 80329.19670803334, 80889.0, 66991.0, 67080.65838683199, 67625.0, 67766.0, 68651.0, 7640.541567505968, 7756.844863102593, 8224.958875205586, 8324.209200125979, 8391.289250458754, 45050.0, 45981.31028034649, 46461.0, 47859.0, 48639.0, 49115.772105697644, 49471.50528494669, 61139.0, 63457.0, 64324.35675643184, 11035.11719062457, 11176.497057632589, 11186.83219751688, 11403.885961140615, 11731.824022639497, 11761.823572646588, 12176.939115304367, 12198.390080495763, 12422.006239500637, 12488.500459981498, 9721.222302215947, 11178.217524773512, 11840.28958262507, 11869.76260474803, 11915.523379065087, 10686.8931310689, 11361.318320900773, 11382.37396943143, 11595.420228988685, 11688.824667630162, 10925.781484370427, 10994.230403871652, 11368.94315528417, 11487.081033517466, 11703.76592468163, 9459.432434053979, 9486.193673537551, 9698.95150524271, 9701.126898579161, 10019.998000199921, 9509.809803804024, 9755.56099975517, 9791.216702663627, 9819.214462843098, 9841.704748857546], "value": 6835.174071485695, "unit": "score"}], "name": "dromaeo_css", "value": 7386.326896256374, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 119.0, 114.0, 114.0, 113.0, 113.0, 113.0, 114.0, 132.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 124.0, 155.0, 156.0, 117.0, 140.0, 119.0, 152.0, 162.0, 104.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 184.0, 184.0, 182.0, 187.0, 186.0, 185.0, 183.0, 183.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 79.0, 72.0, 151.0, 55.0, 56.0, 56.0, 67.0, 132.0, 56.0], "value": 79.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 138.0, 78.0, 81.0, 90.0, 123.0, 79.0, 80.0, 126.0], "value": 98.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 86.0, 88.0, 86.0, 89.0, 87.0, 89.0, 85.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 93.0, 93.0, 94.0, 95.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 86.0, 84.0, 85.0, 84.0, 87.0, 86.0, 84.0, 86.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 63.0, 64.0, 66.0, 66.0, 64.0, 83.0, 61.0, 59.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 68.0, 75.0, 75.0, 70.0, 67.0, 67.0, 68.0, 66.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 102.0, 101.0, 82.0, 78.0, 81.0, 93.0, 103.0, 106.0], "value": 93.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 128.0, 126.0, 129.0, 126.0, 129.0, 128.0, 128.0, 148.0, 136.0], "value": 131.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 168.0, 164.0, 165.0, 165.0, 169.0, 172.0, 166.0, 164.0], "value": 166.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 67.0, 69.0, 68.0, 69.0, 68.0, 68.0, 66.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1476.6000000000001, "alertThreshold": 2.0}]} 19:09:38 INFO - Return code: 0 19:09:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:09:38 ERROR - # TBPL SUCCESS # 19:09:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:09:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:09:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:09:38 INFO - 2016-05-24 19:09:38.215 screenresolution[1685:10293] starting screenresolution argv=screenresolution get 19:09:38 INFO - 2016-05-24 19:09:38.218 screenresolution[1685:10293] Display 0: 1600x1200x32@60 19:09:38 INFO - 2016-05-24 19:09:38.224 screenresolution[1686:10296] starting screenresolution argv=screenresolution list 19:09:38 INFO - Available Modes on Display 0 19:09:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:09:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:09:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:09:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:09:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:09:38 INFO - Intel Iris: 19:09:38 INFO - Chipset Model: Intel Iris 19:09:38 INFO - Type: GPU 19:09:38 INFO - Bus: Built-In 19:09:38 INFO - VRAM (Dynamic, Max): 1536 MB 19:09:38 INFO - Vendor: Intel (0x8086) 19:09:38 INFO - Device ID: 0x0a2e 19:09:38 INFO - Revision ID: 0x0009 19:09:38 INFO - Displays: 19:09:38 INFO - Display: 19:09:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:09:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:09:38 INFO - Display Serial Number: 1600x1200 60 19:09:38 INFO - Main Display: Yes 19:09:38 INFO - Mirror: Off 19:09:38 INFO - Online: Yes 19:09:38 INFO - Rotation: Supported 19:09:38 INFO - Return code: 0 19:09:38 INFO - Running post-action listener: _resource_record_post_action 19:09:38 INFO - Running post-run listener: _resource_record_post_run 19:09:39 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 31432192; Write bytes: 653445632; Read time: 193; Write time: 1819 19:09:39 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354564608; Write bytes: 356509696; Read time: 27743; Write time: 721 19:09:39 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 29650432; Write bytes: 295928320; Read time: 178; Write time: 1094 19:09:39 INFO - Running post-run listener: _upload_blobber_files 19:09:39 INFO - Blob upload gear active. 19:09:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:09:39 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 19:09:39 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:09:39 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:09:39 INFO - (blobuploader) - INFO - Open directory for files ... 19:09:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:09:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:09:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:09:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:09:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:09:41 INFO - (blobuploader) - INFO - Done attempting. 19:09:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:09:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:09:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:09:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:09:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:09:42 INFO - (blobuploader) - INFO - Done attempting. 19:09:42 INFO - (blobuploader) - INFO - Iteration through files over. 19:09:42 INFO - Return code: 0 19:09:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:09:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:09:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ef80461c49cf6f3597bd391162bd043a286e89c0066729c6d39d5cb5bb9d096039909ebc6d3e0cedd3936b76f3f791f909c11fb89b59009c478c2901ff8fd90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ab5a4a0c549891b45cf3615cbb88bd03d6482d00b37823138a0853ed8c21063d6a2fcaebb62a0efa3381ccef4b985b2b14d7c5493b4f2d395eb9869d9b80247"} 19:09:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:09:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:09:42 INFO - Contents: 19:09:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ef80461c49cf6f3597bd391162bd043a286e89c0066729c6d39d5cb5bb9d096039909ebc6d3e0cedd3936b76f3f791f909c11fb89b59009c478c2901ff8fd90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ab5a4a0c549891b45cf3615cbb88bd03d6482d00b37823138a0853ed8c21063d6a2fcaebb62a0efa3381ccef4b985b2b14d7c5493b4f2d395eb9869d9b80247"} 19:09:42 INFO - Running post-run listener: copy_logs_to_upload_dir 19:09:42 INFO - Copying logs to upload dir... 19:09:42 INFO - mkdir: /builds/slave/test/build/upload/logs 19:09:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1406.480597 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 26 secs) (at 2016-05-24 19:09:42.146041) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 19:09:42.149093) ========= 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.P8DsNyBSAD/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.HdXcDGfoaq/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ef80461c49cf6f3597bd391162bd043a286e89c0066729c6d39d5cb5bb9d096039909ebc6d3e0cedd3936b76f3f791f909c11fb89b59009c478c2901ff8fd90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ab5a4a0c549891b45cf3615cbb88bd03d6482d00b37823138a0853ed8c21063d6a2fcaebb62a0efa3381ccef4b985b2b14d7c5493b4f2d395eb9869d9b80247"} build_url:https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011415 build_url: 'https://queue.taskcluster.net/v1/task/RuzHijoCQq2DFhk8WGCOGA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ef80461c49cf6f3597bd391162bd043a286e89c0066729c6d39d5cb5bb9d096039909ebc6d3e0cedd3936b76f3f791f909c11fb89b59009c478c2901ff8fd90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ab5a4a0c549891b45cf3615cbb88bd03d6482d00b37823138a0853ed8c21063d6a2fcaebb62a0efa3381ccef4b985b2b14d7c5493b4f2d395eb9869d9b80247"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 19:09:42.179355) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 19:09:42.179649) ========= 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.P8DsNyBSAD/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.HdXcDGfoaq/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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.005076 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 19:09:42.231708) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 19:09:42.232028) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 19:09:42.232360) ========= ========= Total master_lag: 0.18 =========