builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0239 starttime: 1482016552.25 results: success (0) buildid: 20161217140049 builduid: 7e2923360683441d9520500579487e1f revision: 881ef8c0e2322c6117aeaee8f07fbf4d0f22cd95 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 15:15:52.248493) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 15:15:52.248964) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 15:15:52.249248) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 15:15:52.280398) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 15:15:52.280750) ========= 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.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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-17 15:15:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.8M=0.001s 2016-12-17 15:15:52 (14.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.495684 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 15:15:52.806810) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 15:15:52.807121) ========= 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.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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.037542 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 15:15:52.878144) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-17 15:15:52.878503) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 881ef8c0e2322c6117aeaee8f07fbf4d0f22cd95 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 881ef8c0e2322c6117aeaee8f07fbf4d0f22cd95 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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-17 15:15:53,011 truncating revision to first 12 chars 2016-12-17 15:15:53,011 Setting DEBUG logging. 2016-12-17 15:15:53,011 attempt 1/10 2016-12-17 15:15:53,011 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/881ef8c0e232?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-17 15:15:54,232 unpacking tar archive at: mozilla-central-881ef8c0e232/testing/mozharness/ program finished with exit code 0 elapsedTime=1.602733 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-17 15:15:54.511353) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 15:15:54.511833) ========= script_repo_revision: 881ef8c0e2322c6117aeaee8f07fbf4d0f22cd95 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 15:15:54.512253) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 15:15:54.512532) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 15:15:54.538107) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 7 secs) (at 2016-12-17 15:15:54.538940) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 15:15:54 INFO - MultiFileLogger online at 20161217 15:15:54 in /builds/slave/test 15:15:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:15:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:15:54 INFO - {'append_to_log': False, 15:15:54 INFO - 'base_work_dir': '/builds/slave/test', 15:15:54 INFO - 'blob_upload_branch': 'Firefox', 15:15:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:15:54 INFO - 'branch': 'Firefox', 15:15:54 INFO - 'buildbot_json_path': 'buildprops.json', 15:15:54 INFO - 'config_files': ('talos/mac_config.py',), 15:15:54 INFO - 'default_actions': ('clobber', 15:15:54 INFO - 'read-buildbot-config', 15:15:54 INFO - 'download-and-extract', 15:15:54 INFO - 'populate-webroot', 15:15:54 INFO - 'create-virtualenv', 15:15:54 INFO - 'install', 15:15:54 INFO - 'run-tests'), 15:15:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:15:54 INFO - 'download_minidump_stackwalk': True, 15:15:54 INFO - 'download_symbols': 'ondemand', 15:15:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:15:54 INFO - 'tooltool.py': '/tools/tooltool.py', 15:15:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:15:54 INFO - '/tools/misc-python/virtualenv.py')}, 15:15:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:15:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:15:54 INFO - 'installer_path': 'installer.exe', 15:15:54 INFO - 'log_level': 'info', 15:15:54 INFO - 'log_name': 'talos', 15:15:54 INFO - 'log_to_console': True, 15:15:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:15:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:15:54 INFO - 'opt_config_files': (), 15:15:54 INFO - 'pip_index': False, 15:15:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:15:54 INFO - 'cmd': ('bash', 15:15:54 INFO - '-c', 15:15:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:15:54 INFO - 'enabled': True, 15:15:54 INFO - 'halt_on_failure': False, 15:15:54 INFO - 'name': 'check_screen_resolution'},), 15:15:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:15:54 INFO - 'cmd': ('bash', 15:15:54 INFO - '-c', 15:15:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:15:54 INFO - 'enabled': True, 15:15:54 INFO - 'halt_on_failure': False, 15:15:54 INFO - 'name': 'check_screen_resolution'},), 15:15:54 INFO - 'run_cmd_checks_enabled': True, 15:15:54 INFO - 'sps_profile': False, 15:15:54 INFO - 'sps_profile_interval': 0, 15:15:54 INFO - 'suite': 'dromaeojs-e10s', 15:15:54 INFO - 'system_bits': '32', 15:15:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:15:54 INFO - 'title': 't-yosemite-r7-0239', 15:15:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:15:54 INFO - 'use_talos_json': True, 15:15:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:15:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:15:54 INFO - 'work_dir': 'build'} 15:15:54 INFO - [mozharness: 2016-12-17 23:15:54.841241Z] Running clobber step. 15:15:54 INFO - Running pre-action listener: _resource_record_pre_action 15:15:54 INFO - Running main action method: clobber 15:15:54 INFO - rmtree: /builds/slave/test/build 15:15:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:15:58 INFO - Running post-action listener: _resource_record_post_action 15:15:58 INFO - [mozharness: 2016-12-17 23:15:58.546558Z] Finished clobber step (success) 15:15:58 INFO - [mozharness: 2016-12-17 23:15:58.546955Z] Running read-buildbot-config step. 15:15:58 INFO - Running pre-action listener: _resource_record_pre_action 15:15:58 INFO - Running main action method: read_buildbot_config 15:15:58 INFO - Using buildbot properties: 15:15:58 INFO - { 15:15:58 INFO - "project": "", 15:15:58 INFO - "product": "firefox", 15:15:58 INFO - "script_repo_revision": "production", 15:15:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:15:58 INFO - "repository": "", 15:15:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:15:58 INFO - "buildid": "20161217140049", 15:15:58 INFO - "pgo_build": "False", 15:15:58 INFO - "basedir": "/builds/slave/test", 15:15:58 INFO - "buildnumber": 40, 15:15:58 INFO - "slavename": "t-yosemite-r7-0239", 15:15:58 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:15:58 INFO - "platform": "yosemite_r7", 15:15:58 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:15:58 INFO - "branch": "mozilla-central", 15:15:58 INFO - "repo_path": "mozilla-central", 15:15:58 INFO - "revision": "881ef8c0e2322c6117aeaee8f07fbf4d0f22cd95", 15:15:58 INFO - "stage_platform": "macosx64", 15:15:58 INFO - "builduid": "7e2923360683441d9520500579487e1f", 15:15:58 INFO - "slavebuilddir": "test" 15:15:58 INFO - } 15:15:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 15:15:58 INFO - Running post-action listener: _resource_record_post_action 15:15:58 INFO - [mozharness: 2016-12-17 23:15:58.556228Z] Finished read-buildbot-config step (success) 15:15:58 INFO - [mozharness: 2016-12-17 23:15:58.556656Z] Running download-and-extract step. 15:15:58 INFO - Running pre-action listener: _resource_record_pre_action 15:15:58 INFO - Running main action method: download_and_extract 15:15:58 INFO - mkdir: /builds/slave/test/build/tests 15:15: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')]} 15:15:58 INFO - trying https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 15:15:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/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 15:15:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/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 15:16:00 INFO - Downloaded 1199 bytes. 15:16:00 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 15:16:00 INFO - Using the following test package requirements: 15:16:00 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:16:00 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:00 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 15:16:00 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:00 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 15:16:00 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:00 INFO - u'jsshell-mac.zip'], 15:16:00 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:00 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 15:16:00 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:16:00 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:00 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 15:16:00 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:00 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 15:16:00 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:00 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 15:16:00 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:16:00 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 15:16:00 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:16:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:16:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:16:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:16:01 INFO - Expected file size: 16275296 15:16:01 INFO - Obtained file size: 16275296 15:16:11 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 15:16:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:16:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:16:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:16:13 INFO - Expected file size: 16275296 15:16:13 INFO - Obtained file size: 16275296 15:16:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 15:16:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:16:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 15:16:23 INFO - Expected file size: 14409734 15:16:23 INFO - Obtained file size: 14409734 15:16:24 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')]} 15:16:24 INFO - trying https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:16:24 INFO - Downloading https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 15:16:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/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 15:16:27 INFO - Downloaded 69570465 bytes. 15:16:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:16:27 INFO - mkdir: /builds/slave/test/properties 15:16:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:16:27 INFO - Writing to file /builds/slave/test/properties/build_url 15:16:27 INFO - Contents: 15:16:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:16:28 INFO - Running post-action listener: _resource_record_post_action 15:16:28 INFO - Running post-action listener: set_extra_try_arguments 15:16:28 INFO - [mozharness: 2016-12-17 23:16:28.172053Z] Finished download-and-extract step (success) 15:16:28 INFO - [mozharness: 2016-12-17 23:16:28.172679Z] Running populate-webroot step. 15:16:28 INFO - Running pre-action listener: _resource_record_pre_action 15:16:28 INFO - Running main action method: populate_webroot 15:16:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:16:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:16:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:16:28 INFO - '--tppagecycles', 15:16:28 INFO - '7'], 15:16:28 INFO - 'tests': ['tsvgm']}}, 15:16:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:16:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:16:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:16:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:16:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:16:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:16:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:16:28 INFO - 'talos_options': ['--disable-e10s'], 15:16:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:16:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:16:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:16:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:16:28 INFO - 'talos_options': ['--disable-e10s'], 15:16:28 INFO - 'tests': ['damp', 'tps']}, 15:16:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:16:28 INFO - 'tests': ['damp', 'tps']}, 15:16:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:16:28 INFO - 'tests': ['dromaeo_dom']}, 15:16:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:16:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:16:28 INFO - 'tests': ['basic_compositor_video']}, 15:16:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:16:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:16:28 INFO - 'tests': ['a11yr', 15:16:28 INFO - 'ts_paint', 15:16:28 INFO - 'tpaint', 15:16:28 INFO - 'sessionrestore', 15:16:28 INFO - 'sessionrestore_no_auto_restore', 15:16:28 INFO - 'tabpaint']}, 15:16:28 INFO - 'other-e10s': {'tests': ['a11yr', 15:16:28 INFO - 'ts_paint', 15:16:28 INFO - 'tpaint', 15:16:28 INFO - 'sessionrestore', 15:16:28 INFO - 'sessionrestore_no_auto_restore', 15:16:28 INFO - 'tabpaint']}, 15:16:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:16:28 INFO - 'ts_paint', 15:16:28 INFO - 'tpaint', 15:16:28 INFO - 'sessionrestore', 15:16:28 INFO - 'sessionrestore_no_auto_restore', 15:16:28 INFO - 'tabpaint']}, 15:16:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:16:28 INFO - 'ts_paint', 15:16:28 INFO - 'tpaint', 15:16:28 INFO - 'sessionrestore', 15:16:28 INFO - 'sessionrestore_no_auto_restore', 15:16:28 INFO - 'tabpaint']}, 15:16:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:16:28 INFO - 'tests': ['a11yr', 15:16:28 INFO - 'ts_paint', 15:16:28 INFO - 'tpaint', 15:16:28 INFO - 'sessionrestore', 15:16:28 INFO - 'sessionrestore_no_auto_restore', 15:16:28 INFO - 'tabpaint']}, 15:16:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:16:28 INFO - 'tests': ['a11yr', 15:16:28 INFO - 'ts_paint', 15:16:28 INFO - 'tpaint', 15:16:28 INFO - 'sessionrestore', 15:16:28 INFO - 'sessionrestore_no_auto_restore', 15:16:28 INFO - 'tabpaint']}, 15:16:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:16:28 INFO - 'tests': ['tsvgx', 15:16:28 INFO - 'tsvgr_opacity', 15:16:28 INFO - 'tart', 15:16:28 INFO - 'tscrollx', 15:16:28 INFO - 'cart', 15:16:28 INFO - 'tsvg_static']}, 15:16:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:16:28 INFO - 'tsvgr_opacity', 15:16:28 INFO - 'tart', 15:16:28 INFO - 'tscrollx', 15:16:28 INFO - 'cart', 15:16:28 INFO - 'tsvg_static']}, 15:16:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:16:28 INFO - 'talos_options': ['--disable-e10s'], 15:16:28 INFO - 'tests': ['tp5o']}, 15:16:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:16:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:16:28 INFO - 'talos_options': ['--disable-e10s', 15:16:28 INFO - '--xperf_path', 15:16:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:16:28 INFO - 'tests': ['tp5n']}, 15:16:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:16:28 INFO - 'talos_options': ['--xperf_path', 15:16:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:16:28 INFO - 'tests': ['tp5n']}}, 15:16:28 INFO - 'talos.zip': {'path': '', 15:16:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:16:28 INFO - Running post-action listener: _resource_record_post_action 15:16:28 INFO - [mozharness: 2016-12-17 23:16:28.230528Z] Finished populate-webroot step (success) 15:16:28 INFO - [mozharness: 2016-12-17 23:16:28.230903Z] Running create-virtualenv step. 15:16:28 INFO - Running pre-action listener: _resource_record_pre_action 15:16:28 INFO - Running main action method: create_virtualenv 15:16:28 INFO - Creating virtualenv /builds/slave/test/build/venv 15:16:28 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 15:16:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:16:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:16:28 INFO - Using real prefix '/tools/python27' 15:16:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:16:29 INFO - Installing distribute.............................................................................................................................................................................................done. 15:16:34 INFO - Installing pip.................done. 15:16:34 INFO - Return code: 0 15:16:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:16:34 INFO - Reading from file tmpfile_stdout 15:16:34 INFO - Output received: 15:16:34 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) 15:16:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:16:34 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')]} 15:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:34 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')]} 15:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:34 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 0x10ced6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f05852d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb4d30>, '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 0x10cbdbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9be8>, '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.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 15:16:34 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 15:16:34 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 15:16:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn9DmVkwT7/Render', 15:16:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:34 INFO - 'HOME': '/Users/cltbld', 15:16:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:34 INFO - 'LOGNAME': 'cltbld', 15:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:34 INFO - 'MOZ_NO_REMOTE': '1', 15:16:34 INFO - 'NO_EM_RESTART': '1', 15:16:34 INFO - 'PAGER': '/bin/cat', 15:16:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:34 INFO - 'PWD': '/builds/slave/test', 15:16:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:34 INFO - 'SHELL': '/bin/bash', 15:16:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kh4dK9ZBDF/Listeners', 15:16:34 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 15:16:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:34 INFO - 'USER': 'cltbld', 15:16:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:34 INFO - 'XPC_FLAGS': '0x0', 15:16:34 INFO - 'XPC_SERVICE_NAME': '0', 15:16:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:35 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 15:16:35 INFO - Cleaning up... 15:16:35 INFO - Return code: 0 15:16:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ced6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f05852d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb4d30>, '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 0x10cbdbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9be8>, '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.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 15:16:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:16:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn9DmVkwT7/Render', 15:16:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:35 INFO - 'HOME': '/Users/cltbld', 15:16:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:35 INFO - 'LOGNAME': 'cltbld', 15:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:35 INFO - 'MOZ_NO_REMOTE': '1', 15:16:35 INFO - 'NO_EM_RESTART': '1', 15:16:35 INFO - 'PAGER': '/bin/cat', 15:16:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:35 INFO - 'PWD': '/builds/slave/test', 15:16:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:35 INFO - 'SHELL': '/bin/bash', 15:16:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kh4dK9ZBDF/Listeners', 15:16:35 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 15:16:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:35 INFO - 'USER': 'cltbld', 15:16:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:35 INFO - 'XPC_FLAGS': '0x0', 15:16:35 INFO - 'XPC_SERVICE_NAME': '0', 15:16:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:35 INFO - Downloading/unpacking psutil>=3.1.1 15:16:35 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 15:16:35 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 15:16:35 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 15:16:35 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 15:16:35 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 15:16:35 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 15:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:16:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:16:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:16:40 INFO - Installing collected packages: psutil 15:16:40 INFO - Running setup.py install for psutil 15:16:41 INFO - building 'psutil._psutil_osx' extension 15:16:41 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 15:16:41 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 15:16:42 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 15:16:42 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 15:16:42 INFO - building 'psutil._psutil_posix' extension 15:16:42 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 15:16:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:16:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:16:42 INFO - ^ 15:16:42 INFO - 1 warning generated. 15:16:42 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 15:16:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:16:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:16:42 INFO - Successfully installed psutil 15:16:42 INFO - Cleaning up... 15:16:42 INFO - Return code: 0 15:16:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:16:42 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')]} 15:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:42 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')]} 15:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:42 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 0x10ced6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f05852d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb4d30>, '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 0x10cbdbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9be8>, '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.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 15:16:42 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 15:16:42 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 15:16:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn9DmVkwT7/Render', 15:16:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:42 INFO - 'HOME': '/Users/cltbld', 15:16:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:42 INFO - 'LOGNAME': 'cltbld', 15:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:42 INFO - 'MOZ_NO_REMOTE': '1', 15:16:42 INFO - 'NO_EM_RESTART': '1', 15:16:42 INFO - 'PAGER': '/bin/cat', 15:16:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:42 INFO - 'PWD': '/builds/slave/test', 15:16:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:42 INFO - 'SHELL': '/bin/bash', 15:16:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kh4dK9ZBDF/Listeners', 15:16:42 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 15:16:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:42 INFO - 'USER': 'cltbld', 15:16:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:42 INFO - 'XPC_FLAGS': '0x0', 15:16:42 INFO - 'XPC_SERVICE_NAME': '0', 15:16:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:16:43 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 15:16:43 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 15:16:43 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 15:16:43 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 15:16:43 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 15:16:43 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 15:16:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:16:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:16:47 INFO - Installing collected packages: mozsystemmonitor 15:16:47 INFO - Running setup.py install for mozsystemmonitor 15:16:47 INFO - Successfully installed mozsystemmonitor 15:16:47 INFO - Cleaning up... 15:16:47 INFO - Return code: 0 15:16:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ced6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f05852d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb4d30>, '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 0x10cbdbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9be8>, '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.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 15:16:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn9DmVkwT7/Render', 15:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:47 INFO - 'HOME': '/Users/cltbld', 15:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:47 INFO - 'LOGNAME': 'cltbld', 15:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:47 INFO - 'MOZ_NO_REMOTE': '1', 15:16:47 INFO - 'NO_EM_RESTART': '1', 15:16:47 INFO - 'PAGER': '/bin/cat', 15:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:47 INFO - 'PWD': '/builds/slave/test', 15:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:47 INFO - 'SHELL': '/bin/bash', 15:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kh4dK9ZBDF/Listeners', 15:16:47 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 15:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:47 INFO - 'USER': 'cltbld', 15:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:47 INFO - 'XPC_FLAGS': '0x0', 15:16:47 INFO - 'XPC_SERVICE_NAME': '0', 15:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:47 INFO - Downloading/unpacking jsonschema==2.5.1 15:16:47 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 15:16:47 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 15:16:47 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 15:16:47 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 15:16:47 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 15:16:47 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 15:16:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:16:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:16:52 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 15:16:52 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 15:16:52 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 15:16:52 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 15:16:52 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 15:16:52 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 15:16:52 INFO - Downloading functools32-3.2.3-2.tar.gz 15:16:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:16:52 INFO - warning: no files found matching '*.txt' 15:16:52 INFO - no previously-included directories found matching 'build' 15:16:52 INFO - no previously-included directories found matching 'dist' 15:16:52 INFO - no previously-included directories found matching '.git*' 15:16:52 INFO - Installing collected packages: jsonschema, functools32 15:16:53 INFO - Running setup.py install for functools32 15:16:53 INFO - warning: no files found matching '*.txt' 15:16:53 INFO - no previously-included directories found matching 'build' 15:16:53 INFO - no previously-included directories found matching 'dist' 15:16:53 INFO - no previously-included directories found matching '.git*' 15:16:53 INFO - Successfully installed jsonschema functools32 15:16:53 INFO - Cleaning up... 15:16:53 INFO - Return code: 0 15:16:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:16:53 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')]} 15:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:53 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')]} 15:16:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:53 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 0x10ced6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f05852d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb4d30>, '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 0x10cbdbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9be8>, '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.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 15:16:53 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 15:16:53 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 15:16:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn9DmVkwT7/Render', 15:16:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:53 INFO - 'HOME': '/Users/cltbld', 15:16:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:53 INFO - 'LOGNAME': 'cltbld', 15:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:53 INFO - 'MOZ_NO_REMOTE': '1', 15:16:53 INFO - 'NO_EM_RESTART': '1', 15:16:53 INFO - 'PAGER': '/bin/cat', 15:16:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:53 INFO - 'PWD': '/builds/slave/test', 15:16:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:53 INFO - 'SHELL': '/bin/bash', 15:16:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kh4dK9ZBDF/Listeners', 15:16:53 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 15:16:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:53 INFO - 'USER': 'cltbld', 15:16:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:53 INFO - 'XPC_FLAGS': '0x0', 15:16:53 INFO - 'XPC_SERVICE_NAME': '0', 15:16:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:16:53 INFO - Cleaning up... 15:16:53 INFO - Return code: 0 15:16:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:16:53 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')]} 15:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:53 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')]} 15:16:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:53 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 0x10ced6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f05852d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb4d30>, '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 0x10cbdbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9be8>, '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.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 15:16:53 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 15:16:53 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 15:16:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn9DmVkwT7/Render', 15:16:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:53 INFO - 'HOME': '/Users/cltbld', 15:16:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:53 INFO - 'LOGNAME': 'cltbld', 15:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:53 INFO - 'MOZ_NO_REMOTE': '1', 15:16:53 INFO - 'NO_EM_RESTART': '1', 15:16:53 INFO - 'PAGER': '/bin/cat', 15:16:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:53 INFO - 'PWD': '/builds/slave/test', 15:16:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:53 INFO - 'SHELL': '/bin/bash', 15:16:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kh4dK9ZBDF/Listeners', 15:16:53 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 15:16:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:53 INFO - 'USER': 'cltbld', 15:16:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:53 INFO - 'XPC_FLAGS': '0x0', 15:16:53 INFO - 'XPC_SERVICE_NAME': '0', 15:16:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:54 INFO - Downloading/unpacking blobuploader==1.2.4 15:16:54 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 15:16:54 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 15:16:54 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 15:16:54 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 15:16:54 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 15:16:54 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 15:16:58 INFO - Downloading blobuploader-1.2.4.tar.gz 15:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:16:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:16:58 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 15:16:58 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 15:16:58 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 15:16:58 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 15:16:58 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 15:16:58 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 15:16:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:16:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:00 INFO - Downloading docopt-0.6.1.tar.gz 15:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:17:00 INFO - Installing collected packages: blobuploader, requests, docopt 15:17:00 INFO - Running setup.py install for blobuploader 15:17:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:17:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:17:00 INFO - Running setup.py install for requests 15:17:01 INFO - Running setup.py install for docopt 15:17:01 INFO - Successfully installed blobuploader requests docopt 15:17:01 INFO - Cleaning up... 15:17:01 INFO - Return code: 0 15:17:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:01 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 0x10ced6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f05852d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb4d30>, '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 0x10cbdbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9be8>, '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.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 15:17:01 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 15:17:01 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 15:17:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn9DmVkwT7/Render', 15:17:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:01 INFO - 'HOME': '/Users/cltbld', 15:17:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:01 INFO - 'LOGNAME': 'cltbld', 15:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:01 INFO - 'MOZ_NO_REMOTE': '1', 15:17:01 INFO - 'NO_EM_RESTART': '1', 15:17:01 INFO - 'PAGER': '/bin/cat', 15:17:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:01 INFO - 'PWD': '/builds/slave/test', 15:17:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:01 INFO - 'SHELL': '/bin/bash', 15:17:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kh4dK9ZBDF/Listeners', 15:17:01 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 15:17:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:01 INFO - 'USER': 'cltbld', 15:17:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:01 INFO - 'XPC_FLAGS': '0x0', 15:17:01 INFO - 'XPC_SERVICE_NAME': '0', 15:17:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:17:02 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-NixzN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:17:02 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-b_PMco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:17:02 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-fN5N9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:17:02 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-7DhJn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:17:02 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-GtsKtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:17:03 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-8Jo75x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:17:03 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-Y8EVxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:17:03 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-2bF3SK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:17:03 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-zfA00I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:17:03 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-l50ZDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:17:03 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-PRfKhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:17:04 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-foHCZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:17:04 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-TuptmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:17:04 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-U0jZtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:04 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-GmkhYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:17:04 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-vZc7oM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:17:04 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-3jBloS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:17:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:17:05 INFO - Running setup.py install for manifestparser 15:17:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:17:05 INFO - Running setup.py install for mozcrash 15:17:05 INFO - Running setup.py install for mozdebug 15:17:05 INFO - Running setup.py install for mozdevice 15:17:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:17:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:17:06 INFO - Running setup.py install for mozfile 15:17:06 INFO - Running setup.py install for mozhttpd 15:17:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:17:06 INFO - Running setup.py install for mozinfo 15:17:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:17:06 INFO - Running setup.py install for mozInstall 15:17:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:17:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:17:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:17:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:17:06 INFO - Running setup.py install for mozleak 15:17:07 INFO - Running setup.py install for mozlog 15:17:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:17:07 INFO - Running setup.py install for moznetwork 15:17:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:17:07 INFO - Running setup.py install for mozprocess 15:17:07 INFO - Running setup.py install for mozprofile 15:17:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:17:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:17:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:17:08 INFO - Running setup.py install for mozrunner 15:17:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:17:08 INFO - Running setup.py install for mozscreenshot 15:17:08 INFO - Running setup.py install for moztest 15:17:08 INFO - Running setup.py install for mozversion 15:17:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:17:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:17:09 INFO - Cleaning up... 15:17:09 INFO - Return code: 0 15:17:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:17:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:09 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 0x10ced6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f05852d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb4d30>, '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 0x10cbdbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9be8>, '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.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 15:17:09 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 15:17:09 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 15:17:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn9DmVkwT7/Render', 15:17:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:09 INFO - 'HOME': '/Users/cltbld', 15:17:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:09 INFO - 'LOGNAME': 'cltbld', 15:17:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:09 INFO - 'MOZ_NO_REMOTE': '1', 15:17:09 INFO - 'NO_EM_RESTART': '1', 15:17:09 INFO - 'PAGER': '/bin/cat', 15:17:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:09 INFO - 'PWD': '/builds/slave/test', 15:17:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:09 INFO - 'SHELL': '/bin/bash', 15:17:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kh4dK9ZBDF/Listeners', 15:17:09 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 15:17:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:09 INFO - 'USER': 'cltbld', 15:17:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:09 INFO - 'XPC_FLAGS': '0x0', 15:17:09 INFO - 'XPC_SERVICE_NAME': '0', 15:17:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:17:09 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-0DJ9Wt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:17:09 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)) 15:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:17:09 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-Gf__xQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:17:09 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)) 15:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:17:09 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-mWt2W6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:17:10 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)) 15:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:17:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-6o2Yg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:17:10 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)) 15:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:17:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-ra2rlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:17:10 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)) 15:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:17:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-hM8mIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:17:10 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)) 15:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:17:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-V0xQYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:17:10 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)) 15:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:17:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-Cc_s_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:17:10 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)) 15:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:17:10 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-R2LjY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:17:11 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)) 15:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:17:11 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-nCzMmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:17:11 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)) 15:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:17:11 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-D0TcBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:17:11 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)) 15:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:17:11 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-LQL8r4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:17:11 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)) 15:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:17:11 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-HuLRqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:17:11 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)) 15:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:17:11 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-E_Rqnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:17:11 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)) 15:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:11 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-Yz4fAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:17:12 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)) 15:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:17:12 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-zrZnd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:17:12 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)) 15:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:17:12 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-w3MtDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:17:12 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)) 15:17:12 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)) 15:17:12 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)) 15:17:12 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)) 15:17:12 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)) 15:17:12 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)) 15:17:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:17:12 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 15:17:12 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 15:17:12 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 15:17:12 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 15:17:12 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 15:17:12 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 15:17:16 INFO - Downloading blessings-1.6.tar.gz 15:17:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:17:16 INFO - Installing collected packages: blessings 15:17:16 INFO - Running setup.py install for blessings 15:17:17 INFO - Successfully installed blessings 15:17:17 INFO - Cleaning up... 15:17:17 INFO - Return code: 0 15:17:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:17:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:17:17 INFO - Reading from file tmpfile_stdout 15:17:17 INFO - Current package versions: 15:17:17 INFO - blessings == 1.6 15:17:17 INFO - blobuploader == 1.2.4 15:17:17 INFO - docopt == 0.6.1 15:17:17 INFO - functools32 == 3.2.3-2 15:17:17 INFO - jsonschema == 2.5.1 15:17:17 INFO - manifestparser == 1.1 15:17:17 INFO - mozInstall == 1.12 15:17:17 INFO - mozcrash == 1.0 15:17:17 INFO - mozdebug == 0.1 15:17:17 INFO - mozdevice == 0.49 15:17:17 INFO - mozfile == 1.2 15:17:17 INFO - mozhttpd == 0.7 15:17:17 INFO - mozinfo == 0.9 15:17:17 INFO - mozleak == 0.1 15:17:17 INFO - mozlog == 3.4 15:17:17 INFO - moznetwork == 0.27 15:17:17 INFO - mozprocess == 0.23 15:17:17 INFO - mozprofile == 0.28 15:17:17 INFO - mozrunner == 6.13 15:17:17 INFO - mozscreenshot == 0.1 15:17:17 INFO - mozsystemmonitor == 0.3 15:17:17 INFO - moztest == 0.7 15:17:17 INFO - mozversion == 1.4 15:17:17 INFO - psutil == 3.1.1 15:17:17 INFO - requests == 1.2.3 15:17:17 INFO - wsgiref == 0.1.2 15:17:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:17 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 0x10ced6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f05852d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb4d30>, '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 0x10cbdbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9be8>, '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.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 15:17:17 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 15:17:17 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 15:17:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn9DmVkwT7/Render', 15:17:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:17 INFO - 'HOME': '/Users/cltbld', 15:17:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:17 INFO - 'LOGNAME': 'cltbld', 15:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:17 INFO - 'MOZ_NO_REMOTE': '1', 15:17:17 INFO - 'NO_EM_RESTART': '1', 15:17:17 INFO - 'PAGER': '/bin/cat', 15:17:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:17 INFO - 'PWD': '/builds/slave/test', 15:17:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:17 INFO - 'SHELL': '/bin/bash', 15:17:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kh4dK9ZBDF/Listeners', 15:17:17 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 15:17:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:17 INFO - 'USER': 'cltbld', 15:17:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:17 INFO - 'XPC_FLAGS': '0x0', 15:17:17 INFO - 'XPC_SERVICE_NAME': '0', 15:17:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:18 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)) 15:17:18 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)) 15:17:18 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)) 15:17:18 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)) 15:17:18 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)) 15:17:18 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)) 15:17:18 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)) 15:17:18 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)) 15:17:18 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)) 15:17:18 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)) 15:17:18 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)) 15:17:18 INFO - Cleaning up... 15:17:18 INFO - Return code: 0 15:17:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:17: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')]} 15:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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')]} 15:17:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ced6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ced9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f05852d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccb4d30>, '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 0x10cbdbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9be8>, '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.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 15:17: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', 'jsonschema'] in /builds/slave/test/build 15:17: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 jsonschema 15:17:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn9DmVkwT7/Render', 15:17:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:18 INFO - 'HOME': '/Users/cltbld', 15:17:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:18 INFO - 'LOGNAME': 'cltbld', 15:17:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:18 INFO - 'MOZ_NO_REMOTE': '1', 15:17:18 INFO - 'NO_EM_RESTART': '1', 15:17:18 INFO - 'PAGER': '/bin/cat', 15:17:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:18 INFO - 'PWD': '/builds/slave/test', 15:17:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:18 INFO - 'SHELL': '/bin/bash', 15:17:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kh4dK9ZBDF/Listeners', 15:17:18 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 15:17:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:18 INFO - 'USER': 'cltbld', 15:17:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:18 INFO - 'XPC_FLAGS': '0x0', 15:17:18 INFO - 'XPC_SERVICE_NAME': '0', 15:17:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:17:18 INFO - Cleaning up... 15:17:18 INFO - Return code: 0 15:17:18 INFO - Running post-action listener: _resource_record_post_action 15:17:18 INFO - Running post-action listener: _start_resource_monitoring 15:17:18 INFO - Starting resource monitoring. 15:17:18 INFO - [mozharness: 2016-12-17 23:17:18.621929Z] Finished create-virtualenv step (success) 15:17:18 INFO - [mozharness: 2016-12-17 23:17:18.623292Z] Running install step. 15:17:18 INFO - Running pre-action listener: _resource_record_pre_action 15:17:18 INFO - Running main action method: install 15:17:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:17:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:17:19 INFO - Reading from file tmpfile_stdout 15:17:19 INFO - Detecting whether we're running mozinstall >=1.0... 15:17:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:17:19 INFO - Reading from file tmpfile_stdout 15:17:19 INFO - Output received: 15:17:19 INFO - Usage: mozinstall [options] installer 15:17:19 INFO - Options: 15:17:19 INFO - -h, --help show this help message and exit 15:17:19 INFO - -d DEST, --destination=DEST 15:17:19 INFO - Directory to install application into. [default: 15:17:19 INFO - "/builds/slave/test"] 15:17:19 INFO - --app=APP Application being installed. [default: firefox] 15:17:19 INFO - mkdir: /builds/slave/test/build/application 15:17:19 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'] 15:17:19 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 15:17:54 INFO - Reading from file tmpfile_stdout 15:17:54 INFO - Output received: 15:17:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:17:54 INFO - Running post-action listener: _resource_record_post_action 15:17:54 INFO - [mozharness: 2016-12-17 23:17:54.764073Z] Finished install step (success) 15:17:54 INFO - [mozharness: 2016-12-17 23:17:54.764321Z] Running run-tests step. 15:17:54 INFO - Running pre-action listener: _resource_record_pre_action 15:17:54 INFO - Running main action method: run_tests 15:17:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:17:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:17:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:17:54 INFO - 2016-12-17 15:17:54.788 screenresolution[1763:8795] starting screenresolution argv=screenresolution get 15:17:54 INFO - 2016-12-17 15:17:54.793 screenresolution[1763:8795] Display 0: 1600x1200x32@60 15:17:54 INFO - 2016-12-17 15:17:54.802 screenresolution[1764:8801] starting screenresolution argv=screenresolution list 15:17:54 INFO - Available Modes on Display 0 15:17:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:17:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:17:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:17:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:17:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:17:55 INFO - Intel Iris: 15:17:55 INFO - Chipset Model: Intel Iris 15:17:55 INFO - Type: GPU 15:17:55 INFO - Bus: Built-In 15:17:55 INFO - VRAM (Dynamic, Max): 1536 MB 15:17:55 INFO - Vendor: Intel (0x8086) 15:17:55 INFO - Device ID: 0x0a2e 15:17:55 INFO - Revision ID: 0x0009 15:17:55 INFO - Displays: 15:17:55 INFO - Display: 15:17:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:17:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:17:55 INFO - Display Serial Number: 1600x1200 60 15:17:55 INFO - Main Display: Yes 15:17:55 INFO - Mirror: Off 15:17:55 INFO - Online: Yes 15:17:55 INFO - Rotation: Supported 15:17:55 INFO - Return code: 0 15:17:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:17:55 INFO - Python 2.7.3 15:17:55 INFO - Return code: 0 15:17:55 INFO - grabbing minidump binary from tooltool 15:17:55 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')]} 15:17:55 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 0x10ccb4d30>, '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 0x10cbdbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cca9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:17:55 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 15:17:55 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 15:17:55 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 15:17:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:17:55 INFO - Return code: 0 15:17:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:17:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:17:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:17:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:17:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:17:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0239', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0239 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:17:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pn9DmVkwT7/Render', 15:17:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:55 INFO - 'HOME': '/Users/cltbld', 15:17:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:55 INFO - 'LOGNAME': 'cltbld', 15:17:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:17:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:55 INFO - 'MOZ_NO_REMOTE': '1', 15:17:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:17:55 INFO - 'NO_EM_RESTART': '1', 15:17:55 INFO - 'PAGER': '/bin/cat', 15:17:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:55 INFO - 'PWD': '/builds/slave/test', 15:17:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:17:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:55 INFO - 'SHELL': '/bin/bash', 15:17:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Kh4dK9ZBDF/Listeners', 15:17:55 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 15:17:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:55 INFO - 'USER': 'cltbld', 15:17:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:55 INFO - 'XPC_FLAGS': '0x0', 15:17:55 INFO - 'XPC_SERVICE_NAME': '0', 15:17:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0239', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:17:55 INFO - mozversion application_buildid: 20161217140049 15:17:55 INFO - mozversion application_changeset: 881ef8c0e2322c6117aeaee8f07fbf4d0f22cd95 15:17:55 INFO - mozversion application_display_name: Nightly 15:17:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:17:55 INFO - mozversion application_name: Firefox 15:17:55 INFO - mozversion application_remotingname: firefox 15:17:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:17:55 INFO - mozversion application_vendor: Mozilla 15:17:55 INFO - mozversion application_version: 53.0a1 15:17:55 INFO - mozversion platform_buildid: 20161217140049 15:17:55 INFO - mozversion platform_changeset: 881ef8c0e2322c6117aeaee8f07fbf4d0f22cd95 15:17:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:17:55 INFO - mozversion platform_version: 53.0a1 15:17:55 INFO - using testdate: 1482016675 15:17:55 INFO - actual date: 1482016675 15:17:55 INFO - starting webserver on 'localhost:49249' 15:17:55 INFO - SUITE-START | Running 2 tests 15:17:55 INFO - TEST-START | dromaeo_css 15:17:55 INFO - operating with platform_type : mac_ 15:17:55 INFO - Initialising browser for dromaeo_css test... 15:17:55 INFO - TEST-INFO | started process 1774 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpZnovtC/profile http://localhost:49249/getInfo.html) 15:18:00 INFO - PROCESS | 1774 | 2016-12-17 15:18:00.027 plugin-container[1776:8967] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x964b, name = 'com.apple.tsm.portname' 15:18:00 INFO - PROCESS | 1774 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:18:00 INFO - PROCESS | 1774 | 2016-12-17 15:18:00.032 plugin-container[1776:8967] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a17, name = 'com.apple.CFPasteboardClient' 15:18:00 INFO - PROCESS | 1774 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:18:00 INFO - PROCESS | 1774 | 2016-12-17 15:18:00.032 plugin-container[1776:8967] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:18:00 INFO - PROCESS | 1774 | __metrics Screen width/height:1600/1200 15:18:00 INFO - PROCESS | 1774 | colorDepth:24 15:18:00 INFO - PROCESS | 1774 | Browser inner width/height: 1024/689 15:18:00 INFO - PROCESS | 1774 | __metrics 15:18:00 ERROR - PROCESS | 1774 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 15:18:01 INFO - TEST-INFO | 1774: exit 0 15:18:01 INFO - Browser initialized. 15:18:01 INFO - Running cycle 1/1 for dromaeo_css test... 15:18:01 INFO - TEST-INFO | started process 1779 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpZnovtC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:18:04 INFO - PROCESS | 1779 | 2016-12-17 15:18:04.993 plugin-container[1780:9091] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474f, name = 'com.apple.tsm.portname' 15:18:04 INFO - PROCESS | 1779 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:18:04 INFO - PROCESS | 1779 | 2016-12-17 15:18:04.996 plugin-container[1780:9091] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431f, name = 'com.apple.CFPasteboardClient' 15:18:04 INFO - PROCESS | 1779 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:18:04 INFO - PROCESS | 1779 | 2016-12-17 15:18:04.996 plugin-container[1780:9091] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:18:14 INFO - PROCESS | 1779 | RSS: Main: 236576768 15:18:14 INFO - PROCESS | 1779 | 15:19:03 INFO - PROCESS | 1779 | 1482016743710 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] 15:21:16 INFO - PROCESS | 1779 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 15:21:19 INFO - PROCESS | 1779 | RSS: Main: 254857216 15:21:19 INFO - PROCESS | 1779 | 15:24:22 INFO - PROCESS | 1779 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 15:24:22 INFO - PROCESS | 1779 | RSS: Main: 265318400 15:24:22 INFO - PROCESS | 1779 | 15:27:25 INFO - PROCESS | 1779 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 15:27:26 INFO - PROCESS | 1779 | RSS: Main: 269824000 15:27:26 INFO - PROCESS | 1779 | 15:30:29 INFO - PROCESS | 1779 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 15:30:30 INFO - PROCESS | 1779 | RSS: Main: 269283328 15:30:30 INFO - PROCESS | 1779 | 15:33:33 INFO - PROCESS | 1779 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 15:33:34 INFO - PROCESS | 1779 | RSS: Main: 273289216 15:33:34 INFO - PROCESS | 1779 | 15:36:38 INFO - PROCESS | 1779 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 15:36:38 INFO - PROCESS | 1779 | RSS: Main: 276676608 15:36:38 INFO - PROCESS | 1779 | 15:36:38 INFO - PROCESS | 1779 | __start_tp_report 15:36:38 INFO - PROCESS | 1779 | _x_x_mozilla_page_load 15:36:38 INFO - PROCESS | 1779 | _x_x_mozilla_page_load_details 15:36:38 INFO - PROCESS | 1779 | |i|pagename|runs| 15:36:38 INFO - PROCESS | 1779 | |0;dojo.html;1955.9315423960163;2016.7277417548626;2055.3731112010832;2058.939646082267;2101.980539438373;3276.4266253405676;3277.8197199154038;3323.268880846213;3400.234947136893;3475;3388.1698983749015;3437.810920399377;3451.9137021574475;3494.4234201356744;3526.1537231064553;2687.368468409922;2966.4808658484785;3041.178881701939;3126.8436578171113;3244.7241984431353;3034.6813584573633;3034.6965303469697;3110.688931106894;3125.9843700781466;3169.985604606527;3024.470717624407;3056.5415187721796;3114.6573876873526;3135.5923729914966;3137.670544592819;3050.801697889652;3102.503599424081;3139.952900706491;3179.8092114473206;3188.537662039015;2323.0591610397814;2651.515151515158;2935.8532073396245;2999;3225.516172574109;2967.887042359115;3410.9829450852835;3424.298018906118;3445.2592692571216;3453.9654603453896;2920.5035144025564;3258.7393008559498;3673.779573225615;3756.492873462102;3881.951872994304;2923.239957611015;3551.8224088795455;3552.5559305086863;3621.746477746559;3645.398509245971;2633.552296109666;2641.5773476243708;3309.3050459403794;3340.4989251611964;3415.7096646785303;2979.6275465566805;3336.2993771308297;3715.554133503943;3821.044738815296;3828.272628200689;2998.250437390652;3214.373197226565;3606.6213047630145;3677.191017976041;3777.301199278142;3600.279944011208;3654.7258955577904;3668.6514781095752;3684.1526448916898;3719.442083687414;3610.1696609779897;3619.2399596085115;3629.0927268182954;3655.670989610948;3662.066173125836;3685.981570092132;3688.0595448160548;3714.331420344364;3726.31063253299;3742.3450896093077;2769.62610047645;2840.943181136407;2849.7435230829324;2866;2867.25451382642;2485.7141428735567;2533.1767175667983;2580.7161212266637;2627.78977681785;2628.7239839816543;3345.6988871001727;3426.811525366129;3476.7392445566684;3498.880358285375;3563.572371315407;3805.6955443564448;3807.086299288206;3814.1608846053823;3814.9237015260096;3879.4374815652136;3164.351307981858;3311.867525298961;3350.463925771865;3431.571053618298;3492.476128580733;2944.4699954008056;3202;3259.4133056049664;3266.020193941808;3321.8671253150087;3732.8133593320226;3761.492198553215;3884.320243957296;4037.1320166164414;4270.615644591878;4306.806193721228;4396.4064851244675;4418.734875907456;4516;4652.651051171108;3781.1870447853844;4591.701539399928;4637.304404339376;4646.279826626878;4655.6275497959405;4407.118576284692;4424.225760491965;4633.374494443207;4647.395838540968;4651.86044418668;4280.293751530962;4713.552212539803;4713.9292910607055;4727.007328461062;4760.690555113906;4491.281394976788;4583.9770801147115;4586.128635559233;4604.539546045368;4633.4208223972;4503.864884053483;4682.719036857799;4754.809807607657;4755.928661070017;4812.663113582015;3465.6014558326115;3920.176762879827;4279.293916503742;4491.7978690959635;4520.434945631796;3486.877959271413;3773.8301776420676;4544.159330523864;4546.090781843578;4629.1898917688595;3538.363094642989;4183.958160418479;4397.25246708054;4406.6474682026;4420.535843736361 15:36:38 INFO - PROCESS | 1779 | |1;ext.html;294.3171841328119;306.12295772611463;307.43125218346074;317.52529967698297;326.66190492839905;20086.49783755405;21601.67597486037;25130.87434562827;25182.866770995348;25553.616695749577;21380.4654883628;25786.742132578667;26130.6080408794;26322.736772632317;27233.319167020836;5751.7986870459545;5754.913676294859;5778.884422311541;5998.25021872266;6035.004224303007;5214.217867319894;5234.973825130869;5711.885762284752;6617.272100069013;7140.928590714078;6572.507061970371;6613.404793568578;6946.965265173692;7000.369966702996;7052.823679408005;6430.453411460032;6912.723491060352;6973.12835895508;7011.579305241702;7077.504574679774;5064.518870707277;5907.966105931445;6128.540359473012;6346.936530634727;6813.727450901958;19851.801481985283;23316.65025024626;23978.920948557356;24056.556606603484;24419.877900610383;2018.3238615063972;2066.5143691232547;2099.3806826986074;2376.857388556675;2380.690510233676;16056.678866422722;17816;17960.730589041174;18302.450926472095;18482.53793655156;1602.2549514475709;1762.6526184080562;1786.437272259234;1835.0916296433218;1864.8601354898437;274884;279873;286812.56593717105;294180;294397;35209;35437;36678.26643467115;36705.816470917474;36842.81578592143;36223.818880905426;36661.633383666354;37000.259994799955;37646.24707505889;37758.67844625425;8356.373272004625;9504.239660827117;9526.142647161789;9624.855627165598;9735.318527702993;5939.287285525765;7317.634118294064;7518.699252029967;7614.581198034161;7653.846923061507;7802.921970780219;8603.741887743377;8862.689805856764;9325.300602454843;9377.484238366818;3903.2435404068283;3980.980095099506;4001.2197621464115;4049.562405346119;4056.776877271778;499.5079846351341;538.6963548213333;543.6900966449165;577.7919948818422;605.4369436424167;16122.919385403233;16333.428330008393;16986.235619397157;17690.380836670654;18606.790558613648;461.5845738835075;569.9344575373802;604.7762327938691;637;662.5560874214289;7976.5612891290375;9097.681581144629;9206.815863682688;9307.906920930704;9529.475878826592;311402;357341;360187;361731.93155061005;369281;27687.584686229722;33317.666823331456;33441;33888.15279618029;34153.43849211269;650.0996120373317;719.2268311565089;771.957542335177;785.1363500149789;861.7070196133219;14760.557183284518;14784.595463430956;15062.246887655792;16963.491095267163;17349.749311418782;22856.771432285463;28795.568066479438;29396.118116455684;29642.703572963994;30943.584872647647;288436.55781720957;334893;338493;338871;340977;14267.42930282777;14684.412623495367;15554.222288885736;15586.986845854983;16149.354025839304;23573.764262357156;23779.881100594386;24483.877580611985;24965.87517062403;25086.37284067913;19734.802651973296;20449;21387.358379248646;21808.67286990728;22814.771852281265;19038.714419284;20626.896865515577;20922.47693807667;21368.893155534122;22651.093956242225;18923.62152756965;19399;19408.514787130436;19596.90201548983;19641.11614977301;16507;16808.831911680725;17169.656606867542;17870.731939020665;19255.422337329903;13135.080544361803;13239.602811915658;13368.398422070835;13984.510542130976;14126.293685315488 15:36:38 INFO - PROCESS | 1779 | |2;jquery.html;181.43754361479415;216.93275084723746;220.10307994920697;226.9546090781844;229.38524753660204;9277.768055798608;9413.905860941388;11257.831132533018;12000.7599848003;12111.454984525684;11488.655340339783;11975.281483110997;12080.93959530201;12180.781921807815;12356.938215308934;3318.634950155475;3407.897763067112;3466;3557.3774589446743;3593.4070878305047;3504.4392897136463;3519.366514027474;3567.8751243706474;3579.9618110747983;3707.4438834174953;3192.1062102611304;3385.5260263563123;3386.0857568456504;3408.437607794711;3621.257642183346;3374.088995971086;3461.152017755666;3463.2380876207194;3484.5470088888283;3514.332276867387;3003.9549406758915;3063.862126204326;3074.784765066446;3142.795718278305;3151.023182813335;13982.370793314325;14607.92696036513;14856.925715371353;14980.400783968627;15308.004979676285;1294.249335385474;1333.9599812005656;1334.7797613393875;1335.1855368225374;1369.452219112363;8462.153784621489;8666.306695464411;8723.12768723129;9334.159925606727;9632.71101866938;1035.259789250682;1059.4332032362727;1090.6727981605486;1096.7777187222152;1151.1021403305435;45863.54136458626;49935;60300.39699603036;62550;66902.66548667275;5324.547413469821;5854.5901786875365;6212.72042758077;6257.374262573706;6351.491880649537;7708.9229107709325;7810.6875724971505;7822.061352637606;8118.715844945398;8250.339972802161;3191.4095892259543;3649.6532829381167;4019.8392064317686;4144.5233798113;4771.5228477152;4591.382911740343;4753.5008824072975;4954.826581069645;4963.255511673278;5176.120059589879;3752.2683076799763;3881.5536213335827;3959.604039596017;4027.496562929634;4071.0840060986043;1085.8099832529276;1097.3909480238385;1108.4623957380663;1118.932864028161;1136.3920302638048;449.5235050846114;457.83746769896874;516.6925679220858;532.9953038522414;591.8520369907524;12130.696732581757;12199.817002744965;12651.3041782701;12701.745965080832;13183.868161318456;397.76730612591956;450.1601340564986;477.9378680771547;611.2847967877594;643.3148696638159;4512.210363186429;6156.78451254204;6223.937760622336;6689.230738465041;6698.4306333961185;280238;305774.2978995769;311980;313710.4314478413;326371.36814315774;67433.66283168552;71899.6405017982;74349;77089.61455192688;77877.86686928867;513.8669234338349;580.6661169827282;598.1207624791549;598.2999890128647;615.559874689599;11376.829347559958;11427.885721142682;11769.705757356134;11836.053115750892;11879.643610691694;27326.497042662846;30924.0722778317;32560.83719581387;33923.4911476333;35085.47371789476;47054.529454705014;48182;48292.7585362071;49016.5098349012;51239.743801280754;3174.8253846038688;4071.470708807836;4447.131600117841;4703.294505824154;5065.822696205615;5453.972730136324;7125.287471252833;7249.05762250904;7689.653965571674;7933.246341597539;4827;5581.5255703265675;5729.713514324184;6449.193850768654;6670.666466676744;5965;6272.090546870587;6625.900611490735;6648.468122550281;6931.1682598088555;6585.835354116185;6595.274519802721;6661.134052573135;6727.260001399744;6844.2129155147795;3750.4749335093657;4179;4203.579642035772;4219.936700949427;4913.43495498008;4436.977815110904;6401.679916004275;6617.139771829631;6678.465722742266;6828.180618325634 15:36:38 INFO - PROCESS | 1779 | |3;mootools.html;870.751835726818;882.3117967984972;928.2713070239859;939.1594522902003;1234.8888600025996;4898.902021959568;5236.685798852067;5249.606279529041;5652.745626446809;5675.545956323489;5272.051030814452;5691.174779656947;5697.544196464283;5768.221290125832;5858.41415858414;1589.1498048544001;1593.5139782366373;1616.442327397052;1631.983680163201;1646.382606522554;1381.785823197401;1617.9595510112224;1631.616570106024;1632.96306845153;1694.3561446650256;1297.5782870567057;1401.635574750562;1403.6701375176826;1428.7428262912672;1485.910845349277;1447.001568917454;1450.0502171077908;1452.0561634937324;1454.9709005819825;1463.9752173478607;1317.7957416600443;1393.6028231953844;1430.613734291746;1464.633841539615;1488.40463814474;4979.377577802775;5031.245313203012;5138.871528211787;5203.115470370009;5281.313429254173;3030.0909727082008;3137.7646676499353;3165.018844158318;3203.935921281561;3225.016370007147;1717.5362652083868;1823.8085001074828;2014.446027342478;2033.7254470646421;2037.2360364863177;474.7649913292942;522.8483739715477;532.5686194182724;564.1030761089887;610.261583483985;4922.827701030446;6116.66358350295;6121.540884433641;6192.59748116371;6213.751449941997;2133.306675330493;2694.4476382341572;2791.7487426131747;2820.323122450638;2843.260752204442;4081.77550234734;4895.779689913962;4948.703077815343;5008.724520151354;5019.895622962942;2896.2759310172455;2996.235959830273;3051.4354844353866;3105.875764969421;3147.8583463744185;2657.734226577327;2751.8899244030013;2791.1766029021487;2806.1300996691093;2832.221139186699;1887.2639670528504;1967.7737060238255;2060.5569802492537;2086.5722526881955;2100.915963361479;509.78334207961467;579.9971000144973;679.4326737174515;679.5175425447889;688.2944981394011;511.1821086261951;513.214015227012;526.6234642230824;542.1488263426462;544.7929786681036;3097;3222.8871989480726;3233.4664780310986;3274.770766046354;3290.6544812794314;434.86519179054596;533.7358007786094;534.9251104845325;536.6484952356213;560.4759549820931;1929.3825975687926;1966.7541557305337;2170.902309396081;2170.9240176593744;2202.8237740980685;4576.794044268016;5810.215620891126;5833.445822646841;5870.853228669317;5891.941080589225;4903.803847846046;5562.498125506171;5635.774569017275;5680.14797780328;5795.449432303924;4472.172648060119;4505.504394516535;4567.771611419415;4575.931361029588;4631.166390049823;3036.9848150759103;3141.607299087614;3154.179913222533;3194.7923384979904;3352.9497057544645;5339.572834173179;5386.622936394415;5414.593905457028;5434.347878254634;5437.374701909331;5213.244079608359;5361.088614935392;5476.808311709071;5532.834014979557;5557.694326812064;3396.558447401822;3825.6556909878245;3853.075261937171;3965.325894597983;3997.7801220931965;4082.9792551862033;4360.215161271001;4397.538258482813;4450.955490445054;4556.681032327838;3329.950050749289;3936.960630393774;4181.770002649883;4233.322668373045;4253.766042867672;3461.8961431157104;3998.1603863187893;4009.2983727848095;4114.5679703630685;4376.715513491613;3753.4932784074895;3773.0378753417704;3794.962050379461;3942.704297177666;3955.960440395674;2713.972860271372;2746.85485119541;2755.118362124101;2762.392273699783;2777.652793400825;2639.221429678288;2801.0756450371737;2816.718328167167;2832.0371073834995;2844.502212112913 15:36:38 INFO - PROCESS | 1779 | |4;prototype.html;172.32620454024763;206.70079332346745;207.69779970143443;211.47448590253222;214.52268702137746;2805.334931814779;3249.5377080313833;3296.967030329696;3383.780054296469;3408.3694516514433;2893.725096115871;3173.904782856512;3238.910902467913;3280.7703460757757;3287.7041066304027;1997.0543448413619;2229.520653059592;2534.7085085215253;2570.0747730816984;2578.5173525222976;2458.766417190364;2465.2989437288215;2524.192258477288;2526.936826579332;2605.452854900473;2127.1597718901025;2517.446161844391;2534.8605826679527;2587.676540432446;2619.027403896572;2305.0176847910952;2353.305774796439;2487.9751202487923;2536.73364296748;2571.961420578693;1904.1526520698294;2051.4050925231663;2057.7016332631683;2123.299311227291;2139.176417079436;2539.987300063506;2689.5965605159186;3061.892833750808;3157.810531368102;3339.7996120232865;2555.246202370305;2596.4287856671504;2752.4219913818124;2762.875670594828;2804.4110736745306;3419.5383623211046;3442.535257740198;3500.842462089212;3500.9474857877153;3508.614052454228;2239.742429620582;2251.8423710340285;2255.9661605075935;2345.2495201535517;2356.175338631482;3533.469979503095;3544.326577950207;3550.8579656813954;3559.5728512578658;3576.5886923003927;2307.146355848347;2338.38032921276;2340.941476463102;2345.1121846913397;2386.5942789725786;1964.204497178645;1972.753405824272;2019.8384129269623;2338.6492026196206;2402.25530085674;2317.5133222023564;2724.6594175728032;2728.7544121029205;2744.7118052604587;2814.4652516021893;2850.558163484663;2932.7947043707163;3274.5415641810164;3302.075418882717;3310.3213841162506;630.1524449615351;694.7360003198752;752.0073502976019;781.8162731758032;782.7163451938825;486.73959431703867;507.6370395167473;511.53194826733125;533.5971341637039;536.9234684457715;2675.411409489909;3111.7510599152015;3124.375124975014;3151.6835126182696;3336.14928193314;3048.116046346535;3101.2712012677002;3579.3736096183065;3602.8919132426067;3621.149029977953;3116.812991220534;3180.6959146749723;3187.6334221564816;3267.9019629411155;3431.4043969554277;1579.5024567261275;1582.2879704133186;1680.2186983052939;1699.4391850689242;1711.9713139929224;3003.9399212015637;3228.2252259458032;3253.934921301608;3381.7632765706658;3409.636145541803;3328.4869923207825;3489.685928266468;3522.2075033117344;3536.8585256589963;3582.6565038110707;3407.4037043517287;3553.411054834584;3598.6401359863803;3601.981990090085;3658.396364599811;3020.2600362911226;3262.4780035194253;3408.0574072778277;3409.5904095904098;3415.4193787055765;2963.903355758297;3004.8197108172703;3218.806871587712;3409.232922592397;3411.3006833598556;3081.1064791210297;3099.6435409927244;3393.1177893748295;3483.3904066787704;3697.556293244828;2178.6949827023914;2179.705739725117;2228.687983682253;2469.7283298837474;2539.9492010159324;3190.2343437575278;3225.7258133058012;3297.7963043488476;3468.375692375296;3550.414181659997;2941.323445635967;2949.7640188785276;3140.7644426667634;3192.473241915058;3397.9830100849335;3048.7865849390328;3106.7825252233033;3131.27980564476;3388.6611338865914;3418.87177231518;2860.5709143628624;2940.2943293609806;3066.005958351462;3229.0958531611186;3274.639789623092;3191.186247506871;3275.5741753571883;3283.63879973198;3298.340331933575;3408.6420925802436;3079.8460076996507;3109.8445077746474;3146.6224053113774;3227.80633162011;3263.6246831613716 15:36:38 INFO - PROCESS | 1779 | |5;yui.html;399.9980000099999;415.33546325878615;441.8541881179209;444.5465625062436;470.6235011990409;8496.532690702008;9031.503267320295;9078.909210907897;9095.818083638324;9162.404443711153;8113.959430202841;8239.588020598962;8332.250097491225;8432.367572432062;8483.151684831513;477.7062106804325;486.1395330265428;503.25015726567335;504.967177133487;512.3979324293958;345.1182229403872;402;430.3243907065908;473.79389965365175;501.706501696508;489.6425609305209;520.4379270387972;521.1245108218211;522.9529342359187;531.6278604976512;519.7998770473357;521.2780299285516;521.7104506998614;522.0107895537973;523.5052875033115;369.7467234944045;379.05993137020346;380.44075209442354;380.84956442205174;383.4822988964875;8230.176982301782;8282.461639993442;8857.202851743374;8921.973972993059;9059.547022648907;7950.3242224411;8069.435139540235;8388.951381077366;8554.572271386469;8705.303575713928;6383.744650213986;6782.898256526156;6898.206706228748;7088.770583137303;7228.241034691387;1695.6523912597888;1916.32928475034;2032.9390118296474;2182.858114222586;2348.2720356689483;48609;68773.3122668772;69057.65471172612;69431.65284173597;70496.6475167626;5315.840524784263;6161.260648722182;6368.611770586031;6414.486841052704;6464.41820236181;12737.93631031839;12859.421326040163;13079.82281594661;13380.063395562218;13625.659358515919;4526.59260666535;4666.486686464486;4786.95213047865;4802.327674125625;5032;2559.1171045989104;2561.9743802562107;2588.197658725763;2595.117659995573;2679.343560827571;1937.428458604715;2032.319173077006;2051.323063389078;2105.000124940641;2107.4099252209407;334.7255250694456;356.6576086956551;364.89618902818654;367.8197683135298;370.646033038453;382.31183869035624;384.26796951806296;401.793076565569;408.29161404962366;430.91381723655394;4453.732776033448;4935.185694360463;5368.436314186954;5388.084025715638;5481.808136715275;283.9162447078117;305.5416874687968;373.68236998551555;384.3600405325158;443.7692399952007;2378;2519.5842685956613;2578.8839502222445;2603.7534368247993;2623.740604509846;40178;50779.49220507748;52439.73780131075;56146.71926640341;56708.71645641745;36339.81961082343;39841;41324.58675413267;42268.99999999938;50259.24611130836;28045.01842435505;41453.963650908365;42688.035512542716;42746.78626606847;42786.28854845771;5409.486098820606;5436.891262174813;5498.092814685692;5511.807086752105;5936.881262374642;32187.839060804545;34911.778087766805;35464.404101816;40337.79831100826;42585.7224283272;45262.09475810531;47186.52813471821;59106.70446647739;64446;64622.67688661526;12407.193532420364;12442.502299908265;12664.620061398173;12847.100702950704;13211.207327560378;11763.647090587296;14580.489682861049;14601.780973285622;14667.48663796762;14867;13255.867441325463;13572.117812342167;13598.592042238748;13692.315384230948;13854.37655305534;12541.74916501683;13390.598282051555;13413.32933353348;13431.86568134306;13497.190168589916;11947.342896140795;12148.331841748788;12168.574099906815;12391.566295179626;12534.937325313314;9453.480058596842;9869.950650246703;9870.062344077302;10044.397336159855;10263.7434064149;8906.087215359377;9234.307426942873;9579.16070577017;9642.517874106126;9724.124828765445 15:36:38 INFO - PROCESS | 1779 | __end_tp_report 15:36:38 INFO - PROCESS | 1779 | __start_cc_report 15:36:38 INFO - PROCESS | 1779 | _x_x_mozilla_cycle_collect,519 15:36:38 INFO - PROCESS | 1779 | __end_cc_report 15:36:38 INFO - PROCESS | 1779 | __startTimestamp1482017798330__endTimestamp 15:36:38 INFO - PROCESS | 1779 | 15:36:38 INFO - PROCESS | 1779 | ------- Summary: start ------- 15:36:38 INFO - PROCESS | 1779 | Number of tests: 6 15:36:38 INFO - PROCESS | 1779 | 15:36:38 INFO - PROCESS | 1779 | [#0] dojo.html Cycles:165 Average:3590.19 Median:3526.15 stddev:670.92 (19.0%) stddev-sans-first:660.62 15:36:38 INFO - PROCESS | 1779 | Values: 1955.9 2016.7 2055.4 2058.9 2102.0 3276.4 3277.8 3323.3 3400.2 3475.0 3388.2 3437.8 3451.9 3494.4 3526.2 2687.4 2966.5 3041.2 3126.8 3244.7 3034.7 3034.7 3110.7 3126.0 3170.0 3024.5 3056.5 3114.7 3135.6 3137.7 3050.8 3102.5 3140.0 3179.8 3188.5 2323.1 2651.5 2935.9 2999.0 3225.5 2967.9 3411.0 3424.3 3445.3 3454.0 2920.5 3258.7 3673.8 3756.5 3882.0 2923.2 3551.8 3552.6 3621.7 3645.4 2633.6 2641.6 3309.3 3340.5 3415.7 2979.6 3336.3 3715.6 3821.0 3828.3 2998.3 3214.4 3606.6 3677.2 3777.3 3600.3 3654.7 3668.7 3684.2 3719.4 3610.2 3619.2 3629.1 3655.7 3662.1 3686.0 3688.1 3714.3 3726.3 3742.3 2769.6 2840.9 2849.7 2866.0 2867.3 2485.7 2533.2 2580.7 2627.8 2628.7 3345.7 3426.8 3476.7 3498.9 3563.6 3805.7 3807.1 3814.2 3814.9 3879.4 3164.4 3311.9 3350.5 3431.6 3492.5 2944.5 3202.0 3259.4 3266.0 3321.9 3732.8 3761.5 3884.3 4037.1 4270.6 4306.8 4396.4 4418.7 4516.0 4652.7 3781.2 4591.7 4637.3 4646.3 4655.6 4407.1 4424.2 4633.4 4647.4 4651.9 4280.3 4713.6 4713.9 4727.0 4760.7 4491.3 4584.0 4586.1 4604.5 4633.4 4503.9 4682.7 4754.8 4755.9 4812.7 3465.6 3920.2 4279.3 4491.8 4520.4 3486.9 3773.8 4544.2 4546.1 4629.2 3538.4 4184.0 4397.3 4406.6 4420.5 15:36:38 INFO - PROCESS | 1779 | 15:36:38 INFO - PROCESS | 1779 | [#1] ext.html Cycles:180 Average:39764.04 Median:155859.76 stddev:86481.70 (55.5%) stddev-sans-first:86673.53 15:36:38 INFO - PROCESS | 1779 | Values: 294.3 306.1 307.4 317.5 326.7 20086.5 21601.7 25130.9 25182.9 25553.6 21380.5 25786.7 26130.6 26322.7 27233.3 5751.8 5754.9 5778.9 5998.3 6035.0 5214.2 5235.0 5711.9 6617.3 7140.9 6572.5 6613.4 6947.0 7000.4 7052.8 6430.5 6912.7 6973.1 7011.6 7077.5 5064.5 5908.0 6128.5 6346.9 6813.7 19851.8 23316.7 23978.9 24056.6 24419.9 2018.3 2066.5 2099.4 2376.9 2380.7 16056.7 17816.0 17960.7 18302.5 18482.5 1602.3 1762.7 1786.4 1835.1 1864.9 274884.0 279873.0 286812.6 294180.0 294397.0 35209.0 35437.0 36678.3 36705.8 36842.8 36223.8 36661.6 37000.3 37646.2 37758.7 8356.4 9504.2 9526.1 9624.9 9735.3 5939.3 7317.6 7518.7 7614.6 7653.8 7802.9 8603.7 8862.7 9325.3 9377.5 3903.2 3981.0 4001.2 4049.6 4056.8 499.5 538.7 543.7 577.8 605.4 16122.9 16333.4 16986.2 17690.4 18606.8 461.6 569.9 604.8 637.0 662.6 7976.6 9097.7 9206.8 9307.9 9529.5 311402.0 357341.0 360187.0 361731.9 369281.0 27687.6 33317.7 33441.0 33888.2 34153.4 650.1 719.2 772.0 785.1 861.7 14760.6 14784.6 15062.2 16963.5 17349.7 22856.8 28795.6 29396.1 29642.7 30943.6 288436.6 334893.0 338493.0 338871.0 340977.0 14267.4 14684.4 15554.2 15587.0 16149.4 23573.8 23779.9 24483.9 24965.9 25086.4 19734.8 20449.0 21387.4 21808.7 22814.8 19038.7 20626.9 20922.5 21368.9 22651.1 18923.6 19399.0 19408.5 19596.9 19641.1 16507.0 16808.8 17169.7 17870.7 19255.4 13135.1 13239.6 13368.4 13984.5 14126.3 15:36:38 INFO - PROCESS | 1779 | 15:36:38 INFO - PROCESS | 1779 | [#2] jquery.html Cycles:180 Average:19053.35 Median:4161.76 stddev:51603.36 (1239.9%) stddev-sans-first:51728.67 15:36:38 INFO - PROCESS | 1779 | Values: 181.4 216.9 220.1 227.0 229.4 9277.8 9413.9 11257.8 12000.8 12111.5 11488.7 11975.3 12080.9 12180.8 12356.9 3318.6 3407.9 3466.0 3557.4 3593.4 3504.4 3519.4 3567.9 3580.0 3707.4 3192.1 3385.5 3386.1 3408.4 3621.3 3374.1 3461.2 3463.2 3484.5 3514.3 3004.0 3063.9 3074.8 3142.8 3151.0 13982.4 14607.9 14856.9 14980.4 15308.0 1294.2 1334.0 1334.8 1335.2 1369.5 8462.2 8666.3 8723.1 9334.2 9632.7 1035.3 1059.4 1090.7 1096.8 1151.1 45863.5 49935.0 60300.4 62550.0 66902.7 5324.5 5854.6 6212.7 6257.4 6351.5 7708.9 7810.7 7822.1 8118.7 8250.3 3191.4 3649.7 4019.8 4144.5 4771.5 4591.4 4753.5 4954.8 4963.3 5176.1 3752.3 3881.6 3959.6 4027.5 4071.1 1085.8 1097.4 1108.5 1118.9 1136.4 449.5 457.8 516.7 533.0 591.9 12130.7 12199.8 12651.3 12701.7 13183.9 397.8 450.2 477.9 611.3 643.3 4512.2 6156.8 6223.9 6689.2 6698.4 280238.0 305774.3 311980.0 313710.4 326371.4 67433.7 71899.6 74349.0 77089.6 77877.9 513.9 580.7 598.1 598.3 615.6 11376.8 11427.9 11769.7 11836.1 11879.6 27326.5 30924.1 32560.8 33923.5 35085.5 47054.5 48182.0 48292.8 49016.5 51239.7 3174.8 4071.5 4447.1 4703.3 5065.8 5454.0 7125.3 7249.1 7689.7 7933.2 4827.0 5581.5 5729.7 6449.2 6670.7 5965.0 6272.1 6625.9 6648.5 6931.2 6585.8 6595.3 6661.1 6727.3 6844.2 3750.5 4179.0 4203.6 4219.9 4913.4 4437.0 6401.7 6617.1 6678.5 6828.2 15:36:38 INFO - PROCESS | 1779 | 15:36:38 INFO - PROCESS | 1779 | [#3] mootools.html Cycles:180 Average:3155.12 Median:3949.33 stddev:1707.25 (43.2%) stddev-sans-first:1703.40 15:36:38 INFO - PROCESS | 1779 | Values: 870.8 882.3 928.3 939.2 1234.9 4898.9 5236.7 5249.6 5652.7 5675.5 5272.1 5691.2 5697.5 5768.2 5858.4 1589.1 1593.5 1616.4 1632.0 1646.4 1381.8 1618.0 1631.6 1633.0 1694.4 1297.6 1401.6 1403.7 1428.7 1485.9 1447.0 1450.1 1452.1 1455.0 1464.0 1317.8 1393.6 1430.6 1464.6 1488.4 4979.4 5031.2 5138.9 5203.1 5281.3 3030.1 3137.8 3165.0 3203.9 3225.0 1717.5 1823.8 2014.4 2033.7 2037.2 474.8 522.8 532.6 564.1 610.3 4922.8 6116.7 6121.5 6192.6 6213.8 2133.3 2694.4 2791.7 2820.3 2843.3 4081.8 4895.8 4948.7 5008.7 5019.9 2896.3 2996.2 3051.4 3105.9 3147.9 2657.7 2751.9 2791.2 2806.1 2832.2 1887.3 1967.8 2060.6 2086.6 2100.9 509.8 580.0 679.4 679.5 688.3 511.2 513.2 526.6 542.1 544.8 3097.0 3222.9 3233.5 3274.8 3290.7 434.9 533.7 534.9 536.6 560.5 1929.4 1966.8 2170.9 2170.9 2202.8 4576.8 5810.2 5833.4 5870.9 5891.9 4903.8 5562.5 5635.8 5680.1 5795.4 4472.2 4505.5 4567.8 4575.9 4631.2 3037.0 3141.6 3154.2 3194.8 3352.9 5339.6 5386.6 5414.6 5434.3 5437.4 5213.2 5361.1 5476.8 5532.8 5557.7 3396.6 3825.7 3853.1 3965.3 3997.8 4083.0 4360.2 4397.5 4451.0 4556.7 3330.0 3937.0 4181.8 4233.3 4253.8 3461.9 3998.2 4009.3 4114.6 4376.7 3753.5 3773.0 3795.0 3942.7 3956.0 2714.0 2746.9 2755.1 2762.4 2777.7 2639.2 2801.1 2816.7 2832.0 2844.5 15:36:38 INFO - PROCESS | 1779 | 15:36:38 INFO - PROCESS | 1779 | [#4] prototype.html Cycles:180 Average:2723.60 Median:3114.28 stddev:843.82 (27.1%) stddev-sans-first:824.17 15:36:38 INFO - PROCESS | 1779 | Values: 172.3 206.7 207.7 211.5 214.5 2805.3 3249.5 3297.0 3383.8 3408.4 2893.7 3173.9 3238.9 3280.8 3287.7 1997.1 2229.5 2534.7 2570.1 2578.5 2458.8 2465.3 2524.2 2526.9 2605.5 2127.2 2517.4 2534.9 2587.7 2619.0 2305.0 2353.3 2488.0 2536.7 2572.0 1904.2 2051.4 2057.7 2123.3 2139.2 2540.0 2689.6 3061.9 3157.8 3339.8 2555.2 2596.4 2752.4 2762.9 2804.4 3419.5 3442.5 3500.8 3500.9 3508.6 2239.7 2251.8 2256.0 2345.2 2356.2 3533.5 3544.3 3550.9 3559.6 3576.6 2307.1 2338.4 2340.9 2345.1 2386.6 1964.2 1972.8 2019.8 2338.6 2402.3 2317.5 2724.7 2728.8 2744.7 2814.5 2850.6 2932.8 3274.5 3302.1 3310.3 630.2 694.7 752.0 781.8 782.7 486.7 507.6 511.5 533.6 536.9 2675.4 3111.8 3124.4 3151.7 3336.1 3048.1 3101.3 3579.4 3602.9 3621.1 3116.8 3180.7 3187.6 3267.9 3431.4 1579.5 1582.3 1680.2 1699.4 1712.0 3003.9 3228.2 3253.9 3381.8 3409.6 3328.5 3489.7 3522.2 3536.9 3582.7 3407.4 3553.4 3598.6 3602.0 3658.4 3020.3 3262.5 3408.1 3409.6 3415.4 2963.9 3004.8 3218.8 3409.2 3411.3 3081.1 3099.6 3393.1 3483.4 3697.6 2178.7 2179.7 2228.7 2469.7 2539.9 3190.2 3225.7 3297.8 3468.4 3550.4 2941.3 2949.8 3140.8 3192.5 3398.0 3048.8 3106.8 3131.3 3388.7 3418.9 2860.6 2940.3 3066.0 3229.1 3274.6 3191.2 3275.6 3283.6 3298.3 3408.6 3079.8 3109.8 3146.6 3227.8 3263.6 15:36:38 INFO - PROCESS | 1779 | 15:36:38 INFO - PROCESS | 1779 | [#5] yui.html Cycles:180 Average:12931.47 Median:436.38 stddev:17272.70 (3958.1%) stddev-sans-first:17295.52 15:36:38 INFO - PROCESS | 1779 | Values: 400.0 415.3 441.9 444.5 470.6 8496.5 9031.5 9078.9 9095.8 9162.4 8114.0 8239.6 8332.3 8432.4 8483.2 477.7 486.1 503.3 505.0 512.4 345.1 402.0 430.3 473.8 501.7 489.6 520.4 521.1 523.0 531.6 519.8 521.3 521.7 522.0 523.5 369.7 379.1 380.4 380.8 383.5 8230.2 8282.5 8857.2 8922.0 9059.5 7950.3 8069.4 8389.0 8554.6 8705.3 6383.7 6782.9 6898.2 7088.8 7228.2 1695.7 1916.3 2032.9 2182.9 2348.3 48609.0 68773.3 69057.7 69431.7 70496.6 5315.8 6161.3 6368.6 6414.5 6464.4 12737.9 12859.4 13079.8 13380.1 13625.7 4526.6 4666.5 4787.0 4802.3 5032.0 2559.1 2562.0 2588.2 2595.1 2679.3 1937.4 2032.3 2051.3 2105.0 2107.4 334.7 356.7 364.9 367.8 370.6 382.3 384.3 401.8 408.3 430.9 4453.7 4935.2 5368.4 5388.1 5481.8 283.9 305.5 373.7 384.4 443.8 2378.0 2519.6 2578.9 2603.8 2623.7 40178.0 50779.5 52439.7 56146.7 56708.7 36339.8 39841.0 41324.6 42269.0 50259.2 28045.0 41454.0 42688.0 42746.8 42786.3 5409.5 5436.9 5498.1 5511.8 5936.9 32187.8 34911.8 35464.4 40337.8 42585.7 45262.1 47186.5 59106.7 64446.0 64622.7 12407.2 12442.5 12664.6 12847.1 13211.2 11763.6 14580.5 14601.8 14667.5 14867.0 13255.9 13572.1 13598.6 13692.3 13854.4 12541.7 13390.6 13413.3 13431.9 13497.2 11947.3 12148.3 12168.6 12391.6 12534.9 9453.5 9870.0 9870.1 10044.4 10263.7 8906.1 9234.3 9579.2 9642.5 9724.1 15:36:38 INFO - PROCESS | 1779 | -------- Summary: end -------- 15:36:38 INFO - PROCESS | 1779 | 15:36:39 INFO - TEST-INFO | 1779: exit 0 15:36:39 INFO - TEST-OK | dromaeo_css | took 1123416ms 15:36:39 INFO - TEST-START | kraken 15:36:39 INFO - operating with platform_type : mac_ 15:36:39 INFO - Initialising browser for kraken test... 15:36:39 INFO - TEST-INFO | started process 1899 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmp0ARGgX/profile http://localhost:49249/getInfo.html) 15:36:42 INFO - PROCESS | 1899 | __metrics Screen width/height:1600/1200 15:36:42 INFO - PROCESS | 1899 | colorDepth:24 15:36:42 INFO - PROCESS | 1899 | Browser inner width/height: 1024/689 15:36:42 INFO - PROCESS | 1899 | __metrics 15:36:42 INFO - PROCESS | 1899 | 2016-12-17 15:36:42.380 plugin-container[1900:11343] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 15:36:42 INFO - PROCESS | 1899 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:36:42 INFO - PROCESS | 1899 | 2016-12-17 15:36:42.384 plugin-container[1900:11343] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 15:36:42 INFO - PROCESS | 1899 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:36:42 INFO - PROCESS | 1899 | 2016-12-17 15:36:42.388 plugin-container[1900:11343] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:36:42 ERROR - PROCESS | 1899 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 15:36:43 INFO - TEST-INFO | 1899: exit 0 15:36:43 INFO - Browser initialized. 15:36:43 INFO - Running cycle 1/1 for kraken test... 15:36:43 INFO - TEST-INFO | started process 1902 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmp0ARGgX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:36:46 INFO - PROCESS | 1902 | 2016-12-17 15:36:46.031 plugin-container[1903:11460] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 15:36:46 INFO - PROCESS | 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:36:46 INFO - PROCESS | 1902 | 2016-12-17 15:36:46.032 plugin-container[1903:11460] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8817, name = 'com.apple.CFPasteboardClient' 15:36:46 INFO - PROCESS | 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:36:46 INFO - PROCESS | 1902 | 2016-12-17 15:36:46.032 plugin-container[1903:11460] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:36:55 INFO - PROCESS | 1902 | RSS: Main: 237146112 15:36:55 INFO - PROCESS | 1902 | 15:36:58 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 15:36:59 INFO - PROCESS | 1902 | RSS: Main: 249405440 15:36:59 INFO - PROCESS | 1902 | 15:37:02 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 15:37:03 INFO - PROCESS | 1902 | RSS: Main: 256987136 15:37:03 INFO - PROCESS | 1902 | 15:37:07 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 15:37:07 INFO - PROCESS | 1902 | RSS: Main: 259846144 15:37:07 INFO - PROCESS | 1902 | 15:37:10 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 15:37:10 INFO - PROCESS | 1902 | RSS: Main: 261824512 15:37:10 INFO - PROCESS | 1902 | 15:37:13 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:37:13 INFO - PROCESS | 1902 | RSS: Main: 268177408 15:37:13 INFO - PROCESS | 1902 | 15:37:18 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 15:37:19 INFO - PROCESS | 1902 | RSS: Main: 260743168 15:37:19 INFO - PROCESS | 1902 | 15:37:23 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 15:37:23 INFO - PROCESS | 1902 | RSS: Main: 266301440 15:37:23 INFO - PROCESS | 1902 | 15:37:27 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 15:37:28 INFO - PROCESS | 1902 | RSS: Main: 264122368 15:37:28 INFO - PROCESS | 1902 | 15:37:30 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:37:31 INFO - PROCESS | 1902 | RSS: Main: 265412608 15:37:31 INFO - PROCESS | 1902 | 15:37:34 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:37:34 INFO - PROCESS | 1902 | RSS: Main: 266047488 15:37:34 INFO - PROCESS | 1902 | 15:37:38 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:37:38 INFO - PROCESS | 1902 | RSS: Main: 265973760 15:37:38 INFO - PROCESS | 1902 | 15:37:43 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:37:44 INFO - PROCESS | 1902 | RSS: Main: 271654912 15:37:44 INFO - PROCESS | 1902 | 15:37:45 INFO - PROCESS | 1902 | 1482017865172 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] 15:37:48 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:37:49 INFO - PROCESS | 1902 | RSS: Main: 277164032 15:37:49 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 15:37:52 INFO - PROCESS | 1902 | RSS: Main: 278638592 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | __start_tp_report 15:37:52 INFO - PROCESS | 1902 | _x_x_mozilla_page_load 15:37:52 INFO - PROCESS | 1902 | _x_x_mozilla_page_load_details 15:37:52 INFO - PROCESS | 1902 | |i|pagename|runs| 15:37:52 INFO - PROCESS | 1902 | |0;ai-astar;137;138;141;144;145;144;143;142;147;175 15:37:52 INFO - PROCESS | 1902 | |1;audio-beat-detection;176;142;144;199;179;147;149;144;164;152 15:37:52 INFO - PROCESS | 1902 | |2;audio-dft;219;219;218;221;223;223;262;268;220;218 15:37:52 INFO - PROCESS | 1902 | |3;audio-fft;103;77;77;138;112;77;78;94;101;82 15:37:52 INFO - PROCESS | 1902 | |4;audio-oscillator;111;98;111;93;91;95;112;140;137;145 15:37:52 INFO - PROCESS | 1902 | |5;imaging-gaussian-blur;162;216;173;232;170;174;182;169;196;216 15:37:52 INFO - PROCESS | 1902 | |6;imaging-darkroom;131;172;200;149;138;160;144;142;137;143 15:37:52 INFO - PROCESS | 1902 | |7;imaging-desaturate;125;163;204;124;134;168;199;133;135;172 15:37:52 INFO - PROCESS | 1902 | |8;json-parse-financial;86;82;117;71;83;110;132;82;111;82 15:37:52 INFO - PROCESS | 1902 | |9;json-stringify-tinderbox;99;109;124;148;169;108;138;145;159;115 15:37:52 INFO - PROCESS | 1902 | |10;stanford-crypto-aes;152;170;147;145;147;152;140;145;150;149 15:37:52 INFO - PROCESS | 1902 | |11;stanford-crypto-ccm;243;212;231;244;258;249;224;243;322;293 15:37:52 INFO - PROCESS | 1902 | |12;stanford-crypto-pbkdf2;293;269;295;380;354;310;273;329;295;276 15:37:52 INFO - PROCESS | 1902 | |13;stanford-crypto-sha256-iterative;135;141;140;116;114;100;112;109;107;107 15:37:52 INFO - PROCESS | 1902 | __end_tp_report 15:37:52 INFO - PROCESS | 1902 | __start_cc_report 15:37:52 INFO - PROCESS | 1902 | _x_x_mozilla_cycle_collect,1308 15:37:52 INFO - PROCESS | 1902 | __end_cc_report 15:37:52 INFO - PROCESS | 1902 | __startTimestamp1482017872392__endTimestamp 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | ------- Summary: start ------- 15:37:52 INFO - PROCESS | 1902 | Number of tests: 14 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | [#0] ai-astar Cycles:10 Average:145.60 Median:144.00 stddev:10.77 (7.5%) stddev-sans-first:10.97 15:37:52 INFO - PROCESS | 1902 | Values: 137.0 138.0 141.0 144.0 145.0 144.0 143.0 142.0 147.0 175.0 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | [#1] audio-beat-detection Cycles:10 Average:159.60 Median:158.00 stddev:19.27 (12.2%) stddev-sans-first:19.50 15:37:52 INFO - PROCESS | 1902 | Values: 176.0 142.0 144.0 199.0 179.0 147.0 149.0 144.0 164.0 152.0 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | [#2] audio-dft Cycles:10 Average:229.10 Median:222.00 stddev:19.06 (8.6%) stddev-sans-first:19.86 15:37:52 INFO - PROCESS | 1902 | Values: 219.0 219.0 218.0 221.0 223.0 223.0 262.0 268.0 220.0 218.0 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | [#3] audio-fft Cycles:10 Average:93.90 Median:77.00 stddev:20.16 (26.2%) stddev-sans-first:21.11 15:37:52 INFO - PROCESS | 1902 | Values: 103.0 77.0 77.0 138.0 112.0 77.0 78.0 94.0 101.0 82.0 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | [#4] audio-oscillator Cycles:10 Average:113.30 Median:118.00 stddev:20.47 (17.3%) stddev-sans-first:21.69 15:37:52 INFO - PROCESS | 1902 | Values: 111.0 98.0 111.0 93.0 91.0 95.0 112.0 140.0 137.0 145.0 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | [#5] imaging-gaussian-blur Cycles:10 Average:189.00 Median:189.00 stddev:24.44 (12.9%) stddev-sans-first:23.89 15:37:52 INFO - PROCESS | 1902 | Values: 162.0 216.0 173.0 232.0 170.0 174.0 182.0 169.0 196.0 216.0 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | [#6] imaging-darkroom Cycles:10 Average:151.60 Median:146.50 stddev:20.77 (14.2%) stddev-sans-first:20.65 15:37:52 INFO - PROCESS | 1902 | Values: 131.0 172.0 200.0 149.0 138.0 160.0 144.0 142.0 137.0 143.0 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | [#7] imaging-desaturate Cycles:10 Average:155.70 Median:165.50 stddev:29.89 (18.1%) stddev-sans-first:29.57 15:37:52 INFO - PROCESS | 1902 | Values: 125.0 163.0 204.0 124.0 134.0 168.0 199.0 133.0 135.0 172.0 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | [#8] json-parse-financial Cycles:10 Average:95.60 Median:82.00 stddev:20.11 (24.5%) stddev-sans-first:21.02 15:37:52 INFO - PROCESS | 1902 | Values: 86.0 82.0 117.0 71.0 83.0 110.0 132.0 82.0 111.0 82.0 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | [#9] json-stringify-tinderbox Cycles:10 Average:131.40 Median:146.50 stddev:23.81 (16.3%) stddev-sans-first:22.18 15:37:52 INFO - PROCESS | 1902 | Values: 99.0 109.0 124.0 148.0 169.0 108.0 138.0 145.0 159.0 115.0 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | [#10] stanford-crypto-aes Cycles:10 Average:149.70 Median:149.50 stddev:8.00 (5.4%) stddev-sans-first:8.44 15:37:52 INFO - PROCESS | 1902 | Values: 152.0 170.0 147.0 145.0 147.0 152.0 140.0 145.0 150.0 149.0 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | [#11] stanford-crypto-ccm Cycles:10 Average:251.90 Median:246.50 stddev:32.79 (13.3%) stddev-sans-first:34.62 15:37:52 INFO - PROCESS | 1902 | Values: 243.0 212.0 231.0 244.0 258.0 249.0 224.0 243.0 322.0 293.0 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:307.40 Median:302.50 stddev:36.63 (12.1%) stddev-sans-first:38.48 15:37:52 INFO - PROCESS | 1902 | Values: 293.0 269.0 295.0 380.0 354.0 310.0 273.0 329.0 295.0 276.0 15:37:52 INFO - PROCESS | 1902 | 15:37:52 INFO - PROCESS | 1902 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:118.10 Median:115.00 stddev:14.93 (13.0%) stddev-sans-first:14.52 15:37:52 INFO - PROCESS | 1902 | Values: 135.0 141.0 140.0 116.0 114.0 100.0 112.0 109.0 107.0 107.0 15:37:52 INFO - PROCESS | 1902 | -------- Summary: end -------- 15:37:52 INFO - PROCESS | 1902 | 15:37:53 INFO - TEST-INFO | 1902: exit 0 15:37:53 INFO - TEST-OK | kraken | took 74168ms 15:37:53 INFO - SUITE-END | took 1197s 15:37:53 INFO - Completed test suite (00:19:57) 15:37:53 INFO - javascript benchmark 15:37:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 4342.589715457077, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1955.9315423960163, 2016.7277417548626, 2055.3731112010832, 2058.939646082267, 2101.980539438373, 3276.4266253405676, 3277.8197199154038, 3323.268880846213, 3400.234947136893, 3475.0, 3388.1698983749015, 3437.810920399377, 3451.9137021574475, 3494.4234201356744, 3526.1537231064553, 2687.368468409922, 2966.4808658484785, 3041.178881701939, 3126.8436578171113, 3244.7241984431353, 3034.6813584573633, 3034.6965303469697, 3110.688931106894, 3125.9843700781466, 3169.985604606527, 3024.470717624407, 3056.5415187721796, 3114.6573876873526, 3135.5923729914966, 3137.670544592819, 3050.801697889652, 3102.503599424081, 3139.952900706491, 3179.8092114473206, 3188.537662039015, 2323.0591610397814, 2651.515151515158, 2935.8532073396245, 2999.0, 3225.516172574109, 2967.887042359115, 3410.9829450852835, 3424.298018906118, 3445.2592692571216, 3453.9654603453896, 2920.5035144025564, 3258.7393008559498, 3673.779573225615, 3756.492873462102, 3881.951872994304, 2923.239957611015, 3551.8224088795455, 3552.5559305086863, 3621.746477746559, 3645.398509245971, 2633.552296109666, 2641.5773476243708, 3309.3050459403794, 3340.4989251611964, 3415.7096646785303, 2979.6275465566805, 3336.2993771308297, 3715.554133503943, 3821.044738815296, 3828.272628200689, 2998.250437390652, 3214.373197226565, 3606.6213047630145, 3677.191017976041, 3777.301199278142, 3600.279944011208, 3654.7258955577904, 3668.6514781095752, 3684.1526448916898, 3719.442083687414, 3610.1696609779897, 3619.2399596085115, 3629.0927268182954, 3655.670989610948, 3662.066173125836, 3685.981570092132, 3688.0595448160548, 3714.331420344364, 3726.31063253299, 3742.3450896093077, 2769.62610047645, 2840.943181136407, 2849.7435230829324, 2866.0, 2867.25451382642, 2485.7141428735567, 2533.1767175667983, 2580.7161212266637, 2627.78977681785, 2628.7239839816543, 3345.6988871001727, 3426.811525366129, 3476.7392445566684, 3498.880358285375, 3563.572371315407, 3805.6955443564448, 3807.086299288206, 3814.1608846053823, 3814.9237015260096, 3879.4374815652136, 3164.351307981858, 3311.867525298961, 3350.463925771865, 3431.571053618298, 3492.476128580733, 2944.4699954008056, 3202.0, 3259.4133056049664, 3266.020193941808, 3321.8671253150087, 3732.8133593320226, 3761.492198553215, 3884.320243957296, 4037.1320166164414, 4270.615644591878, 4306.806193721228, 4396.4064851244675, 4418.734875907456, 4516.0, 4652.651051171108, 3781.1870447853844, 4591.701539399928, 4637.304404339376, 4646.279826626878, 4655.6275497959405, 4407.118576284692, 4424.225760491965, 4633.374494443207, 4647.395838540968, 4651.86044418668, 4280.293751530962, 4713.552212539803, 4713.9292910607055, 4727.007328461062, 4760.690555113906, 4491.281394976788, 4583.9770801147115, 4586.128635559233, 4604.539546045368, 4633.4208223972, 4503.864884053483, 4682.719036857799, 4754.809807607657, 4755.928661070017, 4812.663113582015, 3465.6014558326115, 3920.176762879827, 4279.293916503742, 4491.7978690959635, 4520.434945631796, 3486.877959271413, 3773.8301776420676, 4544.159330523864, 4546.090781843578, 4629.1898917688595, 3538.363094642989, 4183.958160418479, 4397.25246708054, 4406.6474682026, 4420.535843736361], "value": 3531.814258856784, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.3171841328119, 306.12295772611463, 307.43125218346074, 317.52529967698297, 326.66190492839905, 20086.49783755405, 21601.67597486037, 25130.87434562827, 25182.866770995348, 25553.616695749577, 21380.4654883628, 25786.742132578667, 26130.6080408794, 26322.736772632317, 27233.319167020836, 5751.7986870459545, 5754.913676294859, 5778.884422311541, 5998.25021872266, 6035.004224303007, 5214.217867319894, 5234.973825130869, 5711.885762284752, 6617.272100069013, 7140.928590714078, 6572.507061970371, 6613.404793568578, 6946.965265173692, 7000.369966702996, 7052.823679408005, 6430.453411460032, 6912.723491060352, 6973.12835895508, 7011.579305241702, 7077.504574679774, 5064.518870707277, 5907.966105931445, 6128.540359473012, 6346.936530634727, 6813.727450901958, 19851.801481985283, 23316.65025024626, 23978.920948557356, 24056.556606603484, 24419.877900610383, 2018.3238615063972, 2066.5143691232547, 2099.3806826986074, 2376.857388556675, 2380.690510233676, 16056.678866422722, 17816.0, 17960.730589041174, 18302.450926472095, 18482.53793655156, 1602.2549514475709, 1762.6526184080562, 1786.437272259234, 1835.0916296433218, 1864.8601354898437, 274884.0, 279873.0, 286812.56593717105, 294180.0, 294397.0, 35209.0, 35437.0, 36678.26643467115, 36705.816470917474, 36842.81578592143, 36223.818880905426, 36661.633383666354, 37000.259994799955, 37646.24707505889, 37758.67844625425, 8356.373272004625, 9504.239660827117, 9526.142647161789, 9624.855627165598, 9735.318527702993, 5939.287285525765, 7317.634118294064, 7518.699252029967, 7614.581198034161, 7653.846923061507, 7802.921970780219, 8603.741887743377, 8862.689805856764, 9325.300602454843, 9377.484238366818, 3903.2435404068283, 3980.980095099506, 4001.2197621464115, 4049.562405346119, 4056.776877271778, 499.5079846351341, 538.6963548213333, 543.6900966449165, 577.7919948818422, 605.4369436424167, 16122.919385403233, 16333.428330008393, 16986.235619397157, 17690.380836670654, 18606.790558613648, 461.5845738835075, 569.9344575373802, 604.7762327938691, 637.0, 662.5560874214289, 7976.5612891290375, 9097.681581144629, 9206.815863682688, 9307.906920930704, 9529.475878826592, 311402.0, 357341.0, 360187.0, 361731.93155061005, 369281.0, 27687.584686229722, 33317.666823331456, 33441.0, 33888.15279618029, 34153.43849211269, 650.0996120373317, 719.2268311565089, 771.957542335177, 785.1363500149789, 861.7070196133219, 14760.557183284518, 14784.595463430956, 15062.246887655792, 16963.491095267163, 17349.749311418782, 22856.771432285463, 28795.568066479438, 29396.118116455684, 29642.703572963994, 30943.584872647647, 288436.55781720957, 334893.0, 338493.0, 338871.0, 340977.0, 14267.42930282777, 14684.412623495367, 15554.222288885736, 15586.986845854983, 16149.354025839304, 23573.764262357156, 23779.881100594386, 24483.877580611985, 24965.87517062403, 25086.37284067913, 19734.802651973296, 20449.0, 21387.358379248646, 21808.67286990728, 22814.771852281265, 19038.714419284, 20626.896865515577, 20922.47693807667, 21368.893155534122, 22651.093956242225, 18923.62152756965, 19399.0, 19408.514787130436, 19596.90201548983, 19641.11614977301, 16507.0, 16808.831911680725, 17169.656606867542, 17870.731939020665, 19255.422337329903, 13135.080544361803, 13239.602811915658, 13368.398422070835, 13984.510542130976, 14126.293685315488], "value": 11450.33267409822, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [181.43754361479415, 216.93275084723746, 220.10307994920697, 226.9546090781844, 229.38524753660204, 9277.768055798608, 9413.905860941388, 11257.831132533018, 12000.7599848003, 12111.454984525684, 11488.655340339783, 11975.281483110997, 12080.93959530201, 12180.781921807815, 12356.938215308934, 3318.634950155475, 3407.897763067112, 3466.0, 3557.3774589446743, 3593.4070878305047, 3504.4392897136463, 3519.366514027474, 3567.8751243706474, 3579.9618110747983, 3707.4438834174953, 3192.1062102611304, 3385.5260263563123, 3386.0857568456504, 3408.437607794711, 3621.257642183346, 3374.088995971086, 3461.152017755666, 3463.2380876207194, 3484.5470088888283, 3514.332276867387, 3003.9549406758915, 3063.862126204326, 3074.784765066446, 3142.795718278305, 3151.023182813335, 13982.370793314325, 14607.92696036513, 14856.925715371353, 14980.400783968627, 15308.004979676285, 1294.249335385474, 1333.9599812005656, 1334.7797613393875, 1335.1855368225374, 1369.452219112363, 8462.153784621489, 8666.306695464411, 8723.12768723129, 9334.159925606727, 9632.71101866938, 1035.259789250682, 1059.4332032362727, 1090.6727981605486, 1096.7777187222152, 1151.1021403305435, 45863.54136458626, 49935.0, 60300.39699603036, 62550.0, 66902.66548667275, 5324.547413469821, 5854.5901786875365, 6212.72042758077, 6257.374262573706, 6351.491880649537, 7708.9229107709325, 7810.6875724971505, 7822.061352637606, 8118.715844945398, 8250.339972802161, 3191.4095892259543, 3649.6532829381167, 4019.8392064317686, 4144.5233798113, 4771.5228477152, 4591.382911740343, 4753.5008824072975, 4954.826581069645, 4963.255511673278, 5176.120059589879, 3752.2683076799763, 3881.5536213335827, 3959.604039596017, 4027.496562929634, 4071.0840060986043, 1085.8099832529276, 1097.3909480238385, 1108.4623957380663, 1118.932864028161, 1136.3920302638048, 449.5235050846114, 457.83746769896874, 516.6925679220858, 532.9953038522414, 591.8520369907524, 12130.696732581757, 12199.817002744965, 12651.3041782701, 12701.745965080832, 13183.868161318456, 397.76730612591956, 450.1601340564986, 477.9378680771547, 611.2847967877594, 643.3148696638159, 4512.210363186429, 6156.78451254204, 6223.937760622336, 6689.230738465041, 6698.4306333961185, 280238.0, 305774.2978995769, 311980.0, 313710.4314478413, 326371.36814315774, 67433.66283168552, 71899.6405017982, 74349.0, 77089.61455192688, 77877.86686928867, 513.8669234338349, 580.6661169827282, 598.1207624791549, 598.2999890128647, 615.559874689599, 11376.829347559958, 11427.885721142682, 11769.705757356134, 11836.053115750892, 11879.643610691694, 27326.497042662846, 30924.0722778317, 32560.83719581387, 33923.4911476333, 35085.47371789476, 47054.529454705014, 48182.0, 48292.7585362071, 49016.5098349012, 51239.743801280754, 3174.8253846038688, 4071.470708807836, 4447.131600117841, 4703.294505824154, 5065.822696205615, 5453.972730136324, 7125.287471252833, 7249.05762250904, 7689.653965571674, 7933.246341597539, 4827.0, 5581.5255703265675, 5729.713514324184, 6449.193850768654, 6670.666466676744, 5965.0, 6272.090546870587, 6625.900611490735, 6648.468122550281, 6931.1682598088555, 6585.835354116185, 6595.274519802721, 6661.134052573135, 6727.260001399744, 6844.2129155147795, 3750.4749335093657, 4179.0, 4203.579642035772, 4219.936700949427, 4913.43495498008, 4436.977815110904, 6401.679916004275, 6617.139771829631, 6678.465722742266, 6828.180618325634], "value": 5529.77926580284, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [870.751835726818, 882.3117967984972, 928.2713070239859, 939.1594522902003, 1234.8888600025996, 4898.902021959568, 5236.685798852067, 5249.606279529041, 5652.745626446809, 5675.545956323489, 5272.051030814452, 5691.174779656947, 5697.544196464283, 5768.221290125832, 5858.41415858414, 1589.1498048544001, 1593.5139782366373, 1616.442327397052, 1631.983680163201, 1646.382606522554, 1381.785823197401, 1617.9595510112224, 1631.616570106024, 1632.96306845153, 1694.3561446650256, 1297.5782870567057, 1401.635574750562, 1403.6701375176826, 1428.7428262912672, 1485.910845349277, 1447.001568917454, 1450.0502171077908, 1452.0561634937324, 1454.9709005819825, 1463.9752173478607, 1317.7957416600443, 1393.6028231953844, 1430.613734291746, 1464.633841539615, 1488.40463814474, 4979.377577802775, 5031.245313203012, 5138.871528211787, 5203.115470370009, 5281.313429254173, 3030.0909727082008, 3137.7646676499353, 3165.018844158318, 3203.935921281561, 3225.016370007147, 1717.5362652083868, 1823.8085001074828, 2014.446027342478, 2033.7254470646421, 2037.2360364863177, 474.7649913292942, 522.8483739715477, 532.5686194182724, 564.1030761089887, 610.261583483985, 4922.827701030446, 6116.66358350295, 6121.540884433641, 6192.59748116371, 6213.751449941997, 2133.306675330493, 2694.4476382341572, 2791.7487426131747, 2820.323122450638, 2843.260752204442, 4081.77550234734, 4895.779689913962, 4948.703077815343, 5008.724520151354, 5019.895622962942, 2896.2759310172455, 2996.235959830273, 3051.4354844353866, 3105.875764969421, 3147.8583463744185, 2657.734226577327, 2751.8899244030013, 2791.1766029021487, 2806.1300996691093, 2832.221139186699, 1887.2639670528504, 1967.7737060238255, 2060.5569802492537, 2086.5722526881955, 2100.915963361479, 509.78334207961467, 579.9971000144973, 679.4326737174515, 679.5175425447889, 688.2944981394011, 511.1821086261951, 513.214015227012, 526.6234642230824, 542.1488263426462, 544.7929786681036, 3097.0, 3222.8871989480726, 3233.4664780310986, 3274.770766046354, 3290.6544812794314, 434.86519179054596, 533.7358007786094, 534.9251104845325, 536.6484952356213, 560.4759549820931, 1929.3825975687926, 1966.7541557305337, 2170.902309396081, 2170.9240176593744, 2202.8237740980685, 4576.794044268016, 5810.215620891126, 5833.445822646841, 5870.853228669317, 5891.941080589225, 4903.803847846046, 5562.498125506171, 5635.774569017275, 5680.14797780328, 5795.449432303924, 4472.172648060119, 4505.504394516535, 4567.771611419415, 4575.931361029588, 4631.166390049823, 3036.9848150759103, 3141.607299087614, 3154.179913222533, 3194.7923384979904, 3352.9497057544645, 5339.572834173179, 5386.622936394415, 5414.593905457028, 5434.347878254634, 5437.374701909331, 5213.244079608359, 5361.088614935392, 5476.808311709071, 5532.834014979557, 5557.694326812064, 3396.558447401822, 3825.6556909878245, 3853.075261937171, 3965.325894597983, 3997.7801220931965, 4082.9792551862033, 4360.215161271001, 4397.538258482813, 4450.955490445054, 4556.681032327838, 3329.950050749289, 3936.960630393774, 4181.770002649883, 4233.322668373045, 4253.766042867672, 3461.8961431157104, 3998.1603863187893, 4009.2983727848095, 4114.5679703630685, 4376.715513491613, 3753.4932784074895, 3773.0378753417704, 3794.962050379461, 3942.704297177666, 3955.960440395674, 2713.972860271372, 2746.85485119541, 2755.118362124101, 2762.392273699783, 2777.652793400825, 2639.221429678288, 2801.0756450371737, 2816.718328167167, 2832.0371073834995, 2844.502212112913], "value": 2568.41741691617, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [172.32620454024763, 206.70079332346745, 207.69779970143443, 211.47448590253222, 214.52268702137746, 2805.334931814779, 3249.5377080313833, 3296.967030329696, 3383.780054296469, 3408.3694516514433, 2893.725096115871, 3173.904782856512, 3238.910902467913, 3280.7703460757757, 3287.7041066304027, 1997.0543448413619, 2229.520653059592, 2534.7085085215253, 2570.0747730816984, 2578.5173525222976, 2458.766417190364, 2465.2989437288215, 2524.192258477288, 2526.936826579332, 2605.452854900473, 2127.1597718901025, 2517.446161844391, 2534.8605826679527, 2587.676540432446, 2619.027403896572, 2305.0176847910952, 2353.305774796439, 2487.9751202487923, 2536.73364296748, 2571.961420578693, 1904.1526520698294, 2051.4050925231663, 2057.7016332631683, 2123.299311227291, 2139.176417079436, 2539.987300063506, 2689.5965605159186, 3061.892833750808, 3157.810531368102, 3339.7996120232865, 2555.246202370305, 2596.4287856671504, 2752.4219913818124, 2762.875670594828, 2804.4110736745306, 3419.5383623211046, 3442.535257740198, 3500.842462089212, 3500.9474857877153, 3508.614052454228, 2239.742429620582, 2251.8423710340285, 2255.9661605075935, 2345.2495201535517, 2356.175338631482, 3533.469979503095, 3544.326577950207, 3550.8579656813954, 3559.5728512578658, 3576.5886923003927, 2307.146355848347, 2338.38032921276, 2340.941476463102, 2345.1121846913397, 2386.5942789725786, 1964.204497178645, 1972.753405824272, 2019.8384129269623, 2338.6492026196206, 2402.25530085674, 2317.5133222023564, 2724.6594175728032, 2728.7544121029205, 2744.7118052604587, 2814.4652516021893, 2850.558163484663, 2932.7947043707163, 3274.5415641810164, 3302.075418882717, 3310.3213841162506, 630.1524449615351, 694.7360003198752, 752.0073502976019, 781.8162731758032, 782.7163451938825, 486.73959431703867, 507.6370395167473, 511.53194826733125, 533.5971341637039, 536.9234684457715, 2675.411409489909, 3111.7510599152015, 3124.375124975014, 3151.6835126182696, 3336.14928193314, 3048.116046346535, 3101.2712012677002, 3579.3736096183065, 3602.8919132426067, 3621.149029977953, 3116.812991220534, 3180.6959146749723, 3187.6334221564816, 3267.9019629411155, 3431.4043969554277, 1579.5024567261275, 1582.2879704133186, 1680.2186983052939, 1699.4391850689242, 1711.9713139929224, 3003.9399212015637, 3228.2252259458032, 3253.934921301608, 3381.7632765706658, 3409.636145541803, 3328.4869923207825, 3489.685928266468, 3522.2075033117344, 3536.8585256589963, 3582.6565038110707, 3407.4037043517287, 3553.411054834584, 3598.6401359863803, 3601.981990090085, 3658.396364599811, 3020.2600362911226, 3262.4780035194253, 3408.0574072778277, 3409.5904095904098, 3415.4193787055765, 2963.903355758297, 3004.8197108172703, 3218.806871587712, 3409.232922592397, 3411.3006833598556, 3081.1064791210297, 3099.6435409927244, 3393.1177893748295, 3483.3904066787704, 3697.556293244828, 2178.6949827023914, 2179.705739725117, 2228.687983682253, 2469.7283298837474, 2539.9492010159324, 3190.2343437575278, 3225.7258133058012, 3297.7963043488476, 3468.375692375296, 3550.414181659997, 2941.323445635967, 2949.7640188785276, 3140.7644426667634, 3192.473241915058, 3397.9830100849335, 3048.7865849390328, 3106.7825252233033, 3131.27980564476, 3388.6611338865914, 3418.87177231518, 2860.5709143628624, 2940.2943293609806, 3066.005958351462, 3229.0958531611186, 3274.639789623092, 3191.186247506871, 3275.5741753571883, 3283.63879973198, 3298.340331933575, 3408.6420925802436, 3079.8460076996507, 3109.8445077746474, 3146.6224053113774, 3227.80633162011, 3263.6246831613716], "value": 2455.3868334022177, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.9980000099999, 415.33546325878615, 441.8541881179209, 444.5465625062436, 470.6235011990409, 8496.532690702008, 9031.503267320295, 9078.909210907897, 9095.818083638324, 9162.404443711153, 8113.959430202841, 8239.588020598962, 8332.250097491225, 8432.367572432062, 8483.151684831513, 477.7062106804325, 486.1395330265428, 503.25015726567335, 504.967177133487, 512.3979324293958, 345.1182229403872, 402.0, 430.3243907065908, 473.79389965365175, 501.706501696508, 489.6425609305209, 520.4379270387972, 521.1245108218211, 522.9529342359187, 531.6278604976512, 519.7998770473357, 521.2780299285516, 521.7104506998614, 522.0107895537973, 523.5052875033115, 369.7467234944045, 379.05993137020346, 380.44075209442354, 380.84956442205174, 383.4822988964875, 8230.176982301782, 8282.461639993442, 8857.202851743374, 8921.973972993059, 9059.547022648907, 7950.3242224411, 8069.435139540235, 8388.951381077366, 8554.572271386469, 8705.303575713928, 6383.744650213986, 6782.898256526156, 6898.206706228748, 7088.770583137303, 7228.241034691387, 1695.6523912597888, 1916.32928475034, 2032.9390118296474, 2182.858114222586, 2348.2720356689483, 48609.0, 68773.3122668772, 69057.65471172612, 69431.65284173597, 70496.6475167626, 5315.840524784263, 6161.260648722182, 6368.611770586031, 6414.486841052704, 6464.41820236181, 12737.93631031839, 12859.421326040163, 13079.82281594661, 13380.063395562218, 13625.659358515919, 4526.59260666535, 4666.486686464486, 4786.95213047865, 4802.327674125625, 5032.0, 2559.1171045989104, 2561.9743802562107, 2588.197658725763, 2595.117659995573, 2679.343560827571, 1937.428458604715, 2032.319173077006, 2051.323063389078, 2105.000124940641, 2107.4099252209407, 334.7255250694456, 356.6576086956551, 364.89618902818654, 367.8197683135298, 370.646033038453, 382.31183869035624, 384.26796951806296, 401.793076565569, 408.29161404962366, 430.91381723655394, 4453.732776033448, 4935.185694360463, 5368.436314186954, 5388.084025715638, 5481.808136715275, 283.9162447078117, 305.5416874687968, 373.68236998551555, 384.3600405325158, 443.7692399952007, 2378.0, 2519.5842685956613, 2578.8839502222445, 2603.7534368247993, 2623.740604509846, 40178.0, 50779.49220507748, 52439.73780131075, 56146.71926640341, 56708.71645641745, 36339.81961082343, 39841.0, 41324.58675413267, 42268.99999999938, 50259.24611130836, 28045.01842435505, 41453.963650908365, 42688.035512542716, 42746.78626606847, 42786.28854845771, 5409.486098820606, 5436.891262174813, 5498.092814685692, 5511.807086752105, 5936.881262374642, 32187.839060804545, 34911.778087766805, 35464.404101816, 40337.79831100826, 42585.7224283272, 45262.09475810531, 47186.52813471821, 59106.70446647739, 64446.0, 64622.67688661526, 12407.193532420364, 12442.502299908265, 12664.620061398173, 12847.100702950704, 13211.207327560378, 11763.647090587296, 14580.489682861049, 14601.780973285622, 14667.48663796762, 14867.0, 13255.867441325463, 13572.117812342167, 13598.592042238748, 13692.315384230948, 13854.37655305534, 12541.74916501683, 13390.598282051555, 13413.32933353348, 13431.86568134306, 13497.190168589916, 11947.342896140795, 12148.331841748788, 12168.574099906815, 12391.566295179626, 12534.937325313314, 9453.480058596842, 9869.950650246703, 9870.062344077302, 10044.397336159855, 10263.7434064149, 8906.087215359377, 9234.307426942873, 9579.16070577017, 9642.517874106126, 9724.124828765445], "value": 4754.521945608165, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 2291.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 138.0, 141.0, 144.0, 145.0, 144.0, 143.0, 142.0, 147.0, 175.0], "value": 145.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 142.0, 144.0, 199.0, 179.0, 147.0, 149.0, 144.0, 164.0, 152.0], "value": 159.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [219.0, 219.0, 218.0, 221.0, 223.0, 223.0, 262.0, 268.0, 220.0, 218.0], "value": 229.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 77.0, 77.0, 138.0, 112.0, 77.0, 78.0, 94.0, 101.0, 82.0], "value": 93.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 98.0, 111.0, 93.0, 91.0, 95.0, 112.0, 140.0, 137.0, 145.0], "value": 113.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 216.0, 173.0, 232.0, 170.0, 174.0, 182.0, 169.0, 196.0, 216.0], "value": 189.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 172.0, 200.0, 149.0, 138.0, 160.0, 144.0, 142.0, 137.0, 143.0], "value": 151.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 163.0, 204.0, 124.0, 134.0, 168.0, 199.0, 133.0, 135.0, 172.0], "value": 155.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 117.0, 71.0, 83.0, 110.0, 132.0, 82.0, 111.0, 82.0], "value": 95.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 109.0, 124.0, 148.0, 169.0, 108.0, 138.0, 145.0, 159.0, 115.0], "value": 131.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 170.0, 147.0, 145.0, 147.0, 152.0, 140.0, 145.0, 150.0, 149.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [243.0, 212.0, 231.0, 244.0, 258.0, 249.0, 224.0, 243.0, 322.0, 293.0], "value": 251.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [293.0, 269.0, 295.0, 380.0, 354.0, 310.0, 273.0, 329.0, 295.0, 276.0], "value": 307.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 141.0, 140.0, 116.0, 114.0, 100.0, 112.0, 109.0, 107.0, 107.0], "value": 118.1, "unit": "score"}]}]} 15:37:53 INFO - Return code: 0 15:37:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:37:53 ERROR - # TBPL SUCCESS # 15:37:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:37:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:37:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:37:53 INFO - 2016-12-17 15:37:53.736 screenresolution[1914:11876] starting screenresolution argv=screenresolution get 15:37:53 INFO - 2016-12-17 15:37:53.740 screenresolution[1914:11876] Display 0: 1600x1200x32@60 15:37:53 INFO - 2016-12-17 15:37:53.748 screenresolution[1915:11879] starting screenresolution argv=screenresolution list 15:37:53 INFO - Available Modes on Display 0 15:37:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:37:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:37:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:37:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:37:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:37:54 INFO - Intel Iris: 15:37:54 INFO - Chipset Model: Intel Iris 15:37:54 INFO - Type: GPU 15:37:54 INFO - Bus: Built-In 15:37:54 INFO - VRAM (Dynamic, Max): 1536 MB 15:37:54 INFO - Vendor: Intel (0x8086) 15:37:54 INFO - Device ID: 0x0a2e 15:37:54 INFO - Revision ID: 0x0009 15:37:54 INFO - Displays: 15:37:54 INFO - Display: 15:37:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:37:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:37:54 INFO - Display Serial Number: 1600x1200 60 15:37:54 INFO - Main Display: Yes 15:37:54 INFO - Mirror: Off 15:37:54 INFO - Online: Yes 15:37:54 INFO - Rotation: Supported 15:37:54 INFO - Return code: 0 15:37:54 INFO - Running post-action listener: _resource_record_post_action 15:37:54 INFO - [mozharness: 2016-12-17 23:37:54.060760Z] Finished run-tests step (success) 15:37:54 INFO - Running post-run listener: _resource_record_post_run 15:37:54 INFO - Total resource usage - Wall time: 1235s; CPU: 56.0%; Read bytes: 33288704; Write bytes: 559191552; Read time: 220; Write time: 1816 15:37:54 INFO - TinderboxPrint: CPU usage
55.6% 15:37:54 INFO - TinderboxPrint: I/O read bytes / time
33,288,704 / 220 15:37:54 INFO - TinderboxPrint: I/O write bytes / time
559,191,552 / 1,816 15:37:54 INFO - TinderboxPrint: CPU idle
2,190.5 (44.4%) 15:37:54 INFO - TinderboxPrint: CPU system
1,019.1 (20.6%) 15:37:54 INFO - TinderboxPrint: CPU user
1,728.8 (35.0%) 15:37:54 INFO - TinderboxPrint: Swap in / out
279,433,216 / 0 15:37:54 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 250417664; Write bytes: 251652096; Read time: 30538; Write time: 543 15:37:54 INFO - run-tests - Wall time: 1199s; CPU: 56.0%; Read bytes: 31257088; Write bytes: 303121920; Read time: 201; Write time: 1251 15:37:54 INFO - Running post-run listener: _upload_blobber_files 15:37:54 INFO - Blob upload gear active. 15:37:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:37:54 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 15:37:54 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:37:54 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:37:55 INFO - (blobuploader) - INFO - Open directory for files ... 15:37:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:37:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:37:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:56 INFO - (blobuploader) - INFO - Done attempting. 15:37:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:37:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:37:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:57 INFO - (blobuploader) - INFO - Done attempting. 15:37:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:37:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:37:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:58 INFO - (blobuploader) - INFO - Done attempting. 15:37:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:37:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:38:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:38:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:38:01 INFO - (blobuploader) - INFO - Done attempting. 15:38:01 INFO - (blobuploader) - INFO - Iteration through files over. 15:38:01 INFO - Return code: 0 15:38:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:38:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:38:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a03ca482f44164abddc0573c40e7eae40a12c6192313bdb87fbfe68abc0e100f07d914f80080f6d547bc9254dcaab505b74334315bac9927e54dcbad34defdfe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee4c6d7c8a710d940f12ffa97199191b889eeb52038b2ff7317c52a91f1f9cdd810d42437374ca082d033b77b4e794eda8c005541f7e8b467eb05c2cf5fdd4c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fa8606c06db3b213cf27865f629ec6190d8bebdbc945df87fafd49a53fd7397fd1b4d56b40689b0e2b14cb503317eafbb0a858819002ae6fead12a33336b55e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06b67996ecbdc42d5b9cbf80feb80bba3955a8fdcc8d8f330b70333d8e5c1e58f78a37273f33a89ad075fe08b365149db9e11e4724297910af559069a8b7da4b"} 15:38:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:38:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:38:01 INFO - Contents: 15:38:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a03ca482f44164abddc0573c40e7eae40a12c6192313bdb87fbfe68abc0e100f07d914f80080f6d547bc9254dcaab505b74334315bac9927e54dcbad34defdfe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee4c6d7c8a710d940f12ffa97199191b889eeb52038b2ff7317c52a91f1f9cdd810d42437374ca082d033b77b4e794eda8c005541f7e8b467eb05c2cf5fdd4c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fa8606c06db3b213cf27865f629ec6190d8bebdbc945df87fafd49a53fd7397fd1b4d56b40689b0e2b14cb503317eafbb0a858819002ae6fead12a33336b55e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06b67996ecbdc42d5b9cbf80feb80bba3955a8fdcc8d8f330b70333d8e5c1e58f78a37273f33a89ad075fe08b365149db9e11e4724297910af559069a8b7da4b"} 15:38:01 INFO - Running post-run listener: copy_logs_to_upload_dir 15:38:01 INFO - Copying logs to upload dir... 15:38:01 INFO - mkdir: /builds/slave/test/build/upload/logs 15:38:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1327.158758 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 7 secs) (at 2016-12-17 15:38:01.746692) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 15:38:01.750145) ========= 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.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a03ca482f44164abddc0573c40e7eae40a12c6192313bdb87fbfe68abc0e100f07d914f80080f6d547bc9254dcaab505b74334315bac9927e54dcbad34defdfe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee4c6d7c8a710d940f12ffa97199191b889eeb52038b2ff7317c52a91f1f9cdd810d42437374ca082d033b77b4e794eda8c005541f7e8b467eb05c2cf5fdd4c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fa8606c06db3b213cf27865f629ec6190d8bebdbc945df87fafd49a53fd7397fd1b4d56b40689b0e2b14cb503317eafbb0a858819002ae6fead12a33336b55e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06b67996ecbdc42d5b9cbf80feb80bba3955a8fdcc8d8f330b70333d8e5c1e58f78a37273f33a89ad075fe08b365149db9e11e4724297910af559069a8b7da4b"} build_url:https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.013373 build_url: 'https://queue.taskcluster.net/v1/task/Qe2q_tK4QpWZ6sti6-vJ6w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a03ca482f44164abddc0573c40e7eae40a12c6192313bdb87fbfe68abc0e100f07d914f80080f6d547bc9254dcaab505b74334315bac9927e54dcbad34defdfe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee4c6d7c8a710d940f12ffa97199191b889eeb52038b2ff7317c52a91f1f9cdd810d42437374ca082d033b77b4e794eda8c005541f7e8b467eb05c2cf5fdd4c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fa8606c06db3b213cf27865f629ec6190d8bebdbc945df87fafd49a53fd7397fd1b4d56b40689b0e2b14cb503317eafbb0a858819002ae6fead12a33336b55e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06b67996ecbdc42d5b9cbf80feb80bba3955a8fdcc8d8f330b70333d8e5c1e58f78a37273f33a89ad075fe08b365149db9e11e4724297910af559069a8b7da4b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 15:38:01.792275) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 15:38:01.792595) ========= 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.pn9DmVkwT7/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.Kh4dK9ZBDF/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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.006769 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 15:38:01.842678) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 15:38:01.842998) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 15:38:01.843313) ========= ========= Total master_lag: 0.22 =========