builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0087 starttime: 1482515677.12 results: success (0) buildid: 20161223083939 builduid: 50dc7a021b564543bd6593f9970d66e0 revision: f241b4570f5396628738da4f0543f981bcc549d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 09:54:37.122733) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 09:54:37.123382) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 09:54:37.123668) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 09:54:37.185384) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 09:54:37.185765) ========= 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.vaMKyutqMt/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.25tC13NBKb/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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-12-23 09:54:37-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.4M=0.001s 2016-12-23 09:54:37 (14.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149149 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 09:54:37.359554) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 09:54:37.359883) ========= 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.vaMKyutqMt/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.25tC13NBKb/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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.026279 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 09:54:37.412157) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-23 09:54:37.412524) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev f241b4570f5396628738da4f0543f981bcc549d7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev f241b4570f5396628738da4f0543f981bcc549d7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vaMKyutqMt/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.25tC13NBKb/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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-12-23 09:54:37,509 truncating revision to first 12 chars 2016-12-23 09:54:37,509 Setting DEBUG logging. 2016-12-23 09:54:37,509 attempt 1/10 2016-12-23 09:54:37,509 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/f241b4570f53?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-23 09:54:38,482 unpacking tar archive at: graphics-f241b4570f53/testing/mozharness/ program finished with exit code 0 elapsedTime=1.245318 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-23 09:54:38.681463) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 09:54:38.681768) ========= script_repo_revision: f241b4570f5396628738da4f0543f981bcc549d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 09:54:38.682149) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 09:54:38.682427) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 09:54:38.702409) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-23 09:54:38.702801) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vaMKyutqMt/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.25tC13NBKb/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 09:54:38 INFO - MultiFileLogger online at 20161223 09:54:38 in /builds/slave/test 09:54:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 09:54:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:54:38 INFO - {'append_to_log': False, 09:54:38 INFO - 'base_work_dir': '/builds/slave/test', 09:54:38 INFO - 'blob_upload_branch': 'Graphics', 09:54:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:54:38 INFO - 'branch': 'Graphics', 09:54:38 INFO - 'buildbot_json_path': 'buildprops.json', 09:54:38 INFO - 'config_files': ('talos/mac_config.py',), 09:54:38 INFO - 'default_actions': ('clobber', 09:54:38 INFO - 'read-buildbot-config', 09:54:38 INFO - 'download-and-extract', 09:54:38 INFO - 'populate-webroot', 09:54:38 INFO - 'create-virtualenv', 09:54:38 INFO - 'install', 09:54:38 INFO - 'run-tests'), 09:54:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:54:38 INFO - 'download_minidump_stackwalk': True, 09:54:38 INFO - 'download_symbols': 'ondemand', 09:54:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:54:38 INFO - 'tooltool.py': '/tools/tooltool.py', 09:54:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:54:38 INFO - '/tools/misc-python/virtualenv.py')}, 09:54:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:54:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:54:38 INFO - 'installer_path': 'installer.exe', 09:54:38 INFO - 'log_level': 'info', 09:54:38 INFO - 'log_name': 'talos', 09:54:38 INFO - 'log_to_console': True, 09:54:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:54:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:54:38 INFO - 'opt_config_files': (), 09:54:38 INFO - 'pip_index': False, 09:54:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:54:38 INFO - 'cmd': ('bash', 09:54:38 INFO - '-c', 09:54:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:54:38 INFO - 'enabled': True, 09:54:38 INFO - 'halt_on_failure': False, 09:54:38 INFO - 'name': 'check_screen_resolution'},), 09:54:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:54:38 INFO - 'cmd': ('bash', 09:54:38 INFO - '-c', 09:54:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:54:38 INFO - 'enabled': True, 09:54:38 INFO - 'halt_on_failure': False, 09:54:38 INFO - 'name': 'check_screen_resolution'},), 09:54:38 INFO - 'run_cmd_checks_enabled': True, 09:54:38 INFO - 'sps_profile': False, 09:54:38 INFO - 'sps_profile_interval': 0, 09:54:38 INFO - 'suite': 'dromaeojs', 09:54:38 INFO - 'system_bits': '32', 09:54:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:54:38 INFO - 'title': 't-yosemite-r7-0087', 09:54:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:54:38 INFO - 'use_talos_json': True, 09:54:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:54:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:54:38 INFO - 'work_dir': 'build'} 09:54:38 INFO - [mozharness: 2016-12-23 17:54:38.914970Z] Running clobber step. 09:54:38 INFO - Running pre-action listener: _resource_record_pre_action 09:54:38 INFO - Running main action method: clobber 09:54:38 INFO - rmtree: /builds/slave/test/build 09:54:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:54:41 INFO - Running post-action listener: _resource_record_post_action 09:54:41 INFO - [mozharness: 2016-12-23 17:54:41.460390Z] Finished clobber step (success) 09:54:41 INFO - [mozharness: 2016-12-23 17:54:41.460512Z] Running read-buildbot-config step. 09:54:41 INFO - Running pre-action listener: _resource_record_pre_action 09:54:41 INFO - Running main action method: read_buildbot_config 09:54:41 INFO - Using buildbot properties: 09:54:41 INFO - { 09:54:41 INFO - "project": "", 09:54:41 INFO - "product": "firefox", 09:54:41 INFO - "script_repo_revision": "default", 09:54:41 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 09:54:41 INFO - "repository": "", 09:54:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 09:54:41 INFO - "buildid": "20161223083939", 09:54:41 INFO - "pgo_build": "False", 09:54:41 INFO - "basedir": "/builds/slave/test", 09:54:41 INFO - "buildnumber": 9, 09:54:41 INFO - "slavename": "t-yosemite-r7-0087", 09:54:41 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:54:41 INFO - "platform": "yosemite_r7", 09:54:41 INFO - "scheduler": "tests-graphics-macosx64-talos", 09:54:41 INFO - "branch": "graphics", 09:54:41 INFO - "repo_path": "projects/graphics", 09:54:41 INFO - "revision": "f241b4570f5396628738da4f0543f981bcc549d7", 09:54:41 INFO - "stage_platform": "macosx64", 09:54:41 INFO - "builduid": "50dc7a021b564543bd6593f9970d66e0", 09:54:41 INFO - "slavebuilddir": "test" 09:54:41 INFO - } 09:54:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 09:54:41 INFO - Running post-action listener: _resource_record_post_action 09:54:41 INFO - [mozharness: 2016-12-23 17:54:41.463847Z] Finished read-buildbot-config step (success) 09:54:41 INFO - [mozharness: 2016-12-23 17:54:41.463982Z] Running download-and-extract step. 09:54:41 INFO - Running pre-action listener: _resource_record_pre_action 09:54:41 INFO - Running main action method: download_and_extract 09:54:41 INFO - mkdir: /builds/slave/test/build/tests 09:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:41 INFO - trying https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 09:54:41 INFO - Downloading https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:54:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 09:54:42 INFO - Downloaded 1199 bytes. 09:54:42 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:54:42 INFO - Using the following test package requirements: 09:54:42 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:54:42 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 09:54:42 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 09:54:42 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'jsshell-mac.zip'], 09:54:42 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 09:54:42 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:54:42 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 09:54:42 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 09:54:42 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 09:54:42 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:42 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 09:54:42 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:54:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:54:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:54:42 INFO - Fetch https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:54:43 INFO - Expected file size: 16289454 09:54:43 INFO - Obtained file size: 16289454 09:54:50 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:54:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:54:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:54:50 INFO - Fetch https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:54:51 INFO - Expected file size: 16289454 09:54:51 INFO - Obtained file size: 16289454 09:54:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 09:54:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:54:57 INFO - Fetch https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 09:54:58 INFO - Expected file size: 14409776 09:54:58 INFO - Obtained file size: 14409776 09:54:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:58 INFO - trying https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:54:58 INFO - Downloading https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 09:54:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 09:55:00 INFO - Downloaded 69649191 bytes. 09:55:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:55:00 INFO - mkdir: /builds/slave/test/properties 09:55:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:55:00 INFO - Writing to file /builds/slave/test/properties/build_url 09:55:00 INFO - Contents: 09:55:00 INFO - build_url:https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:55:01 INFO - Running post-action listener: _resource_record_post_action 09:55:01 INFO - Running post-action listener: set_extra_try_arguments 09:55:01 INFO - [mozharness: 2016-12-23 17:55:01.412475Z] Finished download-and-extract step (success) 09:55:01 INFO - [mozharness: 2016-12-23 17:55:01.412627Z] Running populate-webroot step. 09:55:01 INFO - Running pre-action listener: _resource_record_pre_action 09:55:01 INFO - Running main action method: populate_webroot 09:55:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:55:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:55:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:55:01 INFO - '--tppagecycles', 09:55:01 INFO - '7'], 09:55:01 INFO - 'tests': ['tsvgm']}}, 09:55:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:55:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:55:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:55:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:55:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:55:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:55:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:55:01 INFO - 'talos_options': ['--disable-e10s'], 09:55:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:55:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:55:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:55:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:55:01 INFO - 'talos_options': ['--disable-e10s'], 09:55:01 INFO - 'tests': ['damp', 'tps']}, 09:55:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:55:01 INFO - 'tests': ['damp', 'tps']}, 09:55:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:55:01 INFO - 'tests': ['dromaeo_dom']}, 09:55:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:55:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:55:01 INFO - 'tests': ['basic_compositor_video']}, 09:55:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:55:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:55:01 INFO - 'tests': ['a11yr', 09:55:01 INFO - 'ts_paint', 09:55:01 INFO - 'tpaint', 09:55:01 INFO - 'sessionrestore', 09:55:01 INFO - 'sessionrestore_no_auto_restore', 09:55:01 INFO - 'tabpaint']}, 09:55:01 INFO - 'other-e10s': {'tests': ['a11yr', 09:55:01 INFO - 'ts_paint', 09:55:01 INFO - 'tpaint', 09:55:01 INFO - 'sessionrestore', 09:55:01 INFO - 'sessionrestore_no_auto_restore', 09:55:01 INFO - 'tabpaint']}, 09:55:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:55:01 INFO - 'ts_paint', 09:55:01 INFO - 'tpaint', 09:55:01 INFO - 'sessionrestore', 09:55:01 INFO - 'sessionrestore_no_auto_restore', 09:55:01 INFO - 'tabpaint']}, 09:55:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:55:01 INFO - 'ts_paint', 09:55:01 INFO - 'tpaint', 09:55:01 INFO - 'sessionrestore', 09:55:01 INFO - 'sessionrestore_no_auto_restore', 09:55:01 INFO - 'tabpaint']}, 09:55:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:55:01 INFO - 'tests': ['a11yr', 09:55:01 INFO - 'ts_paint', 09:55:01 INFO - 'tpaint', 09:55:01 INFO - 'sessionrestore', 09:55:01 INFO - 'sessionrestore_no_auto_restore', 09:55:01 INFO - 'tabpaint']}, 09:55:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:55:01 INFO - 'tests': ['a11yr', 09:55:01 INFO - 'ts_paint', 09:55:01 INFO - 'tpaint', 09:55:01 INFO - 'sessionrestore', 09:55:01 INFO - 'sessionrestore_no_auto_restore', 09:55:01 INFO - 'tabpaint']}, 09:55:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:55:01 INFO - 'tests': ['tsvgx', 09:55:01 INFO - 'tsvgr_opacity', 09:55:01 INFO - 'tart', 09:55:01 INFO - 'tscrollx', 09:55:01 INFO - 'cart', 09:55:01 INFO - 'tsvg_static']}, 09:55:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:55:01 INFO - 'tsvgr_opacity', 09:55:01 INFO - 'tart', 09:55:01 INFO - 'tscrollx', 09:55:01 INFO - 'cart', 09:55:01 INFO - 'tsvg_static']}, 09:55:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:55:01 INFO - 'talos_options': ['--disable-e10s'], 09:55:01 INFO - 'tests': ['tp5o']}, 09:55:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:55:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:55:01 INFO - 'talos_options': ['--disable-e10s', 09:55:01 INFO - '--xperf_path', 09:55:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:55:01 INFO - 'tests': ['tp5n']}, 09:55:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:55:01 INFO - 'talos_options': ['--xperf_path', 09:55:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:55:01 INFO - 'tests': ['tp5n']}}, 09:55:01 INFO - 'talos.zip': {'path': '', 09:55:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:55:01 INFO - Running post-action listener: _resource_record_post_action 09:55:01 INFO - [mozharness: 2016-12-23 17:55:01.430867Z] Finished populate-webroot step (success) 09:55:01 INFO - [mozharness: 2016-12-23 17:55:01.431002Z] Running create-virtualenv step. 09:55:01 INFO - Running pre-action listener: _resource_record_pre_action 09:55:01 INFO - Running main action method: create_virtualenv 09:55:01 INFO - Creating virtualenv /builds/slave/test/build/venv 09:55:01 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 09:55:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:55:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:55:01 INFO - Using real prefix '/tools/python27' 09:55:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:55:02 INFO - Installing distribute.............................................................................................................................................................................................done. 09:55:05 INFO - Installing pip.................done. 09:55:05 INFO - Return code: 0 09:55:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:55:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:55:05 INFO - Reading from file tmpfile_stdout 09:55:05 INFO - Output received: 09:55:05 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:55:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:05 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 0x10576bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82c24ed190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105549d30>, '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 0x105470db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10553ebe8>, '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.vaMKyutqMt/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.25tC13NBKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 09:55:05 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 09:55:05 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 09:55:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vaMKyutqMt/Render', 09:55:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:05 INFO - 'HOME': '/Users/cltbld', 09:55:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:05 INFO - 'LOGNAME': 'cltbld', 09:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:05 INFO - 'MOZ_NO_REMOTE': '1', 09:55:05 INFO - 'NO_EM_RESTART': '1', 09:55:05 INFO - 'PAGER': '/bin/cat', 09:55:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:05 INFO - 'PWD': '/builds/slave/test', 09:55:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:05 INFO - 'SHELL': '/bin/bash', 09:55:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.25tC13NBKb/Listeners', 09:55:05 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 09:55:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:05 INFO - 'USER': 'cltbld', 09:55:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:05 INFO - 'XPC_FLAGS': '0x0', 09:55:05 INFO - 'XPC_SERVICE_NAME': '0', 09:55:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:05 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 09:55:05 INFO - Cleaning up... 09:55:05 INFO - Return code: 0 09:55:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10576bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82c24ed190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105549d30>, '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 0x105470db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10553ebe8>, '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.vaMKyutqMt/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.25tC13NBKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 09:55:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:55:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:55:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vaMKyutqMt/Render', 09:55:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:05 INFO - 'HOME': '/Users/cltbld', 09:55:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:05 INFO - 'LOGNAME': 'cltbld', 09:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:05 INFO - 'MOZ_NO_REMOTE': '1', 09:55:05 INFO - 'NO_EM_RESTART': '1', 09:55:05 INFO - 'PAGER': '/bin/cat', 09:55:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:05 INFO - 'PWD': '/builds/slave/test', 09:55:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:05 INFO - 'SHELL': '/bin/bash', 09:55:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.25tC13NBKb/Listeners', 09:55:05 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 09:55:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:05 INFO - 'USER': 'cltbld', 09:55:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:05 INFO - 'XPC_FLAGS': '0x0', 09:55:05 INFO - 'XPC_SERVICE_NAME': '0', 09:55:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:05 INFO - Downloading/unpacking psutil>=3.1.1 09:55: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 09:55: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 09:55: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 09:55: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 09:55: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 09:55: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 09:55:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:55:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:55:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:55:09 INFO - Installing collected packages: psutil 09:55:09 INFO - Running setup.py install for psutil 09:55:10 INFO - building 'psutil._psutil_osx' extension 09:55:10 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 09:55:10 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 09:55:10 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 09:55:10 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 09:55:10 INFO - building 'psutil._psutil_posix' extension 09:55:10 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 09:55:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:55:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:55:10 INFO - ^ 09:55:11 INFO - 1 warning generated. 09:55:11 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 09:55:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:55:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:55:11 INFO - Successfully installed psutil 09:55:11 INFO - Cleaning up... 09:55:11 INFO - Return code: 0 09:55:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10576bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82c24ed190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105549d30>, '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 0x105470db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10553ebe8>, '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.vaMKyutqMt/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.25tC13NBKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 09:55:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:55:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:55:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vaMKyutqMt/Render', 09:55:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:11 INFO - 'HOME': '/Users/cltbld', 09:55:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:11 INFO - 'LOGNAME': 'cltbld', 09:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:11 INFO - 'MOZ_NO_REMOTE': '1', 09:55:11 INFO - 'NO_EM_RESTART': '1', 09:55:11 INFO - 'PAGER': '/bin/cat', 09:55:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:11 INFO - 'PWD': '/builds/slave/test', 09:55:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:11 INFO - 'SHELL': '/bin/bash', 09:55:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.25tC13NBKb/Listeners', 09:55:11 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 09:55:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:11 INFO - 'USER': 'cltbld', 09:55:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:11 INFO - 'XPC_FLAGS': '0x0', 09:55:11 INFO - 'XPC_SERVICE_NAME': '0', 09:55:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:55: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 09:55: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 09:55: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 09:55: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 09:55: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 09:55: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 09:55:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:55:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:55:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:55:14 INFO - Installing collected packages: mozsystemmonitor 09:55:14 INFO - Running setup.py install for mozsystemmonitor 09:55:14 INFO - Successfully installed mozsystemmonitor 09:55:14 INFO - Cleaning up... 09:55:14 INFO - Return code: 0 09:55:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10576bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82c24ed190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105549d30>, '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 0x105470db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10553ebe8>, '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.vaMKyutqMt/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.25tC13NBKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 09:55:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:55:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:55:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vaMKyutqMt/Render', 09:55:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:14 INFO - 'HOME': '/Users/cltbld', 09:55:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:14 INFO - 'LOGNAME': 'cltbld', 09:55:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:14 INFO - 'MOZ_NO_REMOTE': '1', 09:55:14 INFO - 'NO_EM_RESTART': '1', 09:55:14 INFO - 'PAGER': '/bin/cat', 09:55:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:14 INFO - 'PWD': '/builds/slave/test', 09:55:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:14 INFO - 'SHELL': '/bin/bash', 09:55:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.25tC13NBKb/Listeners', 09:55:14 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 09:55:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:14 INFO - 'USER': 'cltbld', 09:55:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:14 INFO - 'XPC_FLAGS': '0x0', 09:55:14 INFO - 'XPC_SERVICE_NAME': '0', 09:55:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:14 INFO - Downloading/unpacking jsonschema==2.5.1 09:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:55:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:17 INFO - Downloading functools32-3.2.3-2.tar.gz 09:55:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:55:17 INFO - warning: no files found matching '*.txt' 09:55:17 INFO - no previously-included directories found matching 'build' 09:55:17 INFO - no previously-included directories found matching 'dist' 09:55:17 INFO - no previously-included directories found matching '.git*' 09:55:17 INFO - Installing collected packages: jsonschema, functools32 09:55:17 INFO - Running setup.py install for functools32 09:55:17 INFO - warning: no files found matching '*.txt' 09:55:17 INFO - no previously-included directories found matching 'build' 09:55:17 INFO - no previously-included directories found matching 'dist' 09:55:17 INFO - no previously-included directories found matching '.git*' 09:55:17 INFO - Successfully installed jsonschema functools32 09:55:17 INFO - Cleaning up... 09:55:18 INFO - Return code: 0 09:55:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:55:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10576bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82c24ed190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105549d30>, '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 0x105470db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10553ebe8>, '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.vaMKyutqMt/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.25tC13NBKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 09:55:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:55:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:55:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vaMKyutqMt/Render', 09:55:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:18 INFO - 'HOME': '/Users/cltbld', 09:55:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:18 INFO - 'LOGNAME': 'cltbld', 09:55:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:18 INFO - 'MOZ_NO_REMOTE': '1', 09:55:18 INFO - 'NO_EM_RESTART': '1', 09:55:18 INFO - 'PAGER': '/bin/cat', 09:55:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:18 INFO - 'PWD': '/builds/slave/test', 09:55:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:18 INFO - 'SHELL': '/bin/bash', 09:55:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.25tC13NBKb/Listeners', 09:55:18 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 09:55:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:18 INFO - 'USER': 'cltbld', 09:55:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:18 INFO - 'XPC_FLAGS': '0x0', 09:55:18 INFO - 'XPC_SERVICE_NAME': '0', 09:55:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:55:18 INFO - Cleaning up... 09:55:18 INFO - Return code: 0 09:55:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:55:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:18 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 0x10576bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82c24ed190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105549d30>, '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 0x105470db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10553ebe8>, '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.vaMKyutqMt/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.25tC13NBKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 09:55:18 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 09:55:18 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 09:55:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vaMKyutqMt/Render', 09:55:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:18 INFO - 'HOME': '/Users/cltbld', 09:55:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:18 INFO - 'LOGNAME': 'cltbld', 09:55:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:18 INFO - 'MOZ_NO_REMOTE': '1', 09:55:18 INFO - 'NO_EM_RESTART': '1', 09:55:18 INFO - 'PAGER': '/bin/cat', 09:55:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:18 INFO - 'PWD': '/builds/slave/test', 09:55:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:18 INFO - 'SHELL': '/bin/bash', 09:55:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.25tC13NBKb/Listeners', 09:55:18 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 09:55:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:18 INFO - 'USER': 'cltbld', 09:55:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:18 INFO - 'XPC_FLAGS': '0x0', 09:55:18 INFO - 'XPC_SERVICE_NAME': '0', 09:55:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:18 INFO - Downloading/unpacking blobuploader==1.2.4 09:55:18 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 09:55:18 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 09:55:18 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 09:55:18 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 09:55:18 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 09:55:18 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 09:55:21 INFO - Downloading blobuploader-1.2.4.tar.gz 09:55:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:55:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:55:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:55:22 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 09:55:22 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 09:55:22 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 09:55:22 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 09:55:22 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 09:55:22 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 09:55:22 INFO - Downloading docopt-0.6.1.tar.gz 09:55:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:55:22 INFO - Installing collected packages: blobuploader, requests, docopt 09:55:22 INFO - Running setup.py install for blobuploader 09:55:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:55:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:55:22 INFO - Running setup.py install for requests 09:55:23 INFO - Running setup.py install for docopt 09:55:23 INFO - Successfully installed blobuploader requests docopt 09:55:23 INFO - Cleaning up... 09:55:23 INFO - Return code: 0 09:55:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:55:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:23 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 0x10576bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82c24ed190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105549d30>, '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 0x105470db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10553ebe8>, '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.vaMKyutqMt/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.25tC13NBKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 09:55:23 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 09:55:23 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 09:55:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vaMKyutqMt/Render', 09:55:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:23 INFO - 'HOME': '/Users/cltbld', 09:55:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:23 INFO - 'LOGNAME': 'cltbld', 09:55:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:23 INFO - 'MOZ_NO_REMOTE': '1', 09:55:23 INFO - 'NO_EM_RESTART': '1', 09:55:23 INFO - 'PAGER': '/bin/cat', 09:55:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:23 INFO - 'PWD': '/builds/slave/test', 09:55:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:23 INFO - 'SHELL': '/bin/bash', 09:55:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.25tC13NBKb/Listeners', 09:55:23 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 09:55:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:23 INFO - 'USER': 'cltbld', 09:55:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:23 INFO - 'XPC_FLAGS': '0x0', 09:55:23 INFO - 'XPC_SERVICE_NAME': '0', 09:55:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:55:23 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-iZxEBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:55:23 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-UUfa6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:55:23 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-HvXpqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:55:24 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-g5vO6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:55:24 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-7Ntec5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:55:24 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-WLZdNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:55:24 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-Tqiaje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:55:24 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-wgd3Sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:55:24 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-U_331g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:55:24 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-ams9AJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:55:24 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-sBhQCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:55:24 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-mvfKFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:55:25 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-vZ2I5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:55:25 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-htjxhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:55:25 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-QSbRdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:55:25 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-RyBDHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:55:25 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-E3ILy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:55:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:55:25 INFO - Running setup.py install for manifestparser 09:55:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:55:25 INFO - Running setup.py install for mozcrash 09:55:25 INFO - Running setup.py install for mozdebug 09:55:25 INFO - Running setup.py install for mozdevice 09:55:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:55:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:55:26 INFO - Running setup.py install for mozfile 09:55:26 INFO - Running setup.py install for mozhttpd 09:55:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:55:26 INFO - Running setup.py install for mozinfo 09:55:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:55:26 INFO - Running setup.py install for mozInstall 09:55:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:55:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:55:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:55:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:55:26 INFO - Running setup.py install for mozleak 09:55:26 INFO - Running setup.py install for mozlog 09:55:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:55:27 INFO - Running setup.py install for moznetwork 09:55:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:55:27 INFO - Running setup.py install for mozprocess 09:55:27 INFO - Running setup.py install for mozprofile 09:55:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:55:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:55:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:55:27 INFO - Running setup.py install for mozrunner 09:55:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:55:27 INFO - Running setup.py install for mozscreenshot 09:55:27 INFO - Running setup.py install for moztest 09:55:27 INFO - Running setup.py install for mozversion 09:55:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:55:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:55:28 INFO - Cleaning up... 09:55:28 INFO - Return code: 0 09:55:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:55: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:28 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 0x10576bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82c24ed190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105549d30>, '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 0x105470db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10553ebe8>, '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.vaMKyutqMt/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.25tC13NBKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 09:55:28 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 09:55:28 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 09:55:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vaMKyutqMt/Render', 09:55:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:28 INFO - 'HOME': '/Users/cltbld', 09:55:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:28 INFO - 'LOGNAME': 'cltbld', 09:55:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:28 INFO - 'MOZ_NO_REMOTE': '1', 09:55:28 INFO - 'NO_EM_RESTART': '1', 09:55:28 INFO - 'PAGER': '/bin/cat', 09:55:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:28 INFO - 'PWD': '/builds/slave/test', 09:55:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:28 INFO - 'SHELL': '/bin/bash', 09:55:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.25tC13NBKb/Listeners', 09:55:28 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 09:55:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:28 INFO - 'USER': 'cltbld', 09:55:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:28 INFO - 'XPC_FLAGS': '0x0', 09:55:28 INFO - 'XPC_SERVICE_NAME': '0', 09:55:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:55:28 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-5bDE65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:55:28 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)) 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:55:28 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-TjrrE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:55:28 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-72WQFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:55:28 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)) 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:55:28 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-pf4OHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:55:28 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-ggIuUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:55:28 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)) 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:55:28 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-rzu4z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:55:29 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)) 09:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:55:29 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-99xfCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:55:29 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)) 09:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:55:29 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-P4Mlcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:55:29 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)) 09:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:55:29 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-CeA_j8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:55:29 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)) 09:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:55:29 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-KrP_Bd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 09:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:55:29 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-UCx8zr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:55:29 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)) 09:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:55:29 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-wdZwLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:55:29 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-p6jBSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:55:29 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)) 09:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:55:29 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-hxH_Yv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 09:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:55:29 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-H0OhKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:55:30 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)) 09:55:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:55:30 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-sODvI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:55:30 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)) 09:55:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:55:30 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-rfA2qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:55:30 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)) 09:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:55:30 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)) 09:55:30 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:55:30 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:55:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:55:30 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 09:55:30 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 09:55:30 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 09:55:30 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 09:55:30 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 09:55:30 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 09:55:32 INFO - Downloading blessings-1.6.tar.gz 09:55:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:55:33 INFO - Installing collected packages: blessings 09:55:33 INFO - Running setup.py install for blessings 09:55:33 INFO - Successfully installed blessings 09:55:33 INFO - Cleaning up... 09:55:33 INFO - Return code: 0 09:55:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:55:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:55:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:55:33 INFO - Reading from file tmpfile_stdout 09:55:33 INFO - Current package versions: 09:55:33 INFO - blessings == 1.6 09:55:33 INFO - blobuploader == 1.2.4 09:55:33 INFO - docopt == 0.6.1 09:55:33 INFO - functools32 == 3.2.3-2 09:55:33 INFO - jsonschema == 2.5.1 09:55:33 INFO - manifestparser == 1.1 09:55:33 INFO - mozInstall == 1.12 09:55:33 INFO - mozcrash == 1.0 09:55:33 INFO - mozdebug == 0.1 09:55:33 INFO - mozdevice == 0.49 09:55:33 INFO - mozfile == 1.2 09:55:33 INFO - mozhttpd == 0.7 09:55:33 INFO - mozinfo == 0.9 09:55:33 INFO - mozleak == 0.1 09:55:33 INFO - mozlog == 3.4 09:55:33 INFO - moznetwork == 0.27 09:55:33 INFO - mozprocess == 0.23 09:55:33 INFO - mozprofile == 0.28 09:55:33 INFO - mozrunner == 6.13 09:55:33 INFO - mozscreenshot == 0.1 09:55:33 INFO - mozsystemmonitor == 0.3 09:55:33 INFO - moztest == 0.7 09:55:33 INFO - mozversion == 1.4 09:55:33 INFO - psutil == 3.1.1 09:55:33 INFO - requests == 1.2.3 09:55:33 INFO - wsgiref == 0.1.2 09:55:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:33 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 0x10576bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82c24ed190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105549d30>, '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 0x105470db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10553ebe8>, '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.vaMKyutqMt/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.25tC13NBKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 09:55:33 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 09:55:33 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 09:55:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vaMKyutqMt/Render', 09:55:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:33 INFO - 'HOME': '/Users/cltbld', 09:55:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:33 INFO - 'LOGNAME': 'cltbld', 09:55:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:33 INFO - 'MOZ_NO_REMOTE': '1', 09:55:33 INFO - 'NO_EM_RESTART': '1', 09:55:33 INFO - 'PAGER': '/bin/cat', 09:55:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:33 INFO - 'PWD': '/builds/slave/test', 09:55:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:33 INFO - 'SHELL': '/bin/bash', 09:55:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.25tC13NBKb/Listeners', 09:55:33 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 09:55:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:33 INFO - 'USER': 'cltbld', 09:55:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:33 INFO - 'XPC_FLAGS': '0x0', 09:55:33 INFO - 'XPC_SERVICE_NAME': '0', 09:55:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:33 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)) 09:55:33 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)) 09:55:33 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)) 09:55:33 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)) 09:55:33 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)) 09:55:33 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)) 09:55:33 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)) 09:55:33 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)) 09:55:33 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)) 09:55:33 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)) 09:55:33 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)) 09:55:33 INFO - Cleaning up... 09:55:33 INFO - Return code: 0 09:55:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:33 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 0x10576bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82c24ed190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105549d30>, '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 0x105470db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10553ebe8>, '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.vaMKyutqMt/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.25tC13NBKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 09:55:33 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 09:55:33 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 09:55:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vaMKyutqMt/Render', 09:55:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:33 INFO - 'HOME': '/Users/cltbld', 09:55:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:33 INFO - 'LOGNAME': 'cltbld', 09:55:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:33 INFO - 'MOZ_NO_REMOTE': '1', 09:55:33 INFO - 'NO_EM_RESTART': '1', 09:55:33 INFO - 'PAGER': '/bin/cat', 09:55:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:33 INFO - 'PWD': '/builds/slave/test', 09:55:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:33 INFO - 'SHELL': '/bin/bash', 09:55:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.25tC13NBKb/Listeners', 09:55:33 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 09:55:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:33 INFO - 'USER': 'cltbld', 09:55:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:33 INFO - 'XPC_FLAGS': '0x0', 09:55:33 INFO - 'XPC_SERVICE_NAME': '0', 09:55:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:55:34 INFO - Cleaning up... 09:55:34 INFO - Return code: 0 09:55:34 INFO - Running post-action listener: _resource_record_post_action 09:55:34 INFO - Running post-action listener: _start_resource_monitoring 09:55:34 INFO - Starting resource monitoring. 09:55:34 INFO - [mozharness: 2016-12-23 17:55:34.109788Z] Finished create-virtualenv step (success) 09:55:34 INFO - [mozharness: 2016-12-23 17:55:34.110814Z] Running install step. 09:55:34 INFO - Running pre-action listener: _resource_record_pre_action 09:55:34 INFO - Running main action method: install 09:55:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:55:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:55:34 INFO - Reading from file tmpfile_stdout 09:55:34 INFO - Detecting whether we're running mozinstall >=1.0... 09:55:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:55:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:55:34 INFO - Reading from file tmpfile_stdout 09:55:34 INFO - Output received: 09:55:34 INFO - Usage: mozinstall [options] installer 09:55:34 INFO - Options: 09:55:34 INFO - -h, --help show this help message and exit 09:55:34 INFO - -d DEST, --destination=DEST 09:55:34 INFO - Directory to install application into. [default: 09:55:34 INFO - "/builds/slave/test"] 09:55:34 INFO - --app=APP Application being installed. [default: firefox] 09:55:34 INFO - mkdir: /builds/slave/test/build/application 09:55:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:55:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:55:56 INFO - Reading from file tmpfile_stdout 09:55:56 INFO - Output received: 09:55:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:55:56 INFO - Running post-action listener: _resource_record_post_action 09:55:56 INFO - [mozharness: 2016-12-23 17:55:56.432329Z] Finished install step (success) 09:55:56 INFO - [mozharness: 2016-12-23 17:55:56.432493Z] Running run-tests step. 09:55:56 INFO - Running pre-action listener: _resource_record_pre_action 09:55:56 INFO - Running main action method: run_tests 09:55:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:55:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:55:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:55:56 INFO - 2016-12-23 09:55:56.447 screenresolution[1628:7999] starting screenresolution argv=screenresolution get 09:55:56 INFO - 2016-12-23 09:55:56.453 screenresolution[1628:7999] Display 0: 1600x1200x32@60 09:55:56 INFO - 2016-12-23 09:55:56.459 screenresolution[1629:8004] starting screenresolution argv=screenresolution list 09:55:56 INFO - Available Modes on Display 0 09:55:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:55:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:55:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:55:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:55:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:55:56 INFO - Intel Iris: 09:55:56 INFO - Chipset Model: Intel Iris 09:55:56 INFO - Type: GPU 09:55:56 INFO - Bus: Built-In 09:55:56 INFO - VRAM (Dynamic, Max): 1536 MB 09:55:56 INFO - Vendor: Intel (0x8086) 09:55:56 INFO - Device ID: 0x0a2e 09:55:56 INFO - Revision ID: 0x0009 09:55:56 INFO - Displays: 09:55:56 INFO - Display: 09:55:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:55:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:55:56 INFO - Display Serial Number: 1600x1200 60 09:55:56 INFO - Main Display: Yes 09:55:56 INFO - Mirror: Off 09:55:56 INFO - Online: Yes 09:55:56 INFO - Rotation: Supported 09:55:56 INFO - Return code: 0 09:55:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:55:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:55:56 INFO - Python 2.7.3 09:55:56 INFO - Return code: 0 09:55:56 INFO - grabbing minidump binary from tooltool 09:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:56 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105549d30>, '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 0x105470db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10553ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:55:56 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 09:55:56 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 09:55:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:55:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:55:56 INFO - Return code: 0 09:55:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:55:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:55:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:55:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:55:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:55:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0087', '--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 09:55:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0087 --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 09:55:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vaMKyutqMt/Render', 09:55:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:56 INFO - 'HOME': '/Users/cltbld', 09:55:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:56 INFO - 'LOGNAME': 'cltbld', 09:55:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:55:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:55:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:56 INFO - 'MOZ_NO_REMOTE': '1', 09:55:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:55:56 INFO - 'NO_EM_RESTART': '1', 09:55:56 INFO - 'PAGER': '/bin/cat', 09:55:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:56 INFO - 'PWD': '/builds/slave/test', 09:55:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:55:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:56 INFO - 'SHELL': '/bin/bash', 09:55:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.25tC13NBKb/Listeners', 09:55:56 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 09:55:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:56 INFO - 'USER': 'cltbld', 09:55:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:56 INFO - 'XPC_FLAGS': '0x0', 09:55:56 INFO - 'XPC_SERVICE_NAME': '0', 09:55:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0087', '--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 09:55:57 INFO - mozversion application_buildid: 20161223083939 09:55:57 INFO - mozversion application_changeset: f241b4570f5396628738da4f0543f981bcc549d7 09:55:57 INFO - mozversion application_display_name: Nightly 09:55:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:55:57 INFO - mozversion application_name: Firefox 09:55:57 INFO - mozversion application_remotingname: firefox 09:55:57 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 09:55:57 INFO - mozversion application_vendor: Mozilla 09:55:57 INFO - mozversion application_version: 53.0a1 09:55:57 INFO - mozversion platform_buildid: 20161223083939 09:55:57 INFO - mozversion platform_changeset: f241b4570f5396628738da4f0543f981bcc549d7 09:55:57 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 09:55:57 INFO - mozversion platform_version: 53.0a1 09:55:57 INFO - using testdate: 1482515757 09:55:57 INFO - actual date: 1482515757 09:55:57 INFO - starting webserver on 'localhost:49241' 09:55:57 INFO - SUITE-START | Running 2 tests 09:55:57 INFO - TEST-START | dromaeo_css 09:55:57 INFO - operating with platform_type : mac_ 09:55:57 INFO - Initialising browser for dromaeo_css test... 09:55:57 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmpRPb_5h/profile http://localhost:49241/getInfo.html) 09:55:57 INFO - PROCESS | 1639 | 1482515757594 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:55:58 INFO - PROCESS | 1639 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 09:55:58 INFO - PROCESS | 1639 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 09:55:59 INFO - PROCESS | 1639 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 09:55:59 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 09:55:59 INFO - PROCESS | 1639 | colorDepth:24 09:55:59 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 09:55:59 INFO - PROCESS | 1639 | __metrics 09:55:59 INFO - PROCESS | 1639 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:55:59 INFO - PROCESS | 1639 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:56:00 INFO - TEST-INFO | 1639: exit 0 09:56:00 INFO - Browser initialized. 09:56:00 INFO - Running cycle 1/1 for dromaeo_css test... 09:56:00 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmpRPb_5h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:12 INFO - PROCESS | 1642 | RSS: Main: 234557440 09:56:12 INFO - PROCESS | 1642 | 09:57:03 INFO - PROCESS | 1642 | 1482515823423 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] 09:59:16 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 09:59:18 INFO - PROCESS | 1642 | RSS: Main: 397725696 09:59:18 INFO - PROCESS | 1642 | 10:01:00 INFO - PROCESS | 1642 | ************************* 10:01:00 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 10:01:00 INFO - PROCESS | 1642 | 10:01:00 INFO - PROCESS | 1642 | Full message: ReferenceError: fetch is not defined 10:01:00 INFO - PROCESS | 1642 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 10:01:00 INFO - PROCESS | 1642 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 10:01:00 INFO - PROCESS | 1642 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 10:01:00 INFO - PROCESS | 1642 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 10:01:00 INFO - PROCESS | 1642 | TaskImpl@resource://gre/modules/Task.jsm:277:3 10:01:00 INFO - PROCESS | 1642 | asyncFunction@resource://gre/modules/Task.jsm:252:14 10:01:00 INFO - PROCESS | 1642 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 10:01:00 INFO - PROCESS | 1642 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 10:01:00 INFO - PROCESS | 1642 | TaskImpl@resource://gre/modules/Task.jsm:277:3 10:01:00 INFO - PROCESS | 1642 | asyncFunction@resource://gre/modules/Task.jsm:252:14 10:01:00 INFO - PROCESS | 1642 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 10:01:00 INFO - PROCESS | 1642 | 10:01:00 INFO - PROCESS | 1642 | ************************* 10:02:21 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 10:02:22 INFO - PROCESS | 1642 | RSS: Main: 368791552 10:02:22 INFO - PROCESS | 1642 | 10:05:24 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 10:05:25 INFO - PROCESS | 1642 | RSS: Main: 365096960 10:05:25 INFO - PROCESS | 1642 | 10:08:27 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 10:08:28 INFO - PROCESS | 1642 | RSS: Main: 342134784 10:08:28 INFO - PROCESS | 1642 | 10:11:31 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 10:11:32 INFO - PROCESS | 1642 | RSS: Main: 334893056 10:11:32 INFO - PROCESS | 1642 | 10:14:35 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 10:14:35 INFO - PROCESS | 1642 | RSS: Main: 444899328 10:14:35 INFO - PROCESS | 1642 | 10:14:35 INFO - PROCESS | 1642 | __start_tp_report 10:14:35 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 10:14:35 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 10:14:35 INFO - PROCESS | 1642 | |i|pagename|runs| 10:14:35 INFO - PROCESS | 1642 | |0;dojo.html;3276.9180770480734;3312.5031245313185;3331.750118741095;3357.8488967996436;3363.1760218746404;5610.214569960209;5667.886642267157;5670.829875103754;5727.140928860667;5764.221830052941;5725.942740572593;5733.340665823432;5801.622894511854;5804.361520232771;5831.854203644911;5086.745662716868;5159.690418574897;5174.146265866128;5218.425973142951;5247.134222853225;5192.324997750287;5307.840764777063;5320.255164277004;5343.946560534384;5379.408265090857;5126.846194614131;5271.762770675329;5271.789128434877;5274.55166310864;5308.495692909168;5145.665531740462;5207.713575753332;5210.843674689765;5215.895682086375;5226.111561034633;5122.667026643294;5148.510891465305;5195.870103247411;5219.982103489821;5252.212168174766;5716.714164291769;5730.140478928152;5739.3686694463995;5741.62679425836;5757.798477053283;6253.3433989430905;6259.061140828867;6269.749210031593;6274.090256912722;6302.684865756693;6112.442689633878;6166.9383306167265;6175.505959523227;6211.627302361872;6218.81343559694;5765.798197063083;5799.797007104731;5811.389804070512;5859.648421094748;5864.765409383577;6209.161763161916;6261.843453913598;6286.119943207955;6290.685465726695;6294.055891616203;6016.849578760566;6056.454919057306;6150.876982460326;6154.1999540059505;6158.753649854045;6177.320494745574;6198.0702894566175;6210.658413787195;6232.657203853741;6303.7793677222;6227.346128656516;6231.594946328474;6236.563440559208;6239.188905442264;6254.781082662085;6183.103449999815;6197.25632924052;6253.7185826637915;6257.9687101564205;6281.277653069864;4809.230523116355;4874.756262186947;4875.219964805614;4922.778474968635;4949.010197960422;4359.324304732771;4434.290513517822;4458.974435879701;4464.531224221475;4465.218586747306;5595.21666966625;5696.971515142481;5861.267341582302;5866.090755932837;5887.146363777314;6223.751049958041;6291.496680265568;6305.306416294204;6318.462930650946;6321.936780632135;5660.799189185138;5808.94191058084;5846.976779063647;5870.178175055496;5879.588428809942;5185.248139019821;5214.243934629533;5228.215767634885;5347.973260133675;5384.5692344612335;6241.21984751906;6282.183316168869;6293.087502312231;6301.023341382093;6305.432511074026;6238.220222472191;6305.936940630535;6317.96841015792;6318.936810632019;6330.177076979963;6182.474489668249;6193.225846769154;6258.030005349178;6272.529560283088;6279.026750853625;6165.0444181152;6184.5052395809125;6198.969005154945;6203.2245969253845;6207.317195108625;6265.655388953652;6279.591826531261;6282.371762823864;6298.496120310456;6299.622022678471;5095.898082038264;5994.670293133745;6060.757569697163;6147.323794382525;6147.508199344131;4773.856784296477;6085.847853803691;6115.0827375892195;6121.971262793606;6167.814965551041;5164.32863727713;5997.880042399041;5999.160117583455;6013.939860601513;6015.759369625166;5965.34381218057;5991.191189189403;6008.639481631116;6159.168512250789;6184.319724830355;5951.910721339097;5952.910706339322;5954;5956.76172953077;5961.642301461926 10:14:35 INFO - PROCESS | 1642 | |1;ext.html;427.2544410004543;484.0971587988402;491.09393169602095;495.737259252596;503.98992020159596;28318;31524;38825.41761873571;40013.19973600526;40377.79811100948;41257.38113928294;41448;41535;41585.168296634125;41611.58388416115;9391.530423478833;9468.384555003902;9477.004914483983;9487.905120948772;9511.904880951171;8363.330933525334;8590.441621294627;10879.510422030988;11146.777064458705;11153.944230278878;11340.149488788333;11399.487023083939;11410.71473213168;11424;11464.541418343299;11358;11440.713982150513;11444.027257683023;11449.370284634342;11475.827862582068;8389.286910612605;8593.312534997185;10985.176111791568;11104.389258590774;11262.155338349574;38493.80753096216;39081;39116.804415977735;39223.8038809807;39308.21383572312;3385.0014245797547;3424.7945123292684;3692.46459263408;3975.7415767975276;4072.9796351018354;29207;29265.41469170626;29999.550006749916;30042.54936175959;30091.247718807204;3129.6713845046397;3136.0591822453403;3306.024722706807;3350.408555935911;3355;469857.99999999657;470032;472061;472886;475105;56496.43503564994;57368.426315737146;57508.51109566875;57511.84976300534;57605.42394576084;59450.70274648599;59510.40489595135;59617.70191149103;59851.70074149688;59938.40061599328;15071.4724984625;15474.613134671723;15483.612909677124;15503.302351393988;15503.914725969074;11981;12033.977111945407;12043.819342709867;12047.638570842713;12069.879301207051;13562.932185339207;13877.791833122512;13885.652858678613;14971.101733896001;15207.923960380127;7187.89218161728;7189.5686258824635;7192.748253811196;7201.207867134611;7321.560706357635;850.8766228896775;973.5473005052543;1024.3802499487851;1038.7714702765531;1087.124864484082;26437.867810660824;27814.582781258297;28400.857995710303;28859.567106493;29682;1078.6440474643348;1080.5569716416232;1110.8611423572054;1119.485036883043;1149.0290704354807;14980.250987450583;15072.924635376752;15177.089374637557;15215.163166025754;15217.543473695807;614003;617503;617831;618578;621223;57138.7143064282;57392.42607573871;57493.850122997304;57497.42502574921;57612;1375.5391943698774;1376.6971266321393;1399.6780740429758;1402.8106205662514;1404.487362112842;27966.580501292905;28238.294042648275;28364.43271134607;28764;29720.405591888473;47574.52425475701;48787.512124879715;48805.33959953089;48843.75578122229;51679.70800730012;576393;580543;583479;584076;584575;23711;24081;24124.87937560301;24145.75854241435;24162;33261;39231.80384098061;40949;41029;43208.567914320454;34701.30597388089;34802.82598586991;35118.47322290219;35890.64109358977;35989.82005089958;34669.47995780116;34980.30039399249;35050.474242885866;35281.64718352886;35303;32527.51208731918;32553.674463255065;34068.65931340655;34344.14139646529;34655.78704745322;29629.5555566671;30893.536596950613;30953.380932380776;31153.37693246077;31653.36693266168;23243.53512929766;23502.32716086372;23531.41171470727;23576.528469430858;23815.880920595286 10:14:35 INFO - PROCESS | 1642 | |2;jquery.html;339.86915037710475;369.20620665569027;369.80769999600227;372.89931718436026;398.75078076202374;14691;14818.925905370472;15856.127912964768;20010.399688009384;20738.792612073874;19675.70486442701;19794.802051979477;20118.698219526683;20363.49091272719;20847.687284690703;5819.621724587888;5864.325602555718;6023.614568649214;6137.048757442603;6146.477549408283;5916;6016.458518733313;6067.423594758491;6085.543584231178;6087.482563982045;4172.144710334389;4396.670249731267;6363.554551181419;6632.834179145511;6653.035309880064;6444;6638.601683898982;6709.698063587126;6717.395434410878;6733.3603307685935;5809.3609702932645;5814.970925145347;5820.767169313222;5824.970875145639;5862.560307976919;25074.623880641804;25189.984801215858;25322;25754.227373178648;25857.612135817977;2183.6942828004094;2252.808511276527;2288.4736510602656;2408.5303365843665;2500.674912261394;14895.77656335156;15564.377424903103;15836.366545338173;15880.920595396949;16249.756253656205;1840.9079546022645;1864.6177533605578;1888.1125870840683;1911.4934542346289;1963.9312624058089;85355.57322213412;105972.47013764882;107039.99999999921;111942;116241.41879290549;10941.179411544166;11016.504257308441;11125.443727813577;11353.205275630791;11367.431628418546;13710.45158193681;13925.442982280798;13976;14009.649758756113;14014.719705606034;6938.444924406035;8448.493090414595;8500;8595.3553483489;8624.18070283322;8390.804597701053;8422.326213902874;8525.44584601999;8623.525706086226;8653.826923461496;6664.100346453264;6712.932870671329;6772.458203343721;6777.01733248686;6785.050092986986;1639.1967935711655;1764.8263904503617;1782.1712903499936;1814.5554339186983;1817.7818661760673;842.1914961636897;921.6221349246777;923.8244733500613;982.1357205658975;985.5515740338129;21732.456688582595;22538.549229015327;22548.549029019327;22589.2093776717;22681.092756289894;886.9666838133631;986.8569057486624;1018.3584341864578;1022.9130523905551;1076.1874784537624;11140.804497969648;11670.474828632732;11911.04711623068;11927.343996080299;11971.52113915451;424719;536441;540530.7840764783;553110;557564;73914.26085739181;100307.49846250722;101273.49363253136;103785.96214037763;108096;921.3550514639645;987.7185002274462;1024.1499555369026;1024.2318261304022;1026.563710423082;15552.92223538875;20041;20334.881581512567;20474.488137796674;20495.69256461184;33658.66341336555;42679.57320426756;43207.135857282046;50462.7476862628;54499.277575853965;68855.65572172106;70818.64590677014;72531.91202131867;73672.63163684147;74735;5661.688607126647;7339.559626422431;7418;7458.664360103699;7630.542167469969;7627.961860190663;9647.565859536197;11401.315921044765;12128.696782580506;12997.285149316878;8161.877571836309;11276.548938042386;11394.046158109311;11471.311721296748;11727.530898763955;9325.160735533835;10443.477826108512;11281.041111505614;11581.478833452345;11640.88359116398;11095.611653592085;11230.382328971658;11289.887101128883;11399.259048161843;11584.768304633692;7250.274972502708;7296.489245752747;7341.082364704412;7592.177877309517;7689.423293252917;10643.94678026605;11511.884881151082;11553.191276610893;11558.595449159238;11651.88348116508 10:14:35 INFO - PROCESS | 1642 | |3;mootools.html;1441.8990670653054;1499.4077339450916;1681.8234085421032;1801.720733286341;1874.3908229825302;7837.098733645633;8290.70982515612;8303.169683031694;8303.626336814843;8356.707515236967;7274.672639731211;7588.279113484224;7600.7339743109005;7650.273224043707;7669.616519174033;2081.708560801489;2234.318532847481;2306.630939049752;2306.7577904320055;2315.4558678710573;2290.4273931517123;2307.538492301538;2315.872627005514;2317.733460652021;2320;2228.977710222893;2293.4381076636164;2294.8623082615095;2299.0688771047667;2300.5973954557885;2242.069541140425;2276.0895641743436;2277.6697378880053;2279.5099053703534;2281.3726225288015;2280.760520145394;2294.5410917816334;2316.779905908936;2317.40906068953;2320.280712978982;7635.427342949301;7719.459637825355;7997;8167.836643267101;8187.345012399053;4945.950540494585;5104.412992505835;5107.336046313955;5124.897502049938;5128.871778205537;2966.347403571211;3269;3428.9142771430666;3510.7542472026967;3546.8758593449106;997.491279447487;1037.1495373793493;1082.0586090101663;1086.1202426034858;1088.7985722641258;10542;10560;10584.994425529563;10597.364158150458;10609.57561697539;4212.831486740496;4483.887902802456;4549.431321084864;4559.6124329431705;4581.610563102107;9128.817423651622;9143.954280228556;9144.542772861463;9151.542422878963;9152.633894644272;5081.923771143436;5107.284980102789;5111.207762796773;5121.667091639031;5128.974355128201;4507.57178068083;4535.795889184995;4623.815047398134;4675.509071547506;4678.578927896506;3652;3703.5555733312176;3709.647583479565;3732.253549290153;3790.677792387623;1251.205484517338;1256.9685757855943;1258.1633213912828;1270.8474983001913;1274.5602767045355;772.7102336623766;825.6449726617612;881.8420701019954;887.1926546842343;930.042056681619;5082.364704411949;5108.335916330853;5200.193969934666;5294.788208471618;5437.972810135924;406.77627304982144;417.62205204290166;423.0755798580112;424.2363745258522;445.9286514157719;3495.405781017233;3517.577890653138;3546.4325707886615;3608.314420260142;3638.7089032877307;9378.85931711025;9589.568469418893;9721.70834874955;9726.124648781502;9751.658691945748;9627.951860240793;9656.565454554422;9677.564509597085;9682.225421966226;9686.176674982706;6298.338674439118;7541.321281084729;7546.1321947976685;7653.234676532302;7671.501352412075;5299.364076310867;5340.145576707708;5458.617896747349;5482.479164479368;5523.8895222094525;7597.430192735456;8933.46399216049;9015;9046.140616641409;9114.680986165453;8968.372213944962;9085.091490851126;9115.316351273814;9228.861567076634;9282.860757088514;6941.548799328028;7114.181869085121;7144.1784194818265;7145.1783044948415;7147.928520714726;7616.428767842544;7619.771406857803;7651.234876512527;7713.807154821175;7758.262965018314;6933.4106600939485;6946.9652651736415;6969.75605853813;7035.542689725152;7056.858862822817;6766.661666916536;6825.931740682529;6864.141982252218;6934.826629334307;6960.9303906962305;5996.730147143302;6114.510839132948;6120.418560236771;6138.14066030765;6150.384961503813;4546.636269098531;4702.600278976325;4709.340692303149;4745.549172828576;4792.185328494234;3718.5537735471917;3956.8219430125137;4258.957410425856;4318.050028993616;4374.846880359172 10:14:35 INFO - PROCESS | 1642 | |4;prototype.html;349.7044996977554;359.14165145302724;360.6934106009892;367.9779213247205;368.4712437651968;4664.44026716794;4884.902301953967;5104.361954755651;5370.31484257871;5386.653336665833;4763.618910487161;5228.973855130719;5268.841934741964;5334.759935802888;5380;3775.9056023599355;3949.447077409158;4152.359817871954;4172.979135104335;4231.724937879043;4177.603127702863;4189.685773566979;4205.621494065534;4228.429162063113;4233.364995250707;4140.627343539081;4149.958500414988;4176.853810116647;4201.794058119481;4213.262679031172;3679.466477360781;3795.411711184771;4033.576474470168;4039.4950631171105;4199.895002624959;3700.833462494194;3721.5720192177705;3722.088088418328;3726.888193354204;3731.2350968051483;4886.657933944625;5080.452994569876;5233.267342572043;5263.342047255102;5321.547668448186;4003.939940900889;4787.688800227974;4798.0164066366315;4843.7234499785045;4848.490908454597;5677.347105082927;5680.920625081221;5681.914771278434;5695.5443564514735;5722.37053924068;4073.205724883649;4098.692598055128;4100.815463304159;4113.9794301028605;4115.238680844053;4521.502634710179;5071.568916642127;5854.736536855894;5917.408259174092;5953.315368732565;3732.3654978653694;4050.9797451012555;4179.370045682186;4196.622303992655;4207.810648520824;3811.866584669523;3838.731288809812;4222.472190976128;4230.878817113467;4252.576031661923;4063.4603372891943;4744.288356746516;4850.029994001214;4851.95148048515;4887.29134275528;4862.07620552094;5693.202951586781;5760.913586296209;5771.336296325976;5801.53587712982;1321.662975941129;1388.1809732258016;1399.944002239899;1401.0332870319446;1408.830940287172;917.7430319510547;980.4656462228103;985.3741326810676;988.8665030220828;995.2833959549258;5146.742662866916;5299.231611416323;5302.840914772563;5315.787368505294;5318.468153184728;4869.853904382803;5265.315508983807;5547.057000309957;5758.827235182951;5790.420957904176;4593.586577208067;5186.481351864859;5279.313689220377;5281.230787686168;5351.652142610717;2660.7605315521696;2877.8992735254164;2892.3781387001895;2902.3034471727055;2919.3213902006432;5177.637565370388;5458.972705136449;5480.479354461319;5641.689707066151;5686.914696279477;5340.946590534123;5528.419515950767;5676.545876329884;5706.9429305707245;6011.428914253139;6068.4841788448475;6077.68484376098;6092.969535152384;6094.512439004869;6096.634201947809;5197.8440646780655;5498.340199175965;5798.971005144947;5877.853053673522;5915.053591425351;4862.440819305825;5080.568151706999;5147.124988751996;5698.857528561819;5765.068701984882;5078.695278283315;5562.694051827189;5829.329627092939;6019.187409699634;6072.423119803611;3314.105191598303;3747.5690295616355;3870.27772641172;3911.1004468972865;3912.667423269054;5177.670048478125;5192.221166825007;5635.042042852807;5794.507466865363;5858.619189752652;5114.309568208244;5507.638677327693;5645.403114567345;5671.971640141938;5738.942610574008;5007.669899516781;5354.46455354477;5675.659460432387;5731.570132240182;5750.856228594319;5248.343957005374;5325.695204674879;5616.803411880564;5621.494065534122;5649.265595472562;4914.459409464885;5406.107992181246;5546.699713670359;5607.971960140173;5612.5202374526425;5185.299984502044;5514.310711161105;5559.27729395176;5561.053631229038;5567.244214114039 10:14:35 INFO - PROCESS | 1642 | |5;yui.html;717.307797974954;744.880819068949;774.5894675821813;814.9796255093626;824.3487644760637;15050.924745376271;15733.685326293467;15748.842511574881;15784.526464206077;15803.367865285374;14219.71560568788;14361.138331700105;14394.640133996656;14395;14405.927970360133;700.1318365227132;722.8084557592242;751.1812124783985;873.4017198219225;883.8276536075435;863.4905405810571;870;881.383031877685;882.6557642519423;886.7339798060556;924.1867156901917;925.5418567808944;926.5089502563618;926.5922993882704;928.4197376639601;728.7449392712562;750.474667732584;925.791696868206;925.939813912097;927.3647551427226;607.6536374266669;653.9215294164683;690.848013437043;696.3663066609361;698.6786078403941;14641.707165856624;14725.70548589033;14745.262736863222;14787.964842461031;14797.704045919128;13272.800907986388;13492.15473590032;13496.460141594325;13601.863981360259;13689.726205475934;12495.687607809787;12729.618111456672;12877.291748953803;12911.806322905164;12950.417231224617;4504.256797628387;4532.138893610204;4533.909321813545;4715.858524244278;4749.732167326892;88760.24795041018;102474;103613.48193259061;105046.47476762644;106479;10890.074343680717;11043.83434248487;11107;11227.438628068563;11472.4837382318;21536.892315538324;21848;21851.562968740534;21917.01373438199;22163.44591385196;6619.404253617197;7571.280728330799;7621.6570254338685;7633.1985141559335;7648.770536883902;4256.787160642018;4355.847545335898;4366.476022877275;4418.889527761831;4536.274196128604;3401.064707205538;3504.1940353718787;3515.82420878955;3516.630753770817;3517.3317069756663;591.54155529465;645.6610279603245;645.8675971425846;647.664557149489;653.6176336843;501.8845665496956;517.7799435240008;625.9248890133122;641.9229692436937;656.0880376276979;8569.742907712778;8901.421407608485;9010.27917766577;9122.270218382513;9160.450372977642;606.1147153939264;632.5319263744888;636.4049613611288;637.0126304228428;655.3479288108363;4400.515943246241;4419.389186876178;4435.844745433958;4449.911001780011;4509.977450112729;96729;96747;97376.51311743396;98192;98522.52216216763;71589.64205178941;76382.23617763752;79016;81011.59494202609;84312.15687843043;56975.715121424124;75062.8740568892;75129;75541.62229188818;77182.45635087267;8273.255407013397;8573.485590864568;8584.399092063495;8645.438046526955;8946.329025323257;50762.49237507577;50838;51324.74337628288;51821.82187186083;52972.67568310823;66739.33042678238;68603.65698171477;68867.31132688609;69104.6544767273;70722.64638676979;10530.36817790935;11125.888741112485;11667.59163429276;11687.824682629938;11789.646310610353;11518.884811151782;11582.478788454371;11682.94158529202;11734.946384382869;11764.470598823245;11417;11829;11952.58165964187;12154.939225303817;12159.148859579744;11648.242864213798;11650.688409043165;11716.297022178696;11738.647840564796;11806.409679516162;11426.714332141762;11451;11456.885431145582;12035.939820300842;13041.673958151123;11119.888801112209;11144.164187685794;11522;11765.823512647488;11850.229735067192;10647.094996925038;11473.959420635378;11682.649520514398;11700.648980530257;11768.352740599006 10:14:35 INFO - PROCESS | 1642 | __end_tp_report 10:14:35 INFO - PROCESS | 1642 | __start_cc_report 10:14:35 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,1794 10:14:35 INFO - PROCESS | 1642 | __end_cc_report 10:14:35 INFO - PROCESS | 1642 | __startTimestamp1482516875878__endTimestamp 10:14:35 INFO - PROCESS | 1642 | 10:14:35 INFO - PROCESS | 1642 | ------- Summary: start ------- 10:14:35 INFO - PROCESS | 1642 | Number of tests: 6 10:14:35 INFO - PROCESS | 1642 | 10:14:35 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5738.81 Median:5961.64 stddev:651.77 (10.9%) stddev-sans-first:624.50 10:14:35 INFO - PROCESS | 1642 | Values: 3276.9 3312.5 3331.8 3357.8 3363.2 5610.2 5667.9 5670.8 5727.1 5764.2 5725.9 5733.3 5801.6 5804.4 5831.9 5086.7 5159.7 5174.1 5218.4 5247.1 5192.3 5307.8 5320.3 5343.9 5379.4 5126.8 5271.8 5271.8 5274.6 5308.5 5145.7 5207.7 5210.8 5215.9 5226.1 5122.7 5148.5 5195.9 5220.0 5252.2 5716.7 5730.1 5739.4 5741.6 5757.8 6253.3 6259.1 6269.7 6274.1 6302.7 6112.4 6166.9 6175.5 6211.6 6218.8 5765.8 5799.8 5811.4 5859.6 5864.8 6209.2 6261.8 6286.1 6290.7 6294.1 6016.8 6056.5 6150.9 6154.2 6158.8 6177.3 6198.1 6210.7 6232.7 6303.8 6227.3 6231.6 6236.6 6239.2 6254.8 6183.1 6197.3 6253.7 6258.0 6281.3 4809.2 4874.8 4875.2 4922.8 4949.0 4359.3 4434.3 4459.0 4464.5 4465.2 5595.2 5697.0 5861.3 5866.1 5887.1 6223.8 6291.5 6305.3 6318.5 6321.9 5660.8 5808.9 5847.0 5870.2 5879.6 5185.2 5214.2 5228.2 5348.0 5384.6 6241.2 6282.2 6293.1 6301.0 6305.4 6238.2 6305.9 6318.0 6318.9 6330.2 6182.5 6193.2 6258.0 6272.5 6279.0 6165.0 6184.5 6199.0 6203.2 6207.3 6265.7 6279.6 6282.4 6298.5 6299.6 5095.9 5994.7 6060.8 6147.3 6147.5 4773.9 6085.8 6115.1 6122.0 6167.8 5164.3 5997.9 5999.2 6013.9 6015.8 5965.3 5991.2 6008.6 6159.2 6184.3 5951.9 5952.9 5954.0 5956.8 5961.6 10:14:35 INFO - PROCESS | 1642 | 10:14:35 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:67985.49 Median:18746.73 stddev:149948.56 (799.9%) stddev-sans-first:150283.41 10:14:35 INFO - PROCESS | 1642 | Values: 427.3 484.1 491.1 495.7 504.0 28318.0 31524.0 38825.4 40013.2 40377.8 41257.4 41448.0 41535.0 41585.2 41611.6 9391.5 9468.4 9477.0 9487.9 9511.9 8363.3 8590.4 10879.5 11146.8 11153.9 11340.1 11399.5 11410.7 11424.0 11464.5 11358.0 11440.7 11444.0 11449.4 11475.8 8389.3 8593.3 10985.2 11104.4 11262.2 38493.8 39081.0 39116.8 39223.8 39308.2 3385.0 3424.8 3692.5 3975.7 4073.0 29207.0 29265.4 29999.6 30042.5 30091.2 3129.7 3136.1 3306.0 3350.4 3355.0 469858.0 470032.0 472061.0 472886.0 475105.0 56496.4 57368.4 57508.5 57511.8 57605.4 59450.7 59510.4 59617.7 59851.7 59938.4 15071.5 15474.6 15483.6 15503.3 15503.9 11981.0 12034.0 12043.8 12047.6 12069.9 13562.9 13877.8 13885.7 14971.1 15207.9 7187.9 7189.6 7192.7 7201.2 7321.6 850.9 973.5 1024.4 1038.8 1087.1 26437.9 27814.6 28400.9 28859.6 29682.0 1078.6 1080.6 1110.9 1119.5 1149.0 14980.3 15072.9 15177.1 15215.2 15217.5 614003.0 617503.0 617831.0 618578.0 621223.0 57138.7 57392.4 57493.9 57497.4 57612.0 1375.5 1376.7 1399.7 1402.8 1404.5 27966.6 28238.3 28364.4 28764.0 29720.4 47574.5 48787.5 48805.3 48843.8 51679.7 576393.0 580543.0 583479.0 584076.0 584575.0 23711.0 24081.0 24124.9 24145.8 24162.0 33261.0 39231.8 40949.0 41029.0 43208.6 34701.3 34802.8 35118.5 35890.6 35989.8 34669.5 34980.3 35050.5 35281.6 35303.0 32527.5 32553.7 34068.7 34344.1 34655.8 29629.6 30893.5 30953.4 31153.4 31653.4 23243.5 23502.3 23531.4 23576.5 23815.9 10:14:35 INFO - PROCESS | 1642 | 10:14:35 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:31443.91 Median:25132.30 stddev:87166.00 (346.8%) stddev-sans-first:87379.24 10:14:35 INFO - PROCESS | 1642 | Values: 339.9 369.2 369.8 372.9 398.8 14691.0 14818.9 15856.1 20010.4 20738.8 19675.7 19794.8 20118.7 20363.5 20847.7 5819.6 5864.3 6023.6 6137.0 6146.5 5916.0 6016.5 6067.4 6085.5 6087.5 4172.1 4396.7 6363.6 6632.8 6653.0 6444.0 6638.6 6709.7 6717.4 6733.4 5809.4 5815.0 5820.8 5825.0 5862.6 25074.6 25190.0 25322.0 25754.2 25857.6 2183.7 2252.8 2288.5 2408.5 2500.7 14895.8 15564.4 15836.4 15880.9 16249.8 1840.9 1864.6 1888.1 1911.5 1963.9 85355.6 105972.5 107040.0 111942.0 116241.4 10941.2 11016.5 11125.4 11353.2 11367.4 13710.5 13925.4 13976.0 14009.6 14014.7 6938.4 8448.5 8500.0 8595.4 8624.2 8390.8 8422.3 8525.4 8623.5 8653.8 6664.1 6712.9 6772.5 6777.0 6785.1 1639.2 1764.8 1782.2 1814.6 1817.8 842.2 921.6 923.8 982.1 985.6 21732.5 22538.5 22548.5 22589.2 22681.1 887.0 986.9 1018.4 1022.9 1076.2 11140.8 11670.5 11911.0 11927.3 11971.5 424719.0 536441.0 540530.8 553110.0 557564.0 73914.3 100307.5 101273.5 103786.0 108096.0 921.4 987.7 1024.1 1024.2 1026.6 15552.9 20041.0 20334.9 20474.5 20495.7 33658.7 42679.6 43207.1 50462.7 54499.3 68855.7 70818.6 72531.9 73672.6 74735.0 5661.7 7339.6 7418.0 7458.7 7630.5 7628.0 9647.6 11401.3 12128.7 12997.3 8161.9 11276.5 11394.0 11471.3 11727.5 9325.2 10443.5 11281.0 11581.5 11640.9 11095.6 11230.4 11289.9 11399.3 11584.8 7250.3 7296.5 7341.1 7592.2 7689.4 10643.9 11511.9 11553.2 11558.6 11651.9 10:14:35 INFO - PROCESS | 1642 | 10:14:35 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5242.88 Median:5109.77 stddev:2877.26 (56.3%) stddev-sans-first:2871.15 10:14:35 INFO - PROCESS | 1642 | Values: 1441.9 1499.4 1681.8 1801.7 1874.4 7837.1 8290.7 8303.2 8303.6 8356.7 7274.7 7588.3 7600.7 7650.3 7669.6 2081.7 2234.3 2306.6 2306.8 2315.5 2290.4 2307.5 2315.9 2317.7 2320.0 2229.0 2293.4 2294.9 2299.1 2300.6 2242.1 2276.1 2277.7 2279.5 2281.4 2280.8 2294.5 2316.8 2317.4 2320.3 7635.4 7719.5 7997.0 8167.8 8187.3 4946.0 5104.4 5107.3 5124.9 5128.9 2966.3 3269.0 3428.9 3510.8 3546.9 997.5 1037.1 1082.1 1086.1 1088.8 10542.0 10560.0 10585.0 10597.4 10609.6 4212.8 4483.9 4549.4 4559.6 4581.6 9128.8 9144.0 9144.5 9151.5 9152.6 5081.9 5107.3 5111.2 5121.7 5129.0 4507.6 4535.8 4623.8 4675.5 4678.6 3652.0 3703.6 3709.6 3732.3 3790.7 1251.2 1257.0 1258.2 1270.8 1274.6 772.7 825.6 881.8 887.2 930.0 5082.4 5108.3 5200.2 5294.8 5438.0 406.8 417.6 423.1 424.2 445.9 3495.4 3517.6 3546.4 3608.3 3638.7 9378.9 9589.6 9721.7 9726.1 9751.7 9628.0 9656.6 9677.6 9682.2 9686.2 6298.3 7541.3 7546.1 7653.2 7671.5 5299.4 5340.1 5458.6 5482.5 5523.9 7597.4 8933.5 9015.0 9046.1 9114.7 8968.4 9085.1 9115.3 9228.9 9282.9 6941.5 7114.2 7144.2 7145.2 7147.9 7616.4 7619.8 7651.2 7713.8 7758.3 6933.4 6947.0 6969.8 7035.5 7056.9 6766.7 6825.9 6864.1 6934.8 6960.9 5996.7 6114.5 6120.4 6138.1 6150.4 4546.6 4702.6 4709.3 4745.5 4792.2 3718.6 3956.8 4259.0 4318.1 4374.8 10:14:35 INFO - PROCESS | 1642 | 10:14:35 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4608.55 Median:5181.49 stddev:1366.67 (26.4%) stddev-sans-first:1332.59 10:14:35 INFO - PROCESS | 1642 | Values: 349.7 359.1 360.7 368.0 368.5 4664.4 4884.9 5104.4 5370.3 5386.7 4763.6 5229.0 5268.8 5334.8 5380.0 3775.9 3949.4 4152.4 4173.0 4231.7 4177.6 4189.7 4205.6 4228.4 4233.4 4140.6 4150.0 4176.9 4201.8 4213.3 3679.5 3795.4 4033.6 4039.5 4199.9 3700.8 3721.6 3722.1 3726.9 3731.2 4886.7 5080.5 5233.3 5263.3 5321.5 4003.9 4787.7 4798.0 4843.7 4848.5 5677.3 5680.9 5681.9 5695.5 5722.4 4073.2 4098.7 4100.8 4114.0 4115.2 4521.5 5071.6 5854.7 5917.4 5953.3 3732.4 4051.0 4179.4 4196.6 4207.8 3811.9 3838.7 4222.5 4230.9 4252.6 4063.5 4744.3 4850.0 4852.0 4887.3 4862.1 5693.2 5760.9 5771.3 5801.5 1321.7 1388.2 1399.9 1401.0 1408.8 917.7 980.5 985.4 988.9 995.3 5146.7 5299.2 5302.8 5315.8 5318.5 4869.9 5265.3 5547.1 5758.8 5790.4 4593.6 5186.5 5279.3 5281.2 5351.7 2660.8 2877.9 2892.4 2902.3 2919.3 5177.6 5459.0 5480.5 5641.7 5686.9 5340.9 5528.4 5676.5 5706.9 6011.4 6068.5 6077.7 6093.0 6094.5 6096.6 5197.8 5498.3 5799.0 5877.9 5915.1 4862.4 5080.6 5147.1 5698.9 5765.1 5078.7 5562.7 5829.3 6019.2 6072.4 3314.1 3747.6 3870.3 3911.1 3912.7 5177.7 5192.2 5635.0 5794.5 5858.6 5114.3 5507.6 5645.4 5672.0 5738.9 5007.7 5354.5 5675.7 5731.6 5750.9 5248.3 5325.7 5616.8 5621.5 5649.3 4914.5 5406.1 5546.7 5608.0 5612.5 5185.3 5514.3 5559.3 5561.1 5567.2 10:14:35 INFO - PROCESS | 1642 | 10:14:35 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:19547.58 Median:4535.09 stddev:27820.93 (613.5%) stddev-sans-first:27863.05 10:14:35 INFO - PROCESS | 1642 | Values: 717.3 744.9 774.6 815.0 824.3 15050.9 15733.7 15748.8 15784.5 15803.4 14219.7 14361.1 14394.6 14395.0 14405.9 700.1 722.8 751.2 873.4 883.8 863.5 870.0 881.4 882.7 886.7 924.2 925.5 926.5 926.6 928.4 728.7 750.5 925.8 925.9 927.4 607.7 653.9 690.8 696.4 698.7 14641.7 14725.7 14745.3 14788.0 14797.7 13272.8 13492.2 13496.5 13601.9 13689.7 12495.7 12729.6 12877.3 12911.8 12950.4 4504.3 4532.1 4533.9 4715.9 4749.7 88760.2 102474.0 103613.5 105046.5 106479.0 10890.1 11043.8 11107.0 11227.4 11472.5 21536.9 21848.0 21851.6 21917.0 22163.4 6619.4 7571.3 7621.7 7633.2 7648.8 4256.8 4355.8 4366.5 4418.9 4536.3 3401.1 3504.2 3515.8 3516.6 3517.3 591.5 645.7 645.9 647.7 653.6 501.9 517.8 625.9 641.9 656.1 8569.7 8901.4 9010.3 9122.3 9160.5 606.1 632.5 636.4 637.0 655.3 4400.5 4419.4 4435.8 4449.9 4510.0 96729.0 96747.0 97376.5 98192.0 98522.5 71589.6 76382.2 79016.0 81011.6 84312.2 56975.7 75062.9 75129.0 75541.6 77182.5 8273.3 8573.5 8584.4 8645.4 8946.3 50762.5 50838.0 51324.7 51821.8 52972.7 66739.3 68603.7 68867.3 69104.7 70722.6 10530.4 11125.9 11667.6 11687.8 11789.6 11518.9 11582.5 11682.9 11734.9 11764.5 11417.0 11829.0 11952.6 12154.9 12159.1 11648.2 11650.7 11716.3 11738.6 11806.4 11426.7 11451.0 11456.9 12035.9 13041.7 11119.9 11144.2 11522.0 11765.8 11850.2 10647.1 11474.0 11682.6 11700.6 11768.4 10:14:35 INFO - PROCESS | 1642 | -------- Summary: end -------- 10:14:35 INFO - PROCESS | 1642 | 10:14:36 INFO - TEST-INFO | 1642: exit 0 10:14:36 INFO - TEST-OK | dromaeo_css | took 1119458ms 10:14:36 INFO - TEST-START | kraken 10:14:36 INFO - operating with platform_type : mac_ 10:14:36 INFO - Initialising browser for kraken test... 10:14:36 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmpbOYwGx/profile http://localhost:49241/getInfo.html) 10:14:36 INFO - PROCESS | 1648 | 1482516876986 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 10:14:38 INFO - PROCESS | 1648 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 10:14:38 INFO - PROCESS | 1648 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 10:14:38 INFO - PROCESS | 1648 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 10:14:38 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 10:14:38 INFO - PROCESS | 1648 | colorDepth:24 10:14:38 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 10:14:38 INFO - PROCESS | 1648 | __metrics 10:14:38 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:14:38 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:14:39 INFO - TEST-INFO | 1648: exit 0 10:14:39 INFO - Browser initialized. 10:14:39 INFO - Running cycle 1/1 for kraken test... 10:14:39 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmpbOYwGx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:14:51 INFO - PROCESS | 1649 | RSS: Main: 235532288 10:14:51 INFO - PROCESS | 1649 | 10:14:54 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 10:14:55 INFO - PROCESS | 1649 | RSS: Main: 287444992 10:14:55 INFO - PROCESS | 1649 | 10:14:57 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 10:14:58 INFO - PROCESS | 1649 | RSS: Main: 288870400 10:14:58 INFO - PROCESS | 1649 | 10:15:01 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 10:15:02 INFO - PROCESS | 1649 | RSS: Main: 377921536 10:15:02 INFO - PROCESS | 1649 | 10:15:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 10:15:05 INFO - PROCESS | 1649 | RSS: Main: 283951104 10:15:05 INFO - PROCESS | 1649 | 10:15:07 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:15:07 INFO - PROCESS | 1649 | RSS: Main: 286150656 10:15:07 INFO - PROCESS | 1649 | 10:15:10 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 10:15:11 INFO - PROCESS | 1649 | RSS: Main: 328097792 10:15:11 INFO - PROCESS | 1649 | 10:15:14 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 10:15:14 INFO - PROCESS | 1649 | RSS: Main: 329052160 10:15:14 INFO - PROCESS | 1649 | 10:15:17 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 10:15:18 INFO - PROCESS | 1649 | RSS: Main: 328605696 10:15:18 INFO - PROCESS | 1649 | 10:15:19 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:15:20 INFO - PROCESS | 1649 | RSS: Main: 302981120 10:15:20 INFO - PROCESS | 1649 | 10:15:22 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:15:23 INFO - PROCESS | 1649 | RSS: Main: 310112256 10:15:23 INFO - PROCESS | 1649 | 10:15:25 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:15:25 INFO - PROCESS | 1649 | RSS: Main: 310964224 10:15:25 INFO - PROCESS | 1649 | 10:15:28 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:15:29 INFO - PROCESS | 1649 | RSS: Main: 328114176 10:15:29 INFO - PROCESS | 1649 | 10:15:32 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:15:33 INFO - PROCESS | 1649 | RSS: Main: 321941504 10:15:33 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 10:15:35 INFO - PROCESS | 1649 | RSS: Main: 366043136 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | __start_tp_report 10:15:35 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 10:15:35 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 10:15:35 INFO - PROCESS | 1649 | |i|pagename|runs| 10:15:35 INFO - PROCESS | 1649 | |0;ai-astar;113;113;110;108;103;112;113;111;113;112 10:15:35 INFO - PROCESS | 1649 | |1;audio-beat-detection;141;108;109;147;112;114;136;132;150;108 10:15:35 INFO - PROCESS | 1649 | |2;audio-dft;185;185;186;183;186;184;183;186;182;180 10:15:35 INFO - PROCESS | 1649 | |3;audio-fft;81;59;61;86;60;59;75;84;89;58 10:15:35 INFO - PROCESS | 1649 | |4;audio-oscillator;83;97;112;68;68;69;84;68;69;84 10:15:35 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;91;87;91;87;91;87;91;87;90;87 10:15:35 INFO - PROCESS | 1649 | |6;imaging-darkroom;105;147;147;100;95;95;96;98;95;95 10:15:35 INFO - PROCESS | 1649 | |7;imaging-desaturate;86;87;85;94;127;125;87;88;88;84 10:15:35 INFO - PROCESS | 1649 | |8;json-parse-financial;55;56;55;56;56;55;54;54;54;54 10:15:35 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;65;77;69;69;77;72;69;72;71;71 10:15:35 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;90;83;87;86;85;90;87;88;87;88 10:15:35 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;138;140;140;138;138;139;142;151;208;161 10:15:35 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;171;191;224;160;225;229;171;173;171;171 10:15:35 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;66;68;67;68;67;66;68;67;78;80 10:15:35 INFO - PROCESS | 1649 | __end_tp_report 10:15:35 INFO - PROCESS | 1649 | __start_cc_report 10:15:35 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,2057 10:15:35 INFO - PROCESS | 1649 | __end_cc_report 10:15:35 INFO - PROCESS | 1649 | __startTimestamp1482516935378__endTimestamp 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | ------- Summary: start ------- 10:15:35 INFO - PROCESS | 1649 | Number of tests: 14 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.50 stddev:3.19 (2.8%) stddev-sans-first:3.28 10:15:35 INFO - PROCESS | 1649 | Values: 113.0 113.0 110.0 108.0 103.0 112.0 113.0 111.0 113.0 112.0 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:125.70 Median:134.00 stddev:17.17 (12.8%) stddev-sans-first:17.30 10:15:35 INFO - PROCESS | 1649 | Values: 141.0 108.0 109.0 147.0 112.0 114.0 136.0 132.0 150.0 108.0 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:184.00 Median:185.00 stddev:2.00 (1.1%) stddev-sans-first:2.09 10:15:35 INFO - PROCESS | 1649 | Values: 185.0 185.0 186.0 183.0 186.0 184.0 183.0 186.0 182.0 180.0 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:71.20 Median:78.00 stddev:12.96 (16.6%) stddev-sans-first:13.25 10:15:35 INFO - PROCESS | 1649 | Values: 81.0 59.0 61.0 86.0 60.0 59.0 75.0 84.0 89.0 58.0 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:80.20 Median:76.00 stddev:15.01 (19.7%) stddev-sans-first:15.89 10:15:35 INFO - PROCESS | 1649 | Values: 83.0 97.0 112.0 68.0 68.0 69.0 84.0 68.0 69.0 84.0 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:90.50 stddev:2.02 (2.2%) stddev-sans-first:2.00 10:15:35 INFO - PROCESS | 1649 | Values: 91.0 87.0 91.0 87.0 91.0 87.0 91.0 87.0 90.0 87.0 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:107.30 Median:95.00 stddev:21.16 (22.3%) stddev-sans-first:22.43 10:15:35 INFO - PROCESS | 1649 | Values: 105.0 147.0 147.0 100.0 95.0 95.0 96.0 98.0 95.0 95.0 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:87.00 stddev:16.51 (19.0%) stddev-sans-first:17.18 10:15:35 INFO - PROCESS | 1649 | Values: 86.0 87.0 85.0 94.0 127.0 125.0 87.0 88.0 88.0 84.0 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:0.88 (1.6%) stddev-sans-first:0.93 10:15:35 INFO - PROCESS | 1649 | Values: 55.0 56.0 55.0 56.0 56.0 55.0 54.0 54.0 54.0 54.0 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:71.50 stddev:3.68 (5.1%) stddev-sans-first:3.14 10:15:35 INFO - PROCESS | 1649 | Values: 65.0 77.0 69.0 69.0 77.0 72.0 69.0 72.0 71.0 71.0 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:87.50 stddev:2.13 (2.4%) stddev-sans-first:1.99 10:15:35 INFO - PROCESS | 1649 | Values: 90.0 83.0 87.0 86.0 85.0 90.0 87.0 88.0 87.0 88.0 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:141.00 stddev:21.86 (15.5%) stddev-sans-first:22.79 10:15:35 INFO - PROCESS | 1649 | Values: 138.0 140.0 140.0 138.0 138.0 139.0 142.0 151.0 208.0 161.0 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.60 Median:182.00 stddev:26.91 (14.8%) stddev-sans-first:27.78 10:15:35 INFO - PROCESS | 1649 | Values: 171.0 191.0 224.0 160.0 225.0 229.0 171.0 173.0 171.0 171.0 10:15:35 INFO - PROCESS | 1649 | 10:15:35 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:68.00 stddev:5.08 (7.5%) stddev-sans-first:5.23 10:15:35 INFO - PROCESS | 1649 | Values: 66.0 68.0 67.0 68.0 67.0 66.0 68.0 67.0 78.0 80.0 10:15:35 INFO - PROCESS | 1649 | -------- Summary: end -------- 10:15:35 INFO - PROCESS | 1649 | 10:15:36 INFO - TEST-INFO | 1649: exit 0 10:15:36 INFO - TEST-OK | kraken | took 59830ms 10:15:36 INFO - SUITE-END | took 1179s 10:15:36 INFO - Completed test suite (00:19:39) 10:15:36 INFO - javascript benchmark 10:15:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3276.9180770480734, 3312.5031245313185, 3331.750118741095, 3357.8488967996436, 3363.1760218746404, 5610.214569960209, 5667.886642267157, 5670.829875103754, 5727.140928860667, 5764.221830052941, 5725.942740572593, 5733.340665823432, 5801.622894511854, 5804.361520232771, 5831.854203644911, 5086.745662716868, 5159.690418574897, 5174.146265866128, 5218.425973142951, 5247.134222853225, 5192.324997750287, 5307.840764777063, 5320.255164277004, 5343.946560534384, 5379.408265090857, 5126.846194614131, 5271.762770675329, 5271.789128434877, 5274.55166310864, 5308.495692909168, 5145.665531740462, 5207.713575753332, 5210.843674689765, 5215.895682086375, 5226.111561034633, 5122.667026643294, 5148.510891465305, 5195.870103247411, 5219.982103489821, 5252.212168174766, 5716.714164291769, 5730.140478928152, 5739.3686694463995, 5741.62679425836, 5757.798477053283, 6253.3433989430905, 6259.061140828867, 6269.749210031593, 6274.090256912722, 6302.684865756693, 6112.442689633878, 6166.9383306167265, 6175.505959523227, 6211.627302361872, 6218.81343559694, 5765.798197063083, 5799.797007104731, 5811.389804070512, 5859.648421094748, 5864.765409383577, 6209.161763161916, 6261.843453913598, 6286.119943207955, 6290.685465726695, 6294.055891616203, 6016.849578760566, 6056.454919057306, 6150.876982460326, 6154.1999540059505, 6158.753649854045, 6177.320494745574, 6198.0702894566175, 6210.658413787195, 6232.657203853741, 6303.7793677222, 6227.346128656516, 6231.594946328474, 6236.563440559208, 6239.188905442264, 6254.781082662085, 6183.103449999815, 6197.25632924052, 6253.7185826637915, 6257.9687101564205, 6281.277653069864, 4809.230523116355, 4874.756262186947, 4875.219964805614, 4922.778474968635, 4949.010197960422, 4359.324304732771, 4434.290513517822, 4458.974435879701, 4464.531224221475, 4465.218586747306, 5595.21666966625, 5696.971515142481, 5861.267341582302, 5866.090755932837, 5887.146363777314, 6223.751049958041, 6291.496680265568, 6305.306416294204, 6318.462930650946, 6321.936780632135, 5660.799189185138, 5808.94191058084, 5846.976779063647, 5870.178175055496, 5879.588428809942, 5185.248139019821, 5214.243934629533, 5228.215767634885, 5347.973260133675, 5384.5692344612335, 6241.21984751906, 6282.183316168869, 6293.087502312231, 6301.023341382093, 6305.432511074026, 6238.220222472191, 6305.936940630535, 6317.96841015792, 6318.936810632019, 6330.177076979963, 6182.474489668249, 6193.225846769154, 6258.030005349178, 6272.529560283088, 6279.026750853625, 6165.0444181152, 6184.5052395809125, 6198.969005154945, 6203.2245969253845, 6207.317195108625, 6265.655388953652, 6279.591826531261, 6282.371762823864, 6298.496120310456, 6299.622022678471, 5095.898082038264, 5994.670293133745, 6060.757569697163, 6147.323794382525, 6147.508199344131, 4773.856784296477, 6085.847853803691, 6115.0827375892195, 6121.971262793606, 6167.814965551041, 5164.32863727713, 5997.880042399041, 5999.160117583455, 6013.939860601513, 6015.759369625166, 5965.34381218057, 5991.191189189403, 6008.639481631116, 6159.168512250789, 6184.319724830355, 5951.910721339097, 5952.910706339322, 5954.0, 5956.76172953077, 5961.642301461926], "value": 5696.728298233209, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.2544410004543, 484.0971587988402, 491.09393169602095, 495.737259252596, 503.98992020159596, 28318.0, 31524.0, 38825.41761873571, 40013.19973600526, 40377.79811100948, 41257.38113928294, 41448.0, 41535.0, 41585.168296634125, 41611.58388416115, 9391.530423478833, 9468.384555003902, 9477.004914483983, 9487.905120948772, 9511.904880951171, 8363.330933525334, 8590.441621294627, 10879.510422030988, 11146.777064458705, 11153.944230278878, 11340.149488788333, 11399.487023083939, 11410.71473213168, 11424.0, 11464.541418343299, 11358.0, 11440.713982150513, 11444.027257683023, 11449.370284634342, 11475.827862582068, 8389.286910612605, 8593.312534997185, 10985.176111791568, 11104.389258590774, 11262.155338349574, 38493.80753096216, 39081.0, 39116.804415977735, 39223.8038809807, 39308.21383572312, 3385.0014245797547, 3424.7945123292684, 3692.46459263408, 3975.7415767975276, 4072.9796351018354, 29207.0, 29265.41469170626, 29999.550006749916, 30042.54936175959, 30091.247718807204, 3129.6713845046397, 3136.0591822453403, 3306.024722706807, 3350.408555935911, 3355.0, 469857.99999999657, 470032.0, 472061.0, 472886.0, 475105.0, 56496.43503564994, 57368.426315737146, 57508.51109566875, 57511.84976300534, 57605.42394576084, 59450.70274648599, 59510.40489595135, 59617.70191149103, 59851.70074149688, 59938.40061599328, 15071.4724984625, 15474.613134671723, 15483.612909677124, 15503.302351393988, 15503.914725969074, 11981.0, 12033.977111945407, 12043.819342709867, 12047.638570842713, 12069.879301207051, 13562.932185339207, 13877.791833122512, 13885.652858678613, 14971.101733896001, 15207.923960380127, 7187.89218161728, 7189.5686258824635, 7192.748253811196, 7201.207867134611, 7321.560706357635, 850.8766228896775, 973.5473005052543, 1024.3802499487851, 1038.7714702765531, 1087.124864484082, 26437.867810660824, 27814.582781258297, 28400.857995710303, 28859.567106493, 29682.0, 1078.6440474643348, 1080.5569716416232, 1110.8611423572054, 1119.485036883043, 1149.0290704354807, 14980.250987450583, 15072.924635376752, 15177.089374637557, 15215.163166025754, 15217.543473695807, 614003.0, 617503.0, 617831.0, 618578.0, 621223.0, 57138.7143064282, 57392.42607573871, 57493.850122997304, 57497.42502574921, 57612.0, 1375.5391943698774, 1376.6971266321393, 1399.6780740429758, 1402.8106205662514, 1404.487362112842, 27966.580501292905, 28238.294042648275, 28364.43271134607, 28764.0, 29720.405591888473, 47574.52425475701, 48787.512124879715, 48805.33959953089, 48843.75578122229, 51679.70800730012, 576393.0, 580543.0, 583479.0, 584076.0, 584575.0, 23711.0, 24081.0, 24124.87937560301, 24145.75854241435, 24162.0, 33261.0, 39231.80384098061, 40949.0, 41029.0, 43208.567914320454, 34701.30597388089, 34802.82598586991, 35118.47322290219, 35890.64109358977, 35989.82005089958, 34669.47995780116, 34980.30039399249, 35050.474242885866, 35281.64718352886, 35303.0, 32527.51208731918, 32553.674463255065, 34068.65931340655, 34344.14139646529, 34655.78704745322, 29629.5555566671, 30893.536596950613, 30953.380932380776, 31153.37693246077, 31653.36693266168, 23243.53512929766, 23502.32716086372, 23531.41171470727, 23576.528469430858, 23815.880920595286], "value": 19356.12259120563, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.86915037710475, 369.20620665569027, 369.80769999600227, 372.89931718436026, 398.75078076202374, 14691.0, 14818.925905370472, 15856.127912964768, 20010.399688009384, 20738.792612073874, 19675.70486442701, 19794.802051979477, 20118.698219526683, 20363.49091272719, 20847.687284690703, 5819.621724587888, 5864.325602555718, 6023.614568649214, 6137.048757442603, 6146.477549408283, 5916.0, 6016.458518733313, 6067.423594758491, 6085.543584231178, 6087.482563982045, 4172.144710334389, 4396.670249731267, 6363.554551181419, 6632.834179145511, 6653.035309880064, 6444.0, 6638.601683898982, 6709.698063587126, 6717.395434410878, 6733.3603307685935, 5809.3609702932645, 5814.970925145347, 5820.767169313222, 5824.970875145639, 5862.560307976919, 25074.623880641804, 25189.984801215858, 25322.0, 25754.227373178648, 25857.612135817977, 2183.6942828004094, 2252.808511276527, 2288.4736510602656, 2408.5303365843665, 2500.674912261394, 14895.77656335156, 15564.377424903103, 15836.366545338173, 15880.920595396949, 16249.756253656205, 1840.9079546022645, 1864.6177533605578, 1888.1125870840683, 1911.4934542346289, 1963.9312624058089, 85355.57322213412, 105972.47013764882, 107039.99999999921, 111942.0, 116241.41879290549, 10941.179411544166, 11016.504257308441, 11125.443727813577, 11353.205275630791, 11367.431628418546, 13710.45158193681, 13925.442982280798, 13976.0, 14009.649758756113, 14014.719705606034, 6938.444924406035, 8448.493090414595, 8500.0, 8595.3553483489, 8624.18070283322, 8390.804597701053, 8422.326213902874, 8525.44584601999, 8623.525706086226, 8653.826923461496, 6664.100346453264, 6712.932870671329, 6772.458203343721, 6777.01733248686, 6785.050092986986, 1639.1967935711655, 1764.8263904503617, 1782.1712903499936, 1814.5554339186983, 1817.7818661760673, 842.1914961636897, 921.6221349246777, 923.8244733500613, 982.1357205658975, 985.5515740338129, 21732.456688582595, 22538.549229015327, 22548.549029019327, 22589.2093776717, 22681.092756289894, 886.9666838133631, 986.8569057486624, 1018.3584341864578, 1022.9130523905551, 1076.1874784537624, 11140.804497969648, 11670.474828632732, 11911.04711623068, 11927.343996080299, 11971.52113915451, 424719.0, 536441.0, 540530.7840764783, 553110.0, 557564.0, 73914.26085739181, 100307.49846250722, 101273.49363253136, 103785.96214037763, 108096.0, 921.3550514639645, 987.7185002274462, 1024.1499555369026, 1024.2318261304022, 1026.563710423082, 15552.92223538875, 20041.0, 20334.881581512567, 20474.488137796674, 20495.69256461184, 33658.66341336555, 42679.57320426756, 43207.135857282046, 50462.7476862628, 54499.277575853965, 68855.65572172106, 70818.64590677014, 72531.91202131867, 73672.63163684147, 74735.0, 5661.688607126647, 7339.559626422431, 7418.0, 7458.664360103699, 7630.542167469969, 7627.961860190663, 9647.565859536197, 11401.315921044765, 12128.696782580506, 12997.285149316878, 8161.877571836309, 11276.548938042386, 11394.046158109311, 11471.311721296748, 11727.530898763955, 9325.160735533835, 10443.477826108512, 11281.041111505614, 11581.478833452345, 11640.88359116398, 11095.611653592085, 11230.382328971658, 11289.887101128883, 11399.259048161843, 11584.768304633692, 7250.274972502708, 7296.489245752747, 7341.082364704412, 7592.177877309517, 7689.423293252917, 10643.94678026605, 11511.884881151082, 11553.191276610893, 11558.595449159238, 11651.88348116508], "value": 9510.208256691454, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.8990670653054, 1499.4077339450916, 1681.8234085421032, 1801.720733286341, 1874.3908229825302, 7837.098733645633, 8290.70982515612, 8303.169683031694, 8303.626336814843, 8356.707515236967, 7274.672639731211, 7588.279113484224, 7600.7339743109005, 7650.273224043707, 7669.616519174033, 2081.708560801489, 2234.318532847481, 2306.630939049752, 2306.7577904320055, 2315.4558678710573, 2290.4273931517123, 2307.538492301538, 2315.872627005514, 2317.733460652021, 2320.0, 2228.977710222893, 2293.4381076636164, 2294.8623082615095, 2299.0688771047667, 2300.5973954557885, 2242.069541140425, 2276.0895641743436, 2277.6697378880053, 2279.5099053703534, 2281.3726225288015, 2280.760520145394, 2294.5410917816334, 2316.779905908936, 2317.40906068953, 2320.280712978982, 7635.427342949301, 7719.459637825355, 7997.0, 8167.836643267101, 8187.345012399053, 4945.950540494585, 5104.412992505835, 5107.336046313955, 5124.897502049938, 5128.871778205537, 2966.347403571211, 3269.0, 3428.9142771430666, 3510.7542472026967, 3546.8758593449106, 997.491279447487, 1037.1495373793493, 1082.0586090101663, 1086.1202426034858, 1088.7985722641258, 10542.0, 10560.0, 10584.994425529563, 10597.364158150458, 10609.57561697539, 4212.831486740496, 4483.887902802456, 4549.431321084864, 4559.6124329431705, 4581.610563102107, 9128.817423651622, 9143.954280228556, 9144.542772861463, 9151.542422878963, 9152.633894644272, 5081.923771143436, 5107.284980102789, 5111.207762796773, 5121.667091639031, 5128.974355128201, 4507.57178068083, 4535.795889184995, 4623.815047398134, 4675.509071547506, 4678.578927896506, 3652.0, 3703.5555733312176, 3709.647583479565, 3732.253549290153, 3790.677792387623, 1251.205484517338, 1256.9685757855943, 1258.1633213912828, 1270.8474983001913, 1274.5602767045355, 772.7102336623766, 825.6449726617612, 881.8420701019954, 887.1926546842343, 930.042056681619, 5082.364704411949, 5108.335916330853, 5200.193969934666, 5294.788208471618, 5437.972810135924, 406.77627304982144, 417.62205204290166, 423.0755798580112, 424.2363745258522, 445.9286514157719, 3495.405781017233, 3517.577890653138, 3546.4325707886615, 3608.314420260142, 3638.7089032877307, 9378.85931711025, 9589.568469418893, 9721.70834874955, 9726.124648781502, 9751.658691945748, 9627.951860240793, 9656.565454554422, 9677.564509597085, 9682.225421966226, 9686.176674982706, 6298.338674439118, 7541.321281084729, 7546.1321947976685, 7653.234676532302, 7671.501352412075, 5299.364076310867, 5340.145576707708, 5458.617896747349, 5482.479164479368, 5523.8895222094525, 7597.430192735456, 8933.46399216049, 9015.0, 9046.140616641409, 9114.680986165453, 8968.372213944962, 9085.091490851126, 9115.316351273814, 9228.861567076634, 9282.860757088514, 6941.548799328028, 7114.181869085121, 7144.1784194818265, 7145.1783044948415, 7147.928520714726, 7616.428767842544, 7619.771406857803, 7651.234876512527, 7713.807154821175, 7758.262965018314, 6933.4106600939485, 6946.9652651736415, 6969.75605853813, 7035.542689725152, 7056.858862822817, 6766.661666916536, 6825.931740682529, 6864.141982252218, 6934.826629334307, 6960.9303906962305, 5996.730147143302, 6114.510839132948, 6120.418560236771, 6138.14066030765, 6150.384961503813, 4546.636269098531, 4702.600278976325, 4709.340692303149, 4745.549172828576, 4792.185328494234, 3718.5537735471917, 3956.8219430125137, 4258.957410425856, 4318.050028993616, 4374.846880359172], "value": 4199.580059542422, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.7044996977554, 359.14165145302724, 360.6934106009892, 367.9779213247205, 368.4712437651968, 4664.44026716794, 4884.902301953967, 5104.361954755651, 5370.31484257871, 5386.653336665833, 4763.618910487161, 5228.973855130719, 5268.841934741964, 5334.759935802888, 5380.0, 3775.9056023599355, 3949.447077409158, 4152.359817871954, 4172.979135104335, 4231.724937879043, 4177.603127702863, 4189.685773566979, 4205.621494065534, 4228.429162063113, 4233.364995250707, 4140.627343539081, 4149.958500414988, 4176.853810116647, 4201.794058119481, 4213.262679031172, 3679.466477360781, 3795.411711184771, 4033.576474470168, 4039.4950631171105, 4199.895002624959, 3700.833462494194, 3721.5720192177705, 3722.088088418328, 3726.888193354204, 3731.2350968051483, 4886.657933944625, 5080.452994569876, 5233.267342572043, 5263.342047255102, 5321.547668448186, 4003.939940900889, 4787.688800227974, 4798.0164066366315, 4843.7234499785045, 4848.490908454597, 5677.347105082927, 5680.920625081221, 5681.914771278434, 5695.5443564514735, 5722.37053924068, 4073.205724883649, 4098.692598055128, 4100.815463304159, 4113.9794301028605, 4115.238680844053, 4521.502634710179, 5071.568916642127, 5854.736536855894, 5917.408259174092, 5953.315368732565, 3732.3654978653694, 4050.9797451012555, 4179.370045682186, 4196.622303992655, 4207.810648520824, 3811.866584669523, 3838.731288809812, 4222.472190976128, 4230.878817113467, 4252.576031661923, 4063.4603372891943, 4744.288356746516, 4850.029994001214, 4851.95148048515, 4887.29134275528, 4862.07620552094, 5693.202951586781, 5760.913586296209, 5771.336296325976, 5801.53587712982, 1321.662975941129, 1388.1809732258016, 1399.944002239899, 1401.0332870319446, 1408.830940287172, 917.7430319510547, 980.4656462228103, 985.3741326810676, 988.8665030220828, 995.2833959549258, 5146.742662866916, 5299.231611416323, 5302.840914772563, 5315.787368505294, 5318.468153184728, 4869.853904382803, 5265.315508983807, 5547.057000309957, 5758.827235182951, 5790.420957904176, 4593.586577208067, 5186.481351864859, 5279.313689220377, 5281.230787686168, 5351.652142610717, 2660.7605315521696, 2877.8992735254164, 2892.3781387001895, 2902.3034471727055, 2919.3213902006432, 5177.637565370388, 5458.972705136449, 5480.479354461319, 5641.689707066151, 5686.914696279477, 5340.946590534123, 5528.419515950767, 5676.545876329884, 5706.9429305707245, 6011.428914253139, 6068.4841788448475, 6077.68484376098, 6092.969535152384, 6094.512439004869, 6096.634201947809, 5197.8440646780655, 5498.340199175965, 5798.971005144947, 5877.853053673522, 5915.053591425351, 4862.440819305825, 5080.568151706999, 5147.124988751996, 5698.857528561819, 5765.068701984882, 5078.695278283315, 5562.694051827189, 5829.329627092939, 6019.187409699634, 6072.423119803611, 3314.105191598303, 3747.5690295616355, 3870.27772641172, 3911.1004468972865, 3912.667423269054, 5177.670048478125, 5192.221166825007, 5635.042042852807, 5794.507466865363, 5858.619189752652, 5114.309568208244, 5507.638677327693, 5645.403114567345, 5671.971640141938, 5738.942610574008, 5007.669899516781, 5354.46455354477, 5675.659460432387, 5731.570132240182, 5750.856228594319, 5248.343957005374, 5325.695204674879, 5616.803411880564, 5621.494065534122, 5649.265595472562, 4914.459409464885, 5406.107992181246, 5546.699713670359, 5607.971960140173, 5612.5202374526425, 5185.299984502044, 5514.310711161105, 5559.27729395176, 5561.053631229038, 5567.244214114039], "value": 4190.200326474601, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.307797974954, 744.880819068949, 774.5894675821813, 814.9796255093626, 824.3487644760637, 15050.924745376271, 15733.685326293467, 15748.842511574881, 15784.526464206077, 15803.367865285374, 14219.71560568788, 14361.138331700105, 14394.640133996656, 14395.0, 14405.927970360133, 700.1318365227132, 722.8084557592242, 751.1812124783985, 873.4017198219225, 883.8276536075435, 863.4905405810571, 870.0, 881.383031877685, 882.6557642519423, 886.7339798060556, 924.1867156901917, 925.5418567808944, 926.5089502563618, 926.5922993882704, 928.4197376639601, 728.7449392712562, 750.474667732584, 925.791696868206, 925.939813912097, 927.3647551427226, 607.6536374266669, 653.9215294164683, 690.848013437043, 696.3663066609361, 698.6786078403941, 14641.707165856624, 14725.70548589033, 14745.262736863222, 14787.964842461031, 14797.704045919128, 13272.800907986388, 13492.15473590032, 13496.460141594325, 13601.863981360259, 13689.726205475934, 12495.687607809787, 12729.618111456672, 12877.291748953803, 12911.806322905164, 12950.417231224617, 4504.256797628387, 4532.138893610204, 4533.909321813545, 4715.858524244278, 4749.732167326892, 88760.24795041018, 102474.0, 103613.48193259061, 105046.47476762644, 106479.0, 10890.074343680717, 11043.83434248487, 11107.0, 11227.438628068563, 11472.4837382318, 21536.892315538324, 21848.0, 21851.562968740534, 21917.01373438199, 22163.44591385196, 6619.404253617197, 7571.280728330799, 7621.6570254338685, 7633.1985141559335, 7648.770536883902, 4256.787160642018, 4355.847545335898, 4366.476022877275, 4418.889527761831, 4536.274196128604, 3401.064707205538, 3504.1940353718787, 3515.82420878955, 3516.630753770817, 3517.3317069756663, 591.54155529465, 645.6610279603245, 645.8675971425846, 647.664557149489, 653.6176336843, 501.8845665496956, 517.7799435240008, 625.9248890133122, 641.9229692436937, 656.0880376276979, 8569.742907712778, 8901.421407608485, 9010.27917766577, 9122.270218382513, 9160.450372977642, 606.1147153939264, 632.5319263744888, 636.4049613611288, 637.0126304228428, 655.3479288108363, 4400.515943246241, 4419.389186876178, 4435.844745433958, 4449.911001780011, 4509.977450112729, 96729.0, 96747.0, 97376.51311743396, 98192.0, 98522.52216216763, 71589.64205178941, 76382.23617763752, 79016.0, 81011.59494202609, 84312.15687843043, 56975.715121424124, 75062.8740568892, 75129.0, 75541.62229188818, 77182.45635087267, 8273.255407013397, 8573.485590864568, 8584.399092063495, 8645.438046526955, 8946.329025323257, 50762.49237507577, 50838.0, 51324.74337628288, 51821.82187186083, 52972.67568310823, 66739.33042678238, 68603.65698171477, 68867.31132688609, 69104.6544767273, 70722.64638676979, 10530.36817790935, 11125.888741112485, 11667.59163429276, 11687.824682629938, 11789.646310610353, 11518.884811151782, 11582.478788454371, 11682.94158529202, 11734.946384382869, 11764.470598823245, 11417.0, 11829.0, 11952.58165964187, 12154.939225303817, 12159.148859579744, 11648.242864213798, 11650.688409043165, 11716.297022178696, 11738.647840564796, 11806.409679516162, 11426.714332141762, 11451.0, 11456.885431145582, 12035.939820300842, 13041.673958151123, 11119.888801112209, 11144.164187685794, 11522.0, 11765.823512647488, 11850.229735067192, 10647.094996925038, 11473.959420635378, 11682.649520514398, 11700.648980530257, 11768.352740599006], "value": 7255.520032049939, "unit": "score"}], "name": "dromaeo_css", "value": 7152.617559594361, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 110.0, 108.0, 103.0, 112.0, 113.0, 111.0, 113.0, 112.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 108.0, 109.0, 147.0, 112.0, 114.0, 136.0, 132.0, 150.0, 108.0], "value": 125.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 186.0, 183.0, 186.0, 184.0, 183.0, 186.0, 182.0, 180.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 61.0, 86.0, 60.0, 59.0, 75.0, 84.0, 89.0, 58.0], "value": 71.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 97.0, 112.0, 68.0, 68.0, 69.0, 84.0, 68.0, 69.0, 84.0], "value": 80.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 91.0, 87.0, 91.0, 87.0, 91.0, 87.0, 90.0, 87.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 147.0, 147.0, 100.0, 95.0, 95.0, 96.0, 98.0, 95.0, 95.0], "value": 107.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 85.0, 94.0, 127.0, 125.0, 87.0, 88.0, 88.0, 84.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 56.0, 56.0, 55.0, 54.0, 54.0, 54.0, 54.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 77.0, 69.0, 69.0, 77.0, 72.0, 69.0, 72.0, 71.0, 71.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 83.0, 87.0, 86.0, 85.0, 90.0, 87.0, 88.0, 87.0, 88.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 140.0, 138.0, 138.0, 139.0, 142.0, 151.0, 208.0, 161.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 191.0, 224.0, 160.0, 225.0, 229.0, 171.0, 173.0, 171.0, 171.0], "value": 188.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 68.0, 67.0, 66.0, 68.0, 67.0, 78.0, 80.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1483.9999999999998, "alertThreshold": 2.0}]} 10:15:36 INFO - Return code: 0 10:15:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:15:36 INFO - # TBPL SUCCESS # 10:15:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:15:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:15:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:15:36 INFO - 2016-12-23 10:15:36.695 screenresolution[1653:10161] starting screenresolution argv=screenresolution get 10:15:36 INFO - 2016-12-23 10:15:36.697 screenresolution[1653:10161] Display 0: 1600x1200x32@60 10:15:36 INFO - 2016-12-23 10:15:36.704 screenresolution[1654:10164] starting screenresolution argv=screenresolution list 10:15:36 INFO - Available Modes on Display 0 10:15:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:15:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:15:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:15:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:15:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:15:36 INFO - Intel Iris: 10:15:36 INFO - Chipset Model: Intel Iris 10:15:36 INFO - Type: GPU 10:15:36 INFO - Bus: Built-In 10:15:36 INFO - VRAM (Dynamic, Max): 1536 MB 10:15:36 INFO - Vendor: Intel (0x8086) 10:15:36 INFO - Device ID: 0x0a2e 10:15:36 INFO - Revision ID: 0x0009 10:15:36 INFO - Displays: 10:15:36 INFO - Display: 10:15:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:15:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:15:36 INFO - Display Serial Number: 1600x1200 60 10:15:36 INFO - Main Display: Yes 10:15:36 INFO - Mirror: Off 10:15:36 INFO - Online: Yes 10:15:36 INFO - Rotation: Supported 10:15:36 INFO - Return code: 0 10:15:36 INFO - Running post-action listener: _resource_record_post_action 10:15:36 INFO - [mozharness: 2016-12-23 18:15:36.948066Z] Finished run-tests step (success) 10:15:36 INFO - Running post-run listener: _resource_record_post_run 10:15:37 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 33299456; Write bytes: 551604224; Read time: 311; Write time: 2122 10:15:37 INFO - TinderboxPrint: CPU usage
54.0% 10:15:37 INFO - TinderboxPrint: I/O read bytes / time
33,299,456 / 311 10:15:37 INFO - TinderboxPrint: I/O write bytes / time
551,604,224 / 2,122 10:15:37 INFO - TinderboxPrint: CPU idle
2,210.6 (46.0%) 10:15:37 INFO - TinderboxPrint: CPU system
968.8 (20.1%) 10:15:37 INFO - TinderboxPrint: CPU user
1,630.3 (33.9%) 10:15:37 INFO - TinderboxPrint: Swap in / out
279,367,680 / 0 10:15:37 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 246098432; Write bytes: 240314368; Read time: 17944; Write time: 453 10:15:37 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 31222784; Write bytes: 297670656; Read time: 288; Write time: 1626 10:15:37 INFO - Running post-run listener: _upload_blobber_files 10:15:37 INFO - Blob upload gear active. 10:15:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:15:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:15:37 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:15:37 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:15:37 INFO - (blobuploader) - INFO - Open directory for files ... 10:15:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:15:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:15:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:38 INFO - (blobuploader) - INFO - Done attempting. 10:15:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:15:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:15:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:39 INFO - (blobuploader) - INFO - Done attempting. 10:15:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:15:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:15:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:40 INFO - (blobuploader) - INFO - Done attempting. 10:15:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:15:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:15:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:42 INFO - (blobuploader) - INFO - Done attempting. 10:15:42 INFO - (blobuploader) - INFO - Iteration through files over. 10:15:42 INFO - Return code: 0 10:15:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:15:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:15:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/749cb23fc62813ba678d228783700e8a8650b9b0d7f60aac185dbd51ca51fe94b93d532d820823f1ee3f91699e4b0989bc62129d6c81990767a11ff21c90a0a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/71f6501e2d17fd17eb73665c17d7d3807fde20f42f11980ef68532126f6f17831ca6e02fb78b37f6808b612c8f9cad3736c81c889893c2c03cf62c70ce748849", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c8b731ab4b32d6264e83fb13045c0297d65e843c8ca2c3f6d862f12fb045b87202cf5091dfc1631e165ebf024f6ac2667e57a258ea3cd9af9d9cb75459096aa2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2fab297cfc7f848809172ca4849cef85dc87fa0f10ee51099c27974c5ed4ba67c19c54d4c79dbf07f7e9acf2a6d2ca68685839c5154ed2ba2ca0c699fc50ba7d"} 10:15:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:15:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:15:42 INFO - Contents: 10:15:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/749cb23fc62813ba678d228783700e8a8650b9b0d7f60aac185dbd51ca51fe94b93d532d820823f1ee3f91699e4b0989bc62129d6c81990767a11ff21c90a0a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/71f6501e2d17fd17eb73665c17d7d3807fde20f42f11980ef68532126f6f17831ca6e02fb78b37f6808b612c8f9cad3736c81c889893c2c03cf62c70ce748849", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c8b731ab4b32d6264e83fb13045c0297d65e843c8ca2c3f6d862f12fb045b87202cf5091dfc1631e165ebf024f6ac2667e57a258ea3cd9af9d9cb75459096aa2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2fab297cfc7f848809172ca4849cef85dc87fa0f10ee51099c27974c5ed4ba67c19c54d4c79dbf07f7e9acf2a6d2ca68685839c5154ed2ba2ca0c699fc50ba7d"} 10:15:42 INFO - Running post-run listener: copy_logs_to_upload_dir 10:15:42 INFO - Copying logs to upload dir... 10:15:42 INFO - mkdir: /builds/slave/test/build/upload/logs 10:15:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.276451 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-23 10:15:43.018230) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 10:15:43.022795) ========= 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.vaMKyutqMt/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.25tC13NBKb/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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/Graphics/sha512/749cb23fc62813ba678d228783700e8a8650b9b0d7f60aac185dbd51ca51fe94b93d532d820823f1ee3f91699e4b0989bc62129d6c81990767a11ff21c90a0a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/71f6501e2d17fd17eb73665c17d7d3807fde20f42f11980ef68532126f6f17831ca6e02fb78b37f6808b612c8f9cad3736c81c889893c2c03cf62c70ce748849", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c8b731ab4b32d6264e83fb13045c0297d65e843c8ca2c3f6d862f12fb045b87202cf5091dfc1631e165ebf024f6ac2667e57a258ea3cd9af9d9cb75459096aa2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2fab297cfc7f848809172ca4849cef85dc87fa0f10ee51099c27974c5ed4ba67c19c54d4c79dbf07f7e9acf2a6d2ca68685839c5154ed2ba2ca0c699fc50ba7d"} build_url:https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011104 build_url: 'https://queue.taskcluster.net/v1/task/HgBQYEn2R1SaY624XXo89A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/749cb23fc62813ba678d228783700e8a8650b9b0d7f60aac185dbd51ca51fe94b93d532d820823f1ee3f91699e4b0989bc62129d6c81990767a11ff21c90a0a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/71f6501e2d17fd17eb73665c17d7d3807fde20f42f11980ef68532126f6f17831ca6e02fb78b37f6808b612c8f9cad3736c81c889893c2c03cf62c70ce748849", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c8b731ab4b32d6264e83fb13045c0297d65e843c8ca2c3f6d862f12fb045b87202cf5091dfc1631e165ebf024f6ac2667e57a258ea3cd9af9d9cb75459096aa2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2fab297cfc7f848809172ca4849cef85dc87fa0f10ee51099c27974c5ed4ba67c19c54d4c79dbf07f7e9acf2a6d2ca68685839c5154ed2ba2ca0c699fc50ba7d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 10:15:43.057292) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 10:15:43.057639) ========= 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.vaMKyutqMt/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.25tC13NBKb/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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.005005 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 10:15:43.126712) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 10:15:43.127073) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 10:15:43.127769) ========= ========= Total master_lag: 0.20 =========