{"level":"warn","ts":"2026-03-17T20:44:52.009775Z","caller":"embed/config.go:1209","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"warn","ts":"2026-03-17T20:44:52.009955Z","caller":"etcdmain/config.go:270","msg":"--snapshot-count is deprecated in 3.6 and will be decommissioned in 3.7."} {"level":"info","ts":"2026-03-17T20:44:52.009975Z","caller":"etcdmain/etcd.go:64","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.174:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--feature-gates=InitialCorruptCheck=true","--initial-advertise-peer-urls=https://199.19.213.174:2380","--initial-cluster=controller=https://199.19.213.174:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.174:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.174:2380","--name=controller","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--watch-progress-notify-interval=5s"]} {"level":"info","ts":"2026-03-17T20:44:52.010078Z","caller":"etcdmain/etcd.go:124","msg":"Initialize and start etcd server","data-dir":"/var/lib/etcd","dir-type":"empty"} {"level":"warn","ts":"2026-03-17T20:44:52.010118Z","caller":"embed/config.go:1209","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T20:44:52.010135Z","caller":"embed/etcd.go:138","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.174:2380"]} {"level":"info","ts":"2026-03-17T20:44:52.010162Z","caller":"embed/etcd.go:544","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-17T20:44:52.011998Z","caller":"embed/etcd.go:146","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.174:2379"]} {"level":"info","ts":"2026-03-17T20:44:52.012166Z","caller":"embed/etcd.go:323","msg":"starting an etcd server","etcd-version":"3.6.4","git-sha":"5400cdc","go-version":"go1.23.11","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"controller","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.174:2380"],"listen-peer-urls":["https://199.19.213.174:2380"],"advertise-client-urls":["https://199.19.213.174:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.174:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"experimental-local-address":"","cors":["*"],"host-whitelist":["*"],"initial-cluster":"controller=https://199.19.213.174:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"feature-gates":"InitialCorruptCheck=true","initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","discovery-token":"","discovery-endpoints":"","discovery-dial-timeout":"2s","discovery-request-timeout":"5s","discovery-keepalive-time":"2s","discovery-keepalive-timeout":"6s","discovery-insecure-transport":true,"discovery-insecure-skip-tls-verify":false,"discovery-cert":"","discovery-key":"","discovery-cacert":"","discovery-user":"","downgrade-check-interval":"5s","max-learners":1,"v2-deprecation":"write-only"} {"level":"info","ts":"2026-03-17T20:44:52.013395Z","logger":"bbolt","caller":"backend/backend.go:203","msg":"Opening db file (/var/lib/etcd/member/snap/db) with mode -rw------- and with options: {Timeout: 0s, NoGrowSync: false, NoFreelistSync: true, PreLoadFreelist: false, FreelistType: hashmap, ReadOnly: false, MmapFlags: 8000, InitialMmapSize: 10737418240, PageSize: 0, NoSync: false, OpenFile: 0x0, Mlock: false, Logger: 0xc0003ba288}"} {"level":"info","ts":"2026-03-17T20:44:52.018117Z","logger":"bbolt","caller":"bbolt@v1.4.2/db.go:321","msg":"Opening bbolt db (/var/lib/etcd/member/snap/db) successfully"} {"level":"info","ts":"2026-03-17T20:44:52.018173Z","caller":"storage/backend.go:80","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.928516ms"} {"level":"info","ts":"2026-03-17T20:44:52.018199Z","caller":"etcdserver/bootstrap.go:220","msg":"restore consistentIndex","index":0} {"level":"info","ts":"2026-03-17T20:44:52.018219Z","caller":"etcdserver/bootstrap.go:94","msg":"bootstrapping cluster"} {"level":"info","ts":"2026-03-17T20:44:52.018262Z","caller":"etcdserver/bootstrap.go:101","msg":"bootstrapping storage"} {"level":"info","ts":"2026-03-17T20:44:52.026870Z","caller":"etcdserver/bootstrap.go:109","msg":"bootstrapping raft"} {"level":"info","ts":"2026-03-17T20:44:52.026985Z","caller":"etcdserver/bootstrap.go:499","msg":"starting local member","local-member-id":"b3617fc4a93ad58b","cluster-id":"2f224b65169306de"} {"level":"info","ts":"2026-03-17T20:44:52.027034Z","caller":"etcdserver/server.go:312","msg":"bootstrap successfully"} {"level":"info","ts":"2026-03-17T20:44:52.027129Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"b3617fc4a93ad58b switched to configuration voters=()"} {"level":"info","ts":"2026-03-17T20:44:52.027200Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"b3617fc4a93ad58b became follower at term 0"} {"level":"info","ts":"2026-03-17T20:44:52.027216Z","logger":"raft","caller":"v3@v3.6.0/raft.go:493","msg":"newRaft b3617fc4a93ad58b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-17T20:44:52.027237Z","logger":"raft","caller":"v3@v3.6.0/raft.go:897","msg":"b3617fc4a93ad58b became follower at term 1"} {"level":"info","ts":"2026-03-17T20:44:52.027290Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"b3617fc4a93ad58b switched to configuration voters=(12925752888159557003)"} {"level":"warn","ts":"2026-03-17T20:44:52.034004Z","caller":"auth/store.go:1135","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-17T20:44:52.039605Z","caller":"mvcc/kvstore.go:408","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-17T20:44:52.046601Z","caller":"storage/quota.go:93","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-17T20:44:52.048472Z","caller":"etcdserver/server.go:598","msg":"starting etcd server","local-member-id":"b3617fc4a93ad58b","local-server-version":"3.6.4","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-17T20:44:52.048820Z","caller":"embed/etcd.go:766","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-17T20:44:52.049260Z","caller":"embed/etcd.go:292","msg":"now serving peer/client/metrics","local-member-id":"b3617fc4a93ad58b","initial-advertise-peer-urls":["https://199.19.213.174:2380"],"listen-peer-urls":["https://199.19.213.174:2380"],"advertise-client-urls":["https://199.19.213.174:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.174:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-17T20:44:52.049280Z","caller":"embed/etcd.go:640","msg":"serving peer traffic","address":"199.19.213.174:2380"} {"level":"info","ts":"2026-03-17T20:44:52.049359Z","caller":"embed/etcd.go:611","msg":"cmux::serve","address":"199.19.213.174:2380"} {"level":"info","ts":"2026-03-17T20:44:52.049340Z","caller":"embed/etcd.go:890","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-17T20:44:52.049394Z","caller":"etcdserver/server.go:483","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b3617fc4a93ad58b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-17T20:44:52.049957Z","caller":"fileutil/purge.go:49","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-17T20:44:52.050018Z","caller":"fileutil/purge.go:49","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-17T20:44:52.050048Z","caller":"fileutil/purge.go:49","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-17T20:44:52.050100Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1981","msg":"b3617fc4a93ad58b switched to configuration voters=(12925752888159557003)"} {"level":"info","ts":"2026-03-17T20:44:52.050218Z","caller":"membership/cluster.go:424","msg":"added member","cluster-id":"2f224b65169306de","local-member-id":"b3617fc4a93ad58b","added-peer-id":"b3617fc4a93ad58b","added-peer-peer-urls":["https://199.19.213.174:2380"],"added-peer-is-learner":false} {"level":"info","ts":"2026-03-17T20:44:52.628008Z","logger":"raft","caller":"v3@v3.6.0/raft.go:988","msg":"b3617fc4a93ad58b is starting a new election at term 1"} {"level":"info","ts":"2026-03-17T20:44:52.628075Z","logger":"raft","caller":"v3@v3.6.0/raft.go:930","msg":"b3617fc4a93ad58b became pre-candidate at term 1"} {"level":"info","ts":"2026-03-17T20:44:52.628125Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1077","msg":"b3617fc4a93ad58b received MsgPreVoteResp from b3617fc4a93ad58b at term 1"} {"level":"info","ts":"2026-03-17T20:44:52.628142Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1693","msg":"b3617fc4a93ad58b has received 1 MsgPreVoteResp votes and 0 vote rejections"} {"level":"info","ts":"2026-03-17T20:44:52.628163Z","logger":"raft","caller":"v3@v3.6.0/raft.go:912","msg":"b3617fc4a93ad58b became candidate at term 2"} {"level":"info","ts":"2026-03-17T20:44:52.629016Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1077","msg":"b3617fc4a93ad58b received MsgVoteResp from b3617fc4a93ad58b at term 2"} {"level":"info","ts":"2026-03-17T20:44:52.629041Z","logger":"raft","caller":"v3@v3.6.0/raft.go:1693","msg":"b3617fc4a93ad58b has received 1 MsgVoteResp votes and 0 vote rejections"} {"level":"info","ts":"2026-03-17T20:44:52.629070Z","logger":"raft","caller":"v3@v3.6.0/raft.go:970","msg":"b3617fc4a93ad58b became leader at term 2"} {"level":"info","ts":"2026-03-17T20:44:52.629081Z","logger":"raft","caller":"v3@v3.6.0/node.go:370","msg":"raft.node: b3617fc4a93ad58b elected leader b3617fc4a93ad58b at term 2"} {"level":"info","ts":"2026-03-17T20:44:52.631038Z","caller":"etcdserver/server.go:2404","msg":"setting up initial cluster version using v3 API","cluster-version":"3.6"} {"level":"info","ts":"2026-03-17T20:44:52.633156Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T20:44:52.633154Z","caller":"etcdserver/server.go:1804","msg":"published local member to cluster through raft","local-member-id":"b3617fc4a93ad58b","local-member-attributes":"{Name:controller ClientURLs:[https://199.19.213.174:2379]}","cluster-id":"2f224b65169306de","publish-timeout":"7s"} {"level":"info","ts":"2026-03-17T20:44:52.633671Z","caller":"embed/serve.go:138","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T20:44:52.634205Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-17T20:44:52.634255Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-17T20:44:52.634644Z","caller":"membership/cluster.go:682","msg":"set initial cluster version","cluster-id":"2f224b65169306de","local-member-id":"b3617fc4a93ad58b","cluster-version":"3.6"} {"level":"info","ts":"2026-03-17T20:44:52.634948Z","caller":"api/capability.go:76","msg":"enabled capabilities for version","cluster-version":"3.6"} {"level":"info","ts":"2026-03-17T20:44:52.635128Z","caller":"etcdserver/server.go:2424","msg":"cluster version is updated","cluster-version":"3.6"} {"level":"info","ts":"2026-03-17T20:44:52.635397Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-03-17T20:44:52.635424Z","caller":"version/monitor.go:116","msg":"cluster version differs from storage version.","cluster-version":"3.6.0","storage-version":"3.5.0"} {"level":"warn","ts":"2026-03-17T20:44:52.635614Z","caller":"v3rpc/grpc.go:52","msg":"etcdserver: failed to register grpc metrics","error":"duplicate metrics collector registration attempted"} {"level":"info","ts":"2026-03-17T20:44:52.635893Z","caller":"schema/migration.go:65","msg":"updated storage version","new-storage-version":"3.6.0"} {"level":"info","ts":"2026-03-17T20:44:52.636891Z","caller":"v3rpc/health.go:63","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-03-17T20:44:52.644182Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-17T20:44:52.644922Z","caller":"embed/serve.go:283","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.174:2379"} {"level":"warn","ts":"2026-03-17T20:44:52.648205Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46156","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.648377Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46130","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.648454Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46140","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.660656Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46196","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.684800Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46246","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.702655Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46254","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.719103Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46274","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.731881Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46294","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.742361Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46300","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.753723Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46314","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.763642Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46330","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.799530Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46392","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.809673Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46416","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.830819Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46434","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.849811Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46464","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.858005Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46472","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.867289Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46484","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.898090Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46510","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.912700Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46512","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.924600Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46532","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.936791Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46560","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.945145Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46576","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.954198Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46590","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.966682Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46602","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.979328Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46620","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:52.988485Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46636","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.005168Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46652","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.014936Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46662","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.022770Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46672","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.031973Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46696","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.041618Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46722","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.050991Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46732","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.071713Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46750","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.074206Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46772","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.088665Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46788","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.099852Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46814","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.109602Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46830","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.118213Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46860","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.127204Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46864","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.137278Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46876","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.146775Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46894","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.155278Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46904","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.168706Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46922","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.179747Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46936","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.189142Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46960","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.201828Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:46988","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.214104Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47012","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.224741Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47014","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.235654Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47028","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.245737Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47052","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.253893Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47056","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.264270Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47068","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.274222Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47080","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.282307Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47092","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.320963Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47120","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.329952Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47134","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:44:53.386735Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:47154","server-name":"","error":"EOF"} {"level":"info","ts":"2026-03-17T20:44:57.480846Z","caller":"traceutil/trace.go:172","msg":"trace[2146821726] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"102.190584ms","start":"2026-03-17T20:44:57.378379Z","end":"2026-03-17T20:44:57.480570Z","steps":["trace[2146821726] 'process raft request' (duration: 56.563203ms)","trace[2146821726] 'compare' (duration: 45.285809ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:44:57.660756Z","caller":"traceutil/trace.go:172","msg":"trace[1918814324] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"172.88509ms","start":"2026-03-17T20:44:57.487827Z","end":"2026-03-17T20:44:57.660712Z","steps":["trace[1918814324] 'process raft request' (duration: 130.15314ms)","trace[1918814324] 'compare' (duration: 42.462062ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:44:57.660911Z","caller":"traceutil/trace.go:172","msg":"trace[379747737] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"156.053248ms","start":"2026-03-17T20:44:57.504840Z","end":"2026-03-17T20:44:57.660893Z","steps":["trace[379747737] 'process raft request' (duration: 156.002005ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:44:57.660977Z","caller":"traceutil/trace.go:172","msg":"trace[1037183516] transaction","detail":"{read_only:false; number_of_response:0; response_revision:281; }","duration":"156.389561ms","start":"2026-03-17T20:44:57.504564Z","end":"2026-03-17T20:44:57.660954Z","steps":["trace[1037183516] 'process raft request' (duration: 156.050339ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:44:57.661124Z","caller":"traceutil/trace.go:172","msg":"trace[1565851629] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"156.425254ms","start":"2026-03-17T20:44:57.504688Z","end":"2026-03-17T20:44:57.661113Z","steps":["trace[1565851629] 'process raft request' (duration: 156.016157ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:44:57.661166Z","caller":"traceutil/trace.go:172","msg":"trace[849484350] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"156.331277ms","start":"2026-03-17T20:44:57.504824Z","end":"2026-03-17T20:44:57.661156Z","steps":["trace[849484350] 'process raft request' (duration: 155.91815ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:44:57.661198Z","caller":"traceutil/trace.go:172","msg":"trace[1050269519] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"156.544732ms","start":"2026-03-17T20:44:57.504628Z","end":"2026-03-17T20:44:57.661172Z","steps":["trace[1050269519] 'process raft request' (duration: 156.028328ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:44:57.664564Z","caller":"traceutil/trace.go:172","msg":"trace[927303337] transaction","detail":"{read_only:false; number_of_response:0; response_revision:285; }","duration":"100.556516ms","start":"2026-03-17T20:44:57.563957Z","end":"2026-03-17T20:44:57.664514Z","steps":["trace[927303337] 'process raft request' (duration: 99.605273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:44:59.134694Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"100.673339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" limit:1 ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-17T20:44:59.134753Z","caller":"traceutil/trace.go:172","msg":"trace[1846391641] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:327; }","duration":"100.762104ms","start":"2026-03-17T20:44:59.033980Z","end":"2026-03-17T20:44:59.134742Z","steps":["trace[1846391641] 'range keys from in-memory index tree' (duration: 100.520549ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:44:59.829490Z","caller":"traceutil/trace.go:172","msg":"trace[966911768] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"104.59378ms","start":"2026-03-17T20:44:59.724870Z","end":"2026-03-17T20:44:59.829463Z","steps":["trace[966911768] 'process raft request' (duration: 60.409606ms)","trace[966911768] 'compare' (duration: 43.777337ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:00.491132Z","caller":"traceutil/trace.go:172","msg":"trace[1747944049] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"106.192528ms","start":"2026-03-17T20:45:00.384917Z","end":"2026-03-17T20:45:00.491109Z","steps":["trace[1747944049] 'process raft request' (duration: 106.049359ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:01.042246Z","caller":"traceutil/trace.go:172","msg":"trace[1203358869] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"108.386507ms","start":"2026-03-17T20:45:00.933832Z","end":"2026-03-17T20:45:01.042218Z","steps":["trace[1203358869] 'process raft request' (duration: 87.223285ms)","trace[1203358869] 'compare' (duration: 20.998701ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:03.215634Z","caller":"traceutil/trace.go:172","msg":"trace[553987471] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"147.461969ms","start":"2026-03-17T20:45:03.068059Z","end":"2026-03-17T20:45:03.215521Z","steps":["trace[553987471] 'process raft request' (duration: 119.947994ms)","trace[553987471] 'compare' (duration: 27.153171ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:03.215750Z","caller":"traceutil/trace.go:172","msg":"trace[1888742912] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"147.645281ms","start":"2026-03-17T20:45:03.068082Z","end":"2026-03-17T20:45:03.215727Z","steps":["trace[1888742912] 'process raft request' (duration: 147.337081ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:12.075731Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"237.110091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:45:12.075836Z","caller":"traceutil/trace.go:172","msg":"trace[1337198719] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"346.394134ms","start":"2026-03-17T20:45:11.729425Z","end":"2026-03-17T20:45:12.075819Z","steps":["trace[1337198719] 'process raft request' (duration: 108.673269ms)","trace[1337198719] 'compare' (duration: 236.916728ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:12.075887Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:11.729395Z","time spent":"346.47061ms","remote":"127.0.0.1:46272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T20:45:12.130077Z","caller":"traceutil/trace.go:172","msg":"trace[1679922799] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:451; }","duration":"150.654904ms","start":"2026-03-17T20:45:11.979404Z","end":"2026-03-17T20:45:12.130059Z","steps":["trace[1679922799] 'read index received' (duration: 150.647044ms)","trace[1679922799] 'applied index is now lower than readState.Index' (duration: 7.03µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:12.130284Z","caller":"traceutil/trace.go:172","msg":"trace[1316814585] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"395.888417ms","start":"2026-03-17T20:45:11.734378Z","end":"2026-03-17T20:45:12.130267Z","steps":["trace[1316814585] 'process raft request' (duration: 395.829243ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:12.130327Z","caller":"traceutil/trace.go:172","msg":"trace[717768662] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"399.037406ms","start":"2026-03-17T20:45:11.731269Z","end":"2026-03-17T20:45:12.130307Z","steps":["trace[717768662] 'process raft request' (duration: 398.835822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:12.130390Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"150.961085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-17T20:45:12.130416Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:11.731256Z","time spent":"399.122962ms","remote":"127.0.0.1:46448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3848,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T20:45:12.130439Z","caller":"traceutil/trace.go:172","msg":"trace[227123310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:442; }","duration":"151.02825ms","start":"2026-03-17T20:45:11.979399Z","end":"2026-03-17T20:45:12.130427Z","steps":["trace[227123310] 'agreement among raft nodes before linearized reading' (duration: 150.850897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:12.130597Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:11.734334Z","time spent":"396.020566ms","remote":"127.0.0.1:46944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:12.380038Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"155.893905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:45:12.380147Z","caller":"traceutil/trace.go:172","msg":"trace[1353919054] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"242.292549ms","start":"2026-03-17T20:45:12.137837Z","end":"2026-03-17T20:45:12.380129Z","steps":["trace[1353919054] 'process raft request' (duration: 86.231343ms)","trace[1353919054] 'compare' (duration: 155.775446ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:12.389536Z","caller":"traceutil/trace.go:172","msg":"trace[854075815] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"251.45526ms","start":"2026-03-17T20:45:12.138050Z","end":"2026-03-17T20:45:12.389505Z","steps":["trace[854075815] 'process raft request' (duration: 251.236535ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:12.389961Z","caller":"traceutil/trace.go:172","msg":"trace[1015065290] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"249.283447ms","start":"2026-03-17T20:45:12.140654Z","end":"2026-03-17T20:45:12.389938Z","steps":["trace[1015065290] 'process raft request' (duration: 249.208402ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:12.390285Z","caller":"traceutil/trace.go:172","msg":"trace[543573747] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"252.097624ms","start":"2026-03-17T20:45:12.138170Z","end":"2026-03-17T20:45:12.390267Z","steps":["trace[543573747] 'process raft request' (duration: 251.301709ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:12.390318Z","caller":"traceutil/trace.go:172","msg":"trace[1989903140] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"250.036ms","start":"2026-03-17T20:45:12.140274Z","end":"2026-03-17T20:45:12.390310Z","steps":["trace[1989903140] 'process raft request' (duration: 249.522844ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:12.390713Z","caller":"traceutil/trace.go:172","msg":"trace[316882204] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"252.225244ms","start":"2026-03-17T20:45:12.138470Z","end":"2026-03-17T20:45:12.390695Z","steps":["trace[316882204] 'process raft request' (duration: 251.232405ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:12.389946Z","caller":"traceutil/trace.go:172","msg":"trace[407296098] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"251.434728ms","start":"2026-03-17T20:45:12.138491Z","end":"2026-03-17T20:45:12.389926Z","steps":["trace[407296098] 'process raft request' (duration: 251.274467ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:12.428907Z","caller":"traceutil/trace.go:172","msg":"trace[870291042] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"202.404369ms","start":"2026-03-17T20:45:12.226477Z","end":"2026-03-17T20:45:12.428882Z","steps":["trace[870291042] 'process raft request' (duration: 202.156642ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:12.689359Z","caller":"traceutil/trace.go:172","msg":"trace[1912021872] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:463; }","duration":"250.435909ms","start":"2026-03-17T20:45:12.438885Z","end":"2026-03-17T20:45:12.689321Z","steps":["trace[1912021872] 'read index received' (duration: 250.422958ms)","trace[1912021872] 'applied index is now lower than readState.Index' (duration: 10.86µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:12.792190Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"357.420571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7488bc8bd5\" limit:1 ","response":"range_response_count:1 size:4137"} {"level":"warn","ts":"2026-03-17T20:45:12.792267Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"102.727535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:45:12.792281Z","caller":"traceutil/trace.go:172","msg":"trace[420587466] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7488bc8bd5; range_end:; response_count:1; response_revision:452; }","duration":"357.520448ms","start":"2026-03-17T20:45:12.434736Z","end":"2026-03-17T20:45:12.792256Z","steps":["trace[420587466] 'agreement among raft nodes before linearized reading' (duration: 254.765251ms)","trace[420587466] 'range keys from in-memory index tree' (duration: 102.379981ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:12.792347Z","caller":"traceutil/trace.go:172","msg":"trace[616666160] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"360.671529ms","start":"2026-03-17T20:45:12.431665Z","end":"2026-03-17T20:45:12.792337Z","steps":["trace[616666160] 'process raft request' (duration: 257.820215ms)","trace[616666160] 'compare' (duration: 102.570314ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:12.792338Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:12.434727Z","time spent":"357.599194ms","remote":"127.0.0.1:47000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":4160,"request content":"key:\"/registry/replicasets/kube-system/coredns-7488bc8bd5\" limit:1 "} {"level":"warn","ts":"2026-03-17T20:45:12.792388Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:12.431640Z","time spent":"360.734323ms","remote":"127.0.0.1:46448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T20:45:12.793024Z","caller":"traceutil/trace.go:172","msg":"trace[1242679176] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"345.375341ms","start":"2026-03-17T20:45:12.447633Z","end":"2026-03-17T20:45:12.793009Z","steps":["trace[1242679176] 'process raft request' (duration: 345.253732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:12.793130Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:12.447615Z","time spent":"345.456636ms","remote":"127.0.0.1:47000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4122,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T20:45:12.793282Z","caller":"traceutil/trace.go:172","msg":"trace[1951378667] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"249.538506ms","start":"2026-03-17T20:45:12.543718Z","end":"2026-03-17T20:45:12.793257Z","steps":["trace[1951378667] 'process raft request' (duration: 249.262207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:13.236313Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"212.836592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:45:13.237413Z","caller":"traceutil/trace.go:172","msg":"trace[261045322] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"380.207013ms","start":"2026-03-17T20:45:12.857184Z","end":"2026-03-17T20:45:13.237391Z","steps":["trace[261045322] 'process raft request' (duration: 380.024441ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:13.237392Z","caller":"traceutil/trace.go:172","msg":"trace[1353067799] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"380.567719ms","start":"2026-03-17T20:45:12.856749Z","end":"2026-03-17T20:45:13.237317Z","steps":["trace[1353067799] 'process raft request' (duration: 166.641192ms)","trace[1353067799] 'compare' (duration: 212.727063ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:13.237734Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:12.857164Z","time spent":"380.328032ms","remote":"127.0.0.1:46272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T20:45:13.237811Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:12.856732Z","time spent":"380.942376ms","remote":"127.0.0.1:47000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4086,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T20:45:13.241004Z","caller":"traceutil/trace.go:172","msg":"trace[1993976532] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"366.365797ms","start":"2026-03-17T20:45:12.874621Z","end":"2026-03-17T20:45:13.240987Z","steps":["trace[1993976532] 'process raft request' (duration: 366.303323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:13.241131Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:12.874595Z","time spent":"366.470734ms","remote":"127.0.0.1:46944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T20:45:13.241359Z","caller":"traceutil/trace.go:172","msg":"trace[1055714147] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"368.562461ms","start":"2026-03-17T20:45:12.872788Z","end":"2026-03-17T20:45:13.241351Z","steps":["trace[1055714147] 'process raft request' (duration: 368.056485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:13.241390Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:12.872770Z","time spent":"368.604663ms","remote":"127.0.0.1:46448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3848,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T20:45:13.498639Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"176.434318ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:13.498725Z","caller":"traceutil/trace.go:172","msg":"trace[1873335142] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:462; }","duration":"176.567346ms","start":"2026-03-17T20:45:13.322140Z","end":"2026-03-17T20:45:13.498707Z","steps":["trace[1873335142] 'agreement among raft nodes before linearized reading' (duration: 80.213576ms)","trace[1873335142] 'range keys from in-memory index tree' (duration: 96.230022ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:13.498828Z","caller":"traceutil/trace.go:172","msg":"trace[462683497] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"252.405797ms","start":"2026-03-17T20:45:13.246402Z","end":"2026-03-17T20:45:13.498808Z","steps":["trace[462683497] 'process raft request' (duration: 156.077609ms)","trace[462683497] 'compare' (duration: 95.989554ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:13.500479Z","caller":"traceutil/trace.go:172","msg":"trace[1515661060] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"249.848458ms","start":"2026-03-17T20:45:13.250617Z","end":"2026-03-17T20:45:13.500466Z","steps":["trace[1515661060] 'process raft request' (duration: 249.798555ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:13.500549Z","caller":"traceutil/trace.go:172","msg":"trace[1456620100] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"252.205383ms","start":"2026-03-17T20:45:13.248330Z","end":"2026-03-17T20:45:13.500536Z","steps":["trace[1456620100] 'process raft request' (duration: 251.998819ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:13.500442Z","caller":"traceutil/trace.go:172","msg":"trace[1341165252] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"253.936214ms","start":"2026-03-17T20:45:13.246462Z","end":"2026-03-17T20:45:13.500398Z","steps":["trace[1341165252] 'process raft request' (duration: 253.73244ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:13.500966Z","caller":"traceutil/trace.go:172","msg":"trace[1206487797] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"253.050562ms","start":"2026-03-17T20:45:13.247453Z","end":"2026-03-17T20:45:13.500504Z","steps":["trace[1206487797] 'process raft request' (duration: 252.818356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:13.839158Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"102.852986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:45:13.839268Z","caller":"traceutil/trace.go:172","msg":"trace[518360217] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"251.012159ms","start":"2026-03-17T20:45:13.588229Z","end":"2026-03-17T20:45:13.839241Z","steps":["trace[518360217] 'process raft request' (duration: 148.006212ms)","trace[518360217] 'compare' (duration: 102.137916ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:16.536236Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"214.236142ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:16.536391Z","caller":"traceutil/trace.go:172","msg":"trace[1148371443] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:475; }","duration":"214.396093ms","start":"2026-03-17T20:45:16.321961Z","end":"2026-03-17T20:45:16.536357Z","steps":["trace[1148371443] 'range keys from in-memory index tree' (duration: 214.175398ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:16.536417Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"130.512201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-17T20:45:16.536495Z","caller":"traceutil/trace.go:172","msg":"trace[629923585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"130.602107ms","start":"2026-03-17T20:45:16.405873Z","end":"2026-03-17T20:45:16.536475Z","steps":["trace[629923585] 'agreement among raft nodes before linearized reading' (duration: 70.67969ms)","trace[629923585] 'range keys from in-memory index tree' (duration: 59.646258ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:16.536496Z","caller":"traceutil/trace.go:172","msg":"trace[377702893] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"467.31127ms","start":"2026-03-17T20:45:16.069163Z","end":"2026-03-17T20:45:16.536474Z","steps":["trace[377702893] 'process raft request' (duration: 407.430016ms)","trace[377702893] 'compare' (duration: 59.590544ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:16.536667Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:16.069131Z","time spent":"467.418719ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T20:45:16.547831Z","caller":"traceutil/trace.go:172","msg":"trace[991108565] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"107.904652ms","start":"2026-03-17T20:45:16.439914Z","end":"2026-03-17T20:45:16.547819Z","steps":["trace[991108565] 'process raft request' (duration: 107.796514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:16.547922Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"140.663938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-17T20:45:16.547994Z","caller":"traceutil/trace.go:172","msg":"trace[614701800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"140.746364ms","start":"2026-03-17T20:45:16.407232Z","end":"2026-03-17T20:45:16.547978Z","steps":["trace[614701800] 'agreement among raft nodes before linearized reading' (duration: 140.518798ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:18.318295Z","caller":"traceutil/trace.go:172","msg":"trace[1897176522] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"194.107036ms","start":"2026-03-17T20:45:18.124168Z","end":"2026-03-17T20:45:18.318275Z","steps":["trace[1897176522] 'process raft request' (duration: 193.970556ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:20.720082Z","caller":"traceutil/trace.go:172","msg":"trace[255250883] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:497; }","duration":"115.247039ms","start":"2026-03-17T20:45:20.604811Z","end":"2026-03-17T20:45:20.720058Z","steps":["trace[255250883] 'read index received' (duration: 115.241038ms)","trace[255250883] 'applied index is now lower than readState.Index' (duration: 5.031µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:20.720295Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"115.465545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-17T20:45:20.720347Z","caller":"traceutil/trace.go:172","msg":"trace[771561583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:485; }","duration":"115.528679ms","start":"2026-03-17T20:45:20.604807Z","end":"2026-03-17T20:45:20.720335Z","steps":["trace[771561583] 'agreement among raft nodes before linearized reading' (duration: 115.363208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:20.720502Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"104.235933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-17T20:45:20.720536Z","caller":"traceutil/trace.go:172","msg":"trace[846598331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:486; }","duration":"104.268375ms","start":"2026-03-17T20:45:20.616259Z","end":"2026-03-17T20:45:20.720528Z","steps":["trace[846598331] 'agreement among raft nodes before linearized reading' (duration: 104.188909ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:20.720272Z","caller":"traceutil/trace.go:172","msg":"trace[1925441981] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"126.128436ms","start":"2026-03-17T20:45:20.594121Z","end":"2026-03-17T20:45:20.720249Z","steps":["trace[1925441981] 'process raft request' (duration: 125.990206ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:20.882722Z","caller":"traceutil/trace.go:172","msg":"trace[347263803] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"157.00045ms","start":"2026-03-17T20:45:20.725700Z","end":"2026-03-17T20:45:20.882701Z","steps":["trace[347263803] 'process raft request' (duration: 139.585907ms)","trace[347263803] 'compare' (duration: 17.317706ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:20.941169Z","caller":"traceutil/trace.go:172","msg":"trace[43803958] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"215.280723ms","start":"2026-03-17T20:45:20.725864Z","end":"2026-03-17T20:45:20.941145Z","steps":["trace[43803958] 'process raft request' (duration: 215.10851ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:21.274226Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"155.148743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:45:21.274387Z","caller":"traceutil/trace.go:172","msg":"trace[894507994] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"402.011109ms","start":"2026-03-17T20:45:20.872353Z","end":"2026-03-17T20:45:21.274364Z","steps":["trace[894507994] 'process raft request' (duration: 246.578066ms)","trace[894507994] 'compare' (duration: 154.951698ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:21.275926Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:20.872335Z","time spent":"403.542398ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:21.535810Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"174.376419ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-17T20:45:23.951479Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619480931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T20:45:24.451749Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619480931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T20:45:24.952244Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619480931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T20:45:25.450770Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.999993764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-17T20:45:25.450935Z","caller":"traceutil/trace.go:172","msg":"trace[157757192] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.0001952s","start":"2026-03-17T20:45:23.450701Z","end":"2026-03-17T20:45:25.450896Z","steps":["trace[157757192] 'agreement among raft nodes before linearized reading' (duration: 1.999983375s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:25.451188Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:23.450672Z","time spent":"2.00048325s","remote":"127.0.0.1:46132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/17 20:45:25 WARNING: [core] [Server #5]grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-17T20:45:25.453177Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619480931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T20:45:25.954053Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619480931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T20:45:26.454730Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619480931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T20:45:26.955616Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619480931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T20:45:27.455947Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619480931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T20:45:27.460076Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.999860453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-17T20:45:27.460139Z","caller":"traceutil/trace.go:172","msg":"trace[2067549760] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999932108s","start":"2026-03-17T20:45:25.460190Z","end":"2026-03-17T20:45:27.460123Z","steps":["trace[2067549760] 'agreement among raft nodes before linearized reading' (duration: 1.999858423s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:27.460191Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:25.460172Z","time spent":"2.000010764s","remote":"127.0.0.1:46142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/17 20:45:27 WARNING: [core] [Server #5]grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-17T20:45:27.956414Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619480931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T20:45:28.280506Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:23.282189Z","time spent":"4.998309054s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-03-17T20:45:28.456768Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619480931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T20:45:28.663609Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"5.381255791s","expected-duration":"1s"} {"level":"info","ts":"2026-03-17T20:45:28.663984Z","caller":"traceutil/trace.go:172","msg":"trace[131110075] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:503; }","duration":"5.213236557s","start":"2026-03-17T20:45:23.450717Z","end":"2026-03-17T20:45:28.663953Z","steps":["trace[131110075] 'read index received' (duration: 5.213227016s)","trace[131110075] 'applied index is now lower than readState.Index' (duration: 8.07µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:28.949630Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"6.062586318s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-17T20:45:28.949693Z","caller":"traceutil/trace.go:172","msg":"trace[1423840690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:490; }","duration":"6.062658783s","start":"2026-03-17T20:45:22.887021Z","end":"2026-03-17T20:45:28.949680Z","steps":["trace[1423840690] 'range keys from in-memory index tree' (duration: 6.062371283s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:28.949724Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:22.886994Z","time spent":"6.062721598s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-03-17T20:45:28.949717Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"4.850941519s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:28.949780Z","caller":"traceutil/trace.go:172","msg":"trace[474469003] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:490; }","duration":"4.851017635s","start":"2026-03-17T20:45:24.098752Z","end":"2026-03-17T20:45:28.949769Z","steps":["trace[474469003] 'range keys from in-memory index tree' (duration: 4.850890755s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:28.949920Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"6.003024867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-17T20:45:28.949945Z","caller":"traceutil/trace.go:172","msg":"trace[1387266936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:490; }","duration":"6.003051099s","start":"2026-03-17T20:45:22.946887Z","end":"2026-03-17T20:45:28.949939Z","steps":["trace[1387266936] 'range keys from in-memory index tree' (duration: 6.002935111s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:28.949964Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:22.946867Z","time spent":"6.003091552s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-03-17T20:45:28.950347Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"5.628317178s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:28.950437Z","caller":"traceutil/trace.go:172","msg":"trace[15830164] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:490; }","duration":"5.628418385s","start":"2026-03-17T20:45:23.321994Z","end":"2026-03-17T20:45:28.950413Z","steps":["trace[15830164] 'range keys from in-memory index tree' (duration: 5.628237991s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:28.950717Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"286.512031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-03-17T20:45:29.164674Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619480937,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-17T20:45:29.268873Z","caller":"traceutil/trace.go:172","msg":"trace[45214192] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:504; }","duration":"604.774979ms","start":"2026-03-17T20:45:28.664056Z","end":"2026-03-17T20:45:29.268831Z","steps":["trace[45214192] 'read index received' (duration: 604.738347ms)","trace[45214192] 'applied index is now lower than readState.Index' (duration: 33.942µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:29.328978Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"3.524527679s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.174\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-17T20:45:29.329000Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.867548211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:29.329073Z","caller":"traceutil/trace.go:172","msg":"trace[1586971210] range","detail":"{range_begin:/registry/masterleases/199.19.213.174; range_end:; response_count:1; response_revision:491; }","duration":"3.524631806s","start":"2026-03-17T20:45:25.804417Z","end":"2026-03-17T20:45:29.329049Z","steps":["trace[1586971210] 'agreement among raft nodes before linearized reading' (duration: 3.464543851s)","trace[1586971210] 'range keys from in-memory index tree' (duration: 59.851329ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:29.329116Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:25.804390Z","time spent":"3.524716112s","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.174\" limit:1 "} {"level":"warn","ts":"2026-03-17T20:45:29.329186Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.047112591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-17T20:45:29.329272Z","caller":"traceutil/trace.go:172","msg":"trace[422123097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:491; }","duration":"1.047204438s","start":"2026-03-17T20:45:28.282045Z","end":"2026-03-17T20:45:29.329249Z","steps":["trace[422123097] 'agreement among raft nodes before linearized reading' (duration: 986.874605ms)","trace[422123097] 'range keys from in-memory index tree' (duration: 60.118248ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:29.329326Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:28.282022Z","time spent":"1.047293984s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 "} {"level":"info","ts":"2026-03-17T20:45:29.329491Z","caller":"traceutil/trace.go:172","msg":"trace[864695459] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"834.951162ms","start":"2026-03-17T20:45:28.494523Z","end":"2026-03-17T20:45:29.329475Z","steps":["trace[864695459] 'process raft request' (duration: 834.907409ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:29.329515Z","caller":"traceutil/trace.go:172","msg":"trace[20974705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:491; }","duration":"1.867647178s","start":"2026-03-17T20:45:27.461421Z","end":"2026-03-17T20:45:29.329068Z","steps":["trace[20974705] 'agreement among raft nodes before linearized reading' (duration: 1.807494038s)","trace[20974705] 'range keys from in-memory index tree' (duration: 60.004469ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:29.329630Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:27.461392Z","time spent":"1.86822497s","remote":"127.0.0.1:46132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-17T20:45:29.329659Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:28.494490Z","time spent":"835.062991ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T20:45:29.329648Z","caller":"traceutil/trace.go:172","msg":"trace[1424863730] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"4.80747771s","start":"2026-03-17T20:45:24.522013Z","end":"2026-03-17T20:45:29.329490Z","steps":["trace[1424863730] 'process raft request' (duration: 4.746888438s)","trace[1424863730] 'compare' (duration: 60.315402ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:29.329797Z","caller":"traceutil/trace.go:172","msg":"trace[995058284] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"3.874074305s","start":"2026-03-17T20:45:25.455712Z","end":"2026-03-17T20:45:29.329786Z","steps":["trace[995058284] 'process raft request' (duration: 3.873678217s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:29.329860Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:25.455678Z","time spent":"3.874146431s","remote":"127.0.0.1:46272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":769,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T20:45:29.330780Z","caller":"traceutil/trace.go:172","msg":"trace[1062232323] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"4.54070246s","start":"2026-03-17T20:45:24.788783Z","end":"2026-03-17T20:45:29.329485Z","steps":["trace[1062232323] 'process raft request' (duration: 4.54056401s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:29.331712Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:24.788755Z","time spent":"4.542225431s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:29.332396Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:24.521965Z","time spent":"4.807846006s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":675,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:29.349201Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"398.710823ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:29.349267Z","caller":"traceutil/trace.go:172","msg":"trace[1514345110] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:495; }","duration":"398.772467ms","start":"2026-03-17T20:45:28.950473Z","end":"2026-03-17T20:45:29.349246Z","steps":["trace[1514345110] 'agreement among raft nodes before linearized reading' (duration: 398.683721ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:29.349686Z","caller":"traceutil/trace.go:172","msg":"trace[985940485] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"392.118074ms","start":"2026-03-17T20:45:28.957543Z","end":"2026-03-17T20:45:29.349661Z","steps":["trace[985940485] 'process raft request' (duration: 392.05995ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:29.349720Z","caller":"traceutil/trace.go:172","msg":"trace[1928475458] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"392.232693ms","start":"2026-03-17T20:45:28.957466Z","end":"2026-03-17T20:45:29.349699Z","steps":["trace[1928475458] 'process raft request' (duration: 391.980734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:29.349946Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:28.957538Z","time spent":"392.230483ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":425,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:29.350060Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:28.957447Z","time spent":"392.47783ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:31.514452Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"127.680636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:45:31.514545Z","caller":"traceutil/trace.go:172","msg":"trace[343573742] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"156.484096ms","start":"2026-03-17T20:45:31.358049Z","end":"2026-03-17T20:45:31.514533Z","steps":["trace[343573742] 'process raft request' (duration: 28.65926ms)","trace[343573742] 'compare' (duration: 127.586979ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:31.525068Z","caller":"traceutil/trace.go:172","msg":"trace[949456686] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"120.107324ms","start":"2026-03-17T20:45:31.404948Z","end":"2026-03-17T20:45:31.525056Z","steps":["trace[949456686] 'process raft request' (duration: 120.05041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:34.569958Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"247.22397ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:34.570019Z","caller":"traceutil/trace.go:172","msg":"trace[106736157] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:541; }","duration":"247.296015ms","start":"2026-03-17T20:45:34.322713Z","end":"2026-03-17T20:45:34.570009Z","steps":["trace[106736157] 'range keys from in-memory index tree' (duration: 247.180396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:34.570030Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"471.038925ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:34.570126Z","caller":"traceutil/trace.go:172","msg":"trace[1533220740] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:541; }","duration":"471.149783ms","start":"2026-03-17T20:45:34.098952Z","end":"2026-03-17T20:45:34.570102Z","steps":["trace[1533220740] 'range keys from in-memory index tree' (duration: 470.983581ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:35.632505Z","caller":"traceutil/trace.go:172","msg":"trace[98819702] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:557; }","duration":"102.459051ms","start":"2026-03-17T20:45:35.530025Z","end":"2026-03-17T20:45:35.632484Z","steps":["trace[98819702] 'read index received' (duration: 102.45202ms)","trace[98819702] 'applied index is now lower than readState.Index' (duration: 6.051µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:35.643543Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"113.50315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-17T20:45:35.643597Z","caller":"traceutil/trace.go:172","msg":"trace[791734572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:542; }","duration":"113.570545ms","start":"2026-03-17T20:45:35.530019Z","end":"2026-03-17T20:45:35.643589Z","steps":["trace[791734572] 'agreement among raft nodes before linearized reading' (duration: 102.553627ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:35.643702Z","caller":"traceutil/trace.go:172","msg":"trace[1487886069] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"128.065768ms","start":"2026-03-17T20:45:35.515604Z","end":"2026-03-17T20:45:35.643670Z","steps":["trace[1487886069] 'process raft request' (duration: 116.995136ms)","trace[1487886069] 'compare' (duration: 10.815033ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:35.650607Z","caller":"traceutil/trace.go:172","msg":"trace[1535164572] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"107.280135ms","start":"2026-03-17T20:45:35.543304Z","end":"2026-03-17T20:45:35.650584Z","steps":["trace[1535164572] 'process raft request' (duration: 107.149196ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:35.965428Z","caller":"traceutil/trace.go:172","msg":"trace[1485199451] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"120.909314ms","start":"2026-03-17T20:45:35.844499Z","end":"2026-03-17T20:45:35.965408Z","steps":["trace[1485199451] 'process raft request' (duration: 92.456359ms)","trace[1485199451] 'compare' (duration: 28.341537ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:37.863260Z","caller":"traceutil/trace.go:172","msg":"trace[1251579517] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:562; }","duration":"214.914836ms","start":"2026-03-17T20:45:37.648312Z","end":"2026-03-17T20:45:37.863227Z","steps":["trace[1251579517] 'read index received' (duration: 214.901685ms)","trace[1251579517] 'applied index is now lower than readState.Index' (duration: 8.531µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:37.863458Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"215.126662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-17T20:45:37.863485Z","caller":"traceutil/trace.go:172","msg":"trace[564087554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"215.170746ms","start":"2026-03-17T20:45:37.648307Z","end":"2026-03-17T20:45:37.863478Z","steps":["trace[564087554] 'agreement among raft nodes before linearized reading' (duration: 215.030735ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:37.863554Z","caller":"traceutil/trace.go:172","msg":"trace[354390923] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"346.79412ms","start":"2026-03-17T20:45:37.516735Z","end":"2026-03-17T20:45:37.863529Z","steps":["trace[354390923] 'process raft request' (duration: 346.560973ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:37.863988Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:37.516710Z","time spent":"347.164548ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:37.868856Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"160.740795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-17T20:45:37.868916Z","caller":"traceutil/trace.go:172","msg":"trace[1922631709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"160.812411ms","start":"2026-03-17T20:45:37.708090Z","end":"2026-03-17T20:45:37.868902Z","steps":["trace[1922631709] 'agreement among raft nodes before linearized reading' (duration: 160.620386ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:37.869111Z","caller":"traceutil/trace.go:172","msg":"trace[1272550295] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"211.54558ms","start":"2026-03-17T20:45:37.657547Z","end":"2026-03-17T20:45:37.869092Z","steps":["trace[1272550295] 'process raft request' (duration: 211.227376ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:42.201171Z","caller":"traceutil/trace.go:172","msg":"trace[233242736] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:576; }","duration":"312.757123ms","start":"2026-03-17T20:45:41.888387Z","end":"2026-03-17T20:45:42.201144Z","steps":["trace[233242736] 'read index received' (duration: 312.747233ms)","trace[233242736] 'applied index is now lower than readState.Index' (duration: 8.49µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:42.201453Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"313.057711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-17T20:45:42.201608Z","caller":"traceutil/trace.go:172","msg":"trace[1597580682] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"322.40075ms","start":"2026-03-17T20:45:41.879138Z","end":"2026-03-17T20:45:42.201539Z","steps":["trace[1597580682] 'process raft request' (duration: 322.237736ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:42.201657Z","caller":"traceutil/trace.go:172","msg":"trace[848411437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"313.278434ms","start":"2026-03-17T20:45:41.888362Z","end":"2026-03-17T20:45:42.201640Z","steps":["trace[848411437] 'agreement among raft nodes before linearized reading' (duration: 312.903727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:42.201774Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:41.879108Z","time spent":"322.571884ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:42.201840Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:41.888320Z","time spent":"313.503115ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"info","ts":"2026-03-17T20:45:42.689455Z","caller":"traceutil/trace.go:172","msg":"trace[1551631973] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:577; }","duration":"488.165849ms","start":"2026-03-17T20:45:42.201243Z","end":"2026-03-17T20:45:42.689409Z","steps":["trace[1551631973] 'read index received' (duration: 488.14809ms)","trace[1551631973] 'applied index is now lower than readState.Index' (duration: 15.929µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:42.744560Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"852.728583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-17T20:45:42.744643Z","caller":"traceutil/trace.go:172","msg":"trace[325397214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:561; }","duration":"852.834559ms","start":"2026-03-17T20:45:41.891795Z","end":"2026-03-17T20:45:42.744630Z","steps":["trace[325397214] 'agreement among raft nodes before linearized reading' (duration: 797.827107ms)","trace[325397214] 'range keys from in-memory index tree' (duration: 54.802849ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:42.744684Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:41.891771Z","time spent":"852.907276ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-03-17T20:45:42.745059Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"422.682775ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:42.745080Z","caller":"traceutil/trace.go:172","msg":"trace[940408105] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:561; }","duration":"422.705865ms","start":"2026-03-17T20:45:42.322370Z","end":"2026-03-17T20:45:42.745076Z","steps":["trace[940408105] 'range keys from in-memory index tree' (duration: 422.627018ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:42.746531Z","caller":"traceutil/trace.go:172","msg":"trace[697883] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"863.487952ms","start":"2026-03-17T20:45:41.882995Z","end":"2026-03-17T20:45:42.746483Z","steps":["trace[697883] 'process raft request' (duration: 806.217042ms)","trace[697883] 'compare' (duration: 55.687267ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:42.748114Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:41.882946Z","time spent":"863.731263ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T20:45:42.752828Z","caller":"traceutil/trace.go:172","msg":"trace[794691522] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"546.297858ms","start":"2026-03-17T20:45:42.206505Z","end":"2026-03-17T20:45:42.752802Z","steps":["trace[794691522] 'process raft request' (duration: 546.130864ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:42.753213Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:42.206479Z","time spent":"546.445252ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T20:45:44.794653Z","caller":"traceutil/trace.go:172","msg":"trace[177074593] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:580; }","duration":"472.948917ms","start":"2026-03-17T20:45:44.321682Z","end":"2026-03-17T20:45:44.794631Z","steps":["trace[177074593] 'read index received' (duration: 472.939897ms)","trace[177074593] 'applied index is now lower than readState.Index' (duration: 7.57µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:44.794822Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"473.126941ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:44.795007Z","caller":"traceutil/trace.go:172","msg":"trace[917807443] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:564; }","duration":"473.323175ms","start":"2026-03-17T20:45:44.321675Z","end":"2026-03-17T20:45:44.794998Z","steps":["trace[917807443] 'agreement among raft nodes before linearized reading' (duration: 473.061563ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:44.795065Z","caller":"traceutil/trace.go:172","msg":"trace[1117135048] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"588.347332ms","start":"2026-03-17T20:45:44.206653Z","end":"2026-03-17T20:45:44.795000Z","steps":["trace[1117135048] 'process raft request' (duration: 588.167478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:44.795212Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:44.206622Z","time spent":"588.513506ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:45.295082Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619481100,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-17T20:45:45.495833Z","caller":"traceutil/trace.go:172","msg":"trace[618626252] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:581; }","duration":"701.093151ms","start":"2026-03-17T20:45:44.794717Z","end":"2026-03-17T20:45:45.495811Z","steps":["trace[618626252] 'read index received' (duration: 701.084191ms)","trace[618626252] 'applied index is now lower than readState.Index' (duration: 7.75µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:45.745889Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"423.913018ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:45.745972Z","caller":"traceutil/trace.go:172","msg":"trace[1013735504] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:565; }","duration":"424.014135ms","start":"2026-03-17T20:45:45.321941Z","end":"2026-03-17T20:45:45.745955Z","steps":["trace[1013735504] 'range keys from in-memory index tree' (duration: 423.86936ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:45.746205Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"988.753678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-17T20:45:45.746286Z","caller":"traceutil/trace.go:172","msg":"trace[1557041849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"988.848335ms","start":"2026-03-17T20:45:44.757416Z","end":"2026-03-17T20:45:45.746264Z","steps":["trace[1557041849] 'agreement among raft nodes before linearized reading' (duration: 738.425043ms)","trace[1557041849] 'range keys from in-memory index tree' (duration: 250.219958ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:45.746311Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"966.753052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-03-17T20:45:45.746331Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:44.757405Z","time spent":"988.914354ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"info","ts":"2026-03-17T20:45:45.746359Z","caller":"traceutil/trace.go:172","msg":"trace[475547179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:565; }","duration":"966.801191ms","start":"2026-03-17T20:45:44.779542Z","end":"2026-03-17T20:45:45.746344Z","steps":["trace[475547179] 'agreement among raft nodes before linearized reading' (duration: 716.276811ms)","trace[475547179] 'range keys from in-memory index tree' (duration: 250.364745ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:45.746372Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"250.453341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-03-17T20:45:45.746414Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:44.779530Z","time spent":"966.865069ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-03-17T20:45:45.746461Z","caller":"traceutil/trace.go:172","msg":"trace[1552159638] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:581; }","duration":"250.557159ms","start":"2026-03-17T20:45:45.495890Z","end":"2026-03-17T20:45:45.746447Z","steps":["trace[1552159638] 'read index received' (duration: 26.019µs)","trace[1552159638] 'applied index is now lower than readState.Index' (duration: 250.5299ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:45.746547Z","caller":"traceutil/trace.go:172","msg":"trace[1896970161] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"991.311165ms","start":"2026-03-17T20:45:44.755222Z","end":"2026-03-17T20:45:45.746533Z","steps":["trace[1896970161] 'process raft request' (duration: 740.62518ms)","trace[1896970161] 'compare' (duration: 250.265748ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:45.746637Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:44.755192Z","time spent":"991.413362ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:45.746700Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"298.378978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:45.746843Z","caller":"traceutil/trace.go:172","msg":"trace[462938431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"298.524734ms","start":"2026-03-17T20:45:45.448301Z","end":"2026-03-17T20:45:45.746826Z","steps":["trace[462938431] 'agreement among raft nodes before linearized reading' (duration: 298.34127ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:45.905449Z","caller":"traceutil/trace.go:172","msg":"trace[2032842903] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:582; }","duration":"158.921353ms","start":"2026-03-17T20:45:45.746495Z","end":"2026-03-17T20:45:45.905417Z","steps":["trace[2032842903] 'read index received' (duration: 158.908823ms)","trace[2032842903] 'applied index is now lower than readState.Index' (duration: 10.52µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:46.182118Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"436.066506ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:46.182214Z","caller":"traceutil/trace.go:172","msg":"trace[2081334439] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:566; }","duration":"436.182213ms","start":"2026-03-17T20:45:45.746009Z","end":"2026-03-17T20:45:46.182192Z","steps":["trace[2081334439] 'agreement among raft nodes before linearized reading' (duration: 159.521844ms)","trace[2081334439] 'range keys from in-memory index tree' (duration: 276.498264ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:46.405989Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619481106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T20:45:46.571398Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"665.760877ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-17T20:45:46.571736Z","caller":"traceutil/trace.go:172","msg":"trace[574272440] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"666.195984ms","start":"2026-03-17T20:45:45.905519Z","end":"2026-03-17T20:45:46.571715Z","steps":["trace[574272440] 'read index received' (duration: 555.298389ms)","trace[574272440] 'applied index is now lower than readState.Index' (duration: 110.895985ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:46.571994Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"766.017702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.174\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-17T20:45:46.572026Z","caller":"traceutil/trace.go:172","msg":"trace[1140536280] range","detail":"{range_begin:/registry/masterleases/199.19.213.174; range_end:; response_count:1; response_revision:566; }","duration":"766.060951ms","start":"2026-03-17T20:45:45.805954Z","end":"2026-03-17T20:45:46.572015Z","steps":["trace[1140536280] 'agreement among raft nodes before linearized reading' (duration: 765.898025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:46.572069Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:45.805930Z","time spent":"766.129638ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.174\" limit:1 "} {"level":"warn","ts":"2026-03-17T20:45:46.572100Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"823.677363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:46.572183Z","caller":"traceutil/trace.go:172","msg":"trace[1897140604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"823.7718ms","start":"2026-03-17T20:45:45.748393Z","end":"2026-03-17T20:45:46.572165Z","steps":["trace[1897140604] 'agreement among raft nodes before linearized reading' (duration: 823.625034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:46.572225Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:45.748376Z","time spent":"823.841458ms","remote":"127.0.0.1:46132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-17T20:45:46.573074Z","caller":"traceutil/trace.go:172","msg":"trace[512595184] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"822.409724ms","start":"2026-03-17T20:45:45.750642Z","end":"2026-03-17T20:45:46.573052Z","steps":["trace[512595184] 'process raft request' (duration: 820.952487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:46.573407Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:45.750621Z","time spent":"822.667587ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:47.375599Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"802.380731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:45:47.375866Z","caller":"traceutil/trace.go:172","msg":"trace[550292280] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.624664181s","start":"2026-03-17T20:45:45.751162Z","end":"2026-03-17T20:45:47.375826Z","steps":["trace[550292280] 'process raft request' (duration: 821.864842ms)","trace[550292280] 'compare' (duration: 802.179257ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:47.375953Z","caller":"traceutil/trace.go:172","msg":"trace[626986668] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"574.65754ms","start":"2026-03-17T20:45:46.801281Z","end":"2026-03-17T20:45:47.375938Z","steps":["trace[626986668] 'process raft request' (duration: 574.565172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:47.375990Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:45.751144Z","time spent":"1.624792817s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":425,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:47.376026Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.053603772s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:47.376081Z","caller":"traceutil/trace.go:172","msg":"trace[1270072902] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:569; }","duration":"1.053718169s","start":"2026-03-17T20:45:46.322354Z","end":"2026-03-17T20:45:47.376072Z","steps":["trace[1270072902] 'agreement among raft nodes before linearized reading' (duration: 1.053561733s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:47.376026Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:46.801257Z","time spent":"574.730657ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T20:45:47.375863Z","caller":"traceutil/trace.go:172","msg":"trace[1753672942] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:583; }","duration":"804.067991ms","start":"2026-03-17T20:45:46.571770Z","end":"2026-03-17T20:45:47.375838Z","steps":["trace[1753672942] 'read index received' (duration: 234.483µs)","trace[1753672942] 'applied index is now lower than readState.Index' (duration: 803.830949ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:47.376014Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:46.573793Z","time spent":"802.214767ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-17T20:45:47.376354Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"794.161508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:47.376505Z","caller":"traceutil/trace.go:172","msg":"trace[134373461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"794.316065ms","start":"2026-03-17T20:45:46.582175Z","end":"2026-03-17T20:45:47.376491Z","steps":["trace[134373461] 'agreement among raft nodes before linearized reading' (duration: 794.132109ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:47.376543Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:46.582160Z","time spent":"794.377282ms","remote":"127.0.0.1:46142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-17T20:45:47.878393Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619481113,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-17T20:45:48.358174Z","caller":"traceutil/trace.go:172","msg":"trace[903217710] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:587; }","duration":"979.852085ms","start":"2026-03-17T20:45:47.378272Z","end":"2026-03-17T20:45:48.358124Z","steps":["trace[903217710] 'read index received' (duration: 979.841295ms)","trace[903217710] 'applied index is now lower than readState.Index' (duration: 9.28µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:49.082284Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619481116,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T20:45:49.582852Z","caller":"etcdserver/v3_server.go:911","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15387565164619481116,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-17T20:45:49.661227Z","caller":"wal/wal.go:845","msg":"slow fdatasync","took":"1.303085749s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-17T20:45:49.730283Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"2.352006796s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T20:45:49.730300Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.407611965s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:49.730350Z","caller":"traceutil/trace.go:172","msg":"trace[362259121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"2.352076924s","start":"2026-03-17T20:45:47.378259Z","end":"2026-03-17T20:45:49.730336Z","steps":["trace[362259121] 'agreement among raft nodes before linearized reading' (duration: 979.97628ms)","trace[362259121] 'range keys from in-memory index tree' (duration: 1.372002206s)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:49.730373Z","caller":"traceutil/trace.go:172","msg":"trace[517443664] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:569; }","duration":"1.407694442s","start":"2026-03-17T20:45:48.322660Z","end":"2026-03-17T20:45:49.730354Z","steps":["trace[517443664] 'range keys from in-memory index tree' (duration: 1.407562626s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:49.730385Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:47.378237Z","time spent":"2.352137263s","remote":"127.0.0.1:46132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-17T20:45:49.730511Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.372264329s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:45:49.730631Z","caller":"traceutil/trace.go:172","msg":"trace[1985131609] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"2.352994579s","start":"2026-03-17T20:45:47.377620Z","end":"2026-03-17T20:45:49.730615Z","steps":["trace[1985131609] 'process raft request' (duration: 980.576904ms)","trace[1985131609] 'compare' (duration: 1.372183521s)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:49.730730Z","caller":"traceutil/trace.go:172","msg":"trace[799589203] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"1.149417925s","start":"2026-03-17T20:45:48.581296Z","end":"2026-03-17T20:45:49.730714Z","steps":["trace[799589203] 'read index received' (duration: 1.080137137s)","trace[799589203] 'applied index is now lower than readState.Index' (duration: 69.278588ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:49.730784Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:47.377600Z","time spent":"2.353090796s","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:49.730918Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"1.149620399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-17T20:45:49.730949Z","caller":"traceutil/trace.go:172","msg":"trace[1031142993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"1.149653698s","start":"2026-03-17T20:45:48.581284Z","end":"2026-03-17T20:45:49.730938Z","steps":["trace[1031142993] 'agreement among raft nodes before linearized reading' (duration: 1.149503412s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:49.730983Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:48.581240Z","time spent":"1.149729706s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"info","ts":"2026-03-17T20:45:49.731257Z","caller":"traceutil/trace.go:172","msg":"trace[1217172869] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.979353383s","start":"2026-03-17T20:45:47.751890Z","end":"2026-03-17T20:45:49.731243Z","steps":["trace[1217172869] 'process raft request' (duration: 1.97871156s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:49.731307Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:47.751863Z","time spent":"1.979423451s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:49.799385Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"412.566837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:49.799448Z","caller":"traceutil/trace.go:172","msg":"trace[1306882093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"412.637175ms","start":"2026-03-17T20:45:49.386797Z","end":"2026-03-17T20:45:49.799434Z","steps":["trace[1306882093] 'agreement among raft nodes before linearized reading' (duration: 412.521188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:49.799478Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:49.386785Z","time spent":"412.687934ms","remote":"127.0.0.1:46142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-17T20:45:49.799454Z","caller":"traceutil/trace.go:172","msg":"trace[2102871450] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"417.435872ms","start":"2026-03-17T20:45:49.381998Z","end":"2026-03-17T20:45:49.799434Z","steps":["trace[2102871450] 'process raft request' (duration: 417.330185ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:49.799522Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"417.001624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-controller.189dbbcee0227094\" limit:1 ","response":"range_response_count:1 size:784"} {"level":"warn","ts":"2026-03-17T20:45:49.799585Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"418.391978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-17T20:45:49.799671Z","caller":"traceutil/trace.go:172","msg":"trace[1196281424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:571; }","duration":"418.509585ms","start":"2026-03-17T20:45:49.381144Z","end":"2026-03-17T20:45:49.799654Z","steps":["trace[1196281424] 'agreement among raft nodes before linearized reading' (duration: 418.232571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:49.799727Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:49.381053Z","time spent":"418.657752ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-03-17T20:45:49.799613Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:49.381981Z","time spent":"417.533991ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T20:45:49.799656Z","caller":"traceutil/trace.go:172","msg":"trace[2070473751] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-controller.189dbbcee0227094; range_end:; response_count:1; response_revision:571; }","duration":"417.157219ms","start":"2026-03-17T20:45:49.382480Z","end":"2026-03-17T20:45:49.799638Z","steps":["trace[2070473751] 'agreement among raft nodes before linearized reading' (duration: 416.845597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:49.800122Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:49.382469Z","time spent":"417.631607ms","remote":"127.0.0.1:46272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":807,"request content":"key:\"/registry/events/kube-system/kube-apiserver-controller.189dbbcee0227094\" limit:1 "} {"level":"warn","ts":"2026-03-17T20:45:49.872214Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"138.788079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" limit:1 ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-17T20:45:49.872283Z","caller":"traceutil/trace.go:172","msg":"trace[1986837223] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:574; }","duration":"138.881557ms","start":"2026-03-17T20:45:49.733385Z","end":"2026-03-17T20:45:49.872267Z","steps":["trace[1986837223] 'agreement among raft nodes before linearized reading' (duration: 138.572075ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:49.871934Z","caller":"traceutil/trace.go:172","msg":"trace[1632242243] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"137.20489ms","start":"2026-03-17T20:45:49.734692Z","end":"2026-03-17T20:45:49.871897Z","steps":["trace[1632242243] 'process raft request' (duration: 136.255125ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:49.937858Z","caller":"traceutil/trace.go:172","msg":"trace[343291880] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"109.534288ms","start":"2026-03-17T20:45:49.828310Z","end":"2026-03-17T20:45:49.937844Z","steps":["trace[343291880] 'process raft request' (duration: 109.491849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:49.937985Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"137.140802ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T20:45:49.938028Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"137.318997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:49.938131Z","caller":"traceutil/trace.go:172","msg":"trace[560136757] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"133.095236ms","start":"2026-03-17T20:45:49.805025Z","end":"2026-03-17T20:45:49.938121Z","steps":["trace[560136757] 'process raft request' (duration: 132.718235ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:49.938155Z","caller":"traceutil/trace.go:172","msg":"trace[931446756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"137.448454ms","start":"2026-03-17T20:45:49.800668Z","end":"2026-03-17T20:45:49.938117Z","steps":["trace[931446756] 'agreement among raft nodes before linearized reading' (duration: 137.240549ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:49.938073Z","caller":"traceutil/trace.go:172","msg":"trace[383605724] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:577; }","duration":"137.238659ms","start":"2026-03-17T20:45:49.800816Z","end":"2026-03-17T20:45:49.938055Z","steps":["trace[383605724] 'agreement among raft nodes before linearized reading' (duration: 137.031235ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:49.938545Z","caller":"traceutil/trace.go:172","msg":"trace[218693790] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"135.021546ms","start":"2026-03-17T20:45:49.803200Z","end":"2026-03-17T20:45:49.938222Z","steps":["trace[218693790] 'process raft request' (duration: 134.449071ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:51.027903Z","caller":"traceutil/trace.go:172","msg":"trace[2083461836] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"440.045654ms","start":"2026-03-17T20:45:50.587835Z","end":"2026-03-17T20:45:51.027880Z","steps":["trace[2083461836] 'process raft request' (duration: 439.906887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:51.028036Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:50.587820Z","time spent":"440.157482ms","remote":"127.0.0.1:46272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":747,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T20:45:51.089036Z","caller":"traceutil/trace.go:172","msg":"trace[752399173] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"479.682209ms","start":"2026-03-17T20:45:50.609332Z","end":"2026-03-17T20:45:51.089014Z","steps":["trace[752399173] 'process raft request' (duration: 479.506154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:51.089200Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:50.609312Z","time spent":"479.812737ms","remote":"127.0.0.1:46448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18605,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T20:45:51.836956Z","caller":"traceutil/trace.go:172","msg":"trace[495137152] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"158.67566ms","start":"2026-03-17T20:45:51.678260Z","end":"2026-03-17T20:45:51.836935Z","steps":["trace[495137152] 'process raft request' (duration: 158.513184ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:51.901812Z","caller":"traceutil/trace.go:172","msg":"trace[1340431040] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"165.841627ms","start":"2026-03-17T20:45:51.735942Z","end":"2026-03-17T20:45:51.901784Z","steps":["trace[1340431040] 'process raft request' (duration: 163.902941ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:52.841806Z","caller":"traceutil/trace.go:172","msg":"trace[1315877214] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"227.600355ms","start":"2026-03-17T20:45:52.614181Z","end":"2026-03-17T20:45:52.841781Z","steps":["trace[1315877214] 'process raft request' (duration: 221.722661ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:54.076536Z","caller":"traceutil/trace.go:172","msg":"trace[673948073] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:607; }","duration":"150.272922ms","start":"2026-03-17T20:45:53.926240Z","end":"2026-03-17T20:45:54.076513Z","steps":["trace[673948073] 'read index received' (duration: 150.265172ms)","trace[673948073] 'applied index is now lower than readState.Index' (duration: 6.51µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:54.358702Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"259.982826ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:54.358803Z","caller":"traceutil/trace.go:172","msg":"trace[1347261595] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:588; }","duration":"260.097754ms","start":"2026-03-17T20:45:54.098681Z","end":"2026-03-17T20:45:54.358779Z","steps":["trace[1347261595] 'range keys from in-memory index tree' (duration: 259.925306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:54.359339Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"433.096487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:54.359398Z","caller":"traceutil/trace.go:172","msg":"trace[553736941] range","detail":"{range_begin:/registry/persistentvolumes; range_end:; response_count:0; response_revision:588; }","duration":"433.154706ms","start":"2026-03-17T20:45:53.926228Z","end":"2026-03-17T20:45:54.359383Z","steps":["trace[553736941] 'agreement among raft nodes before linearized reading' (duration: 150.38592ms)","trace[553736941] 'range keys from in-memory index tree' (duration: 282.688888ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:54.359440Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:53.926214Z","time spent":"433.214555ms","remote":"127.0.0.1:46386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":31,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes\" limit:1 "} {"level":"warn","ts":"2026-03-17T20:45:54.359730Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"283.056581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:45:54.359876Z","caller":"traceutil/trace.go:172","msg":"trace[1403397714] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"283.213158ms","start":"2026-03-17T20:45:54.076645Z","end":"2026-03-17T20:45:54.359858Z","steps":["trace[1403397714] 'read index received' (duration: 282.743227ms)","trace[1403397714] 'applied index is now lower than readState.Index' (duration: 468.531µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:54.359957Z","caller":"traceutil/trace.go:172","msg":"trace[794608398] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"451.083897ms","start":"2026-03-17T20:45:53.908867Z","end":"2026-03-17T20:45:54.359951Z","steps":["trace[794608398] 'process raft request' (duration: 450.946289ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:54.359969Z","caller":"traceutil/trace.go:172","msg":"trace[2092248309] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"497.184799ms","start":"2026-03-17T20:45:53.862769Z","end":"2026-03-17T20:45:54.359954Z","steps":["trace[2092248309] 'process raft request' (duration: 213.873772ms)","trace[2092248309] 'compare' (duration: 282.763667ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:54.359992Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:53.908855Z","time spent":"451.116597ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:54.360035Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:53.862745Z","time spent":"497.253897ms","remote":"127.0.0.1:46272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T20:45:54.360129Z","caller":"traceutil/trace.go:172","msg":"trace[392851650] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"453.297002ms","start":"2026-03-17T20:45:53.906827Z","end":"2026-03-17T20:45:54.360124Z","steps":["trace[392851650] 'process raft request' (duration: 452.946928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:54.360154Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:53.906803Z","time spent":"453.337652ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:45:54.360322Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"276.892687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:54.360340Z","caller":"traceutil/trace.go:172","msg":"trace[1370128247] range","detail":"{range_begin:/registry/services/specs; range_end:; response_count:0; response_revision:591; }","duration":"276.910867ms","start":"2026-03-17T20:45:54.083425Z","end":"2026-03-17T20:45:54.360336Z","steps":["trace[1370128247] 'agreement among raft nodes before linearized reading' (duration: 276.881547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:54.360355Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"366.472871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-17T20:45:54.360403Z","caller":"traceutil/trace.go:172","msg":"trace[485512955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"366.52184ms","start":"2026-03-17T20:45:53.993868Z","end":"2026-03-17T20:45:54.360389Z","steps":["trace[485512955] 'agreement among raft nodes before linearized reading' (duration: 366.371673ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:54.360448Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"376.163016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-17T20:45:54.360442Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:53.993855Z","time spent":"366.576629ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-03-17T20:45:54.360463Z","caller":"traceutil/trace.go:172","msg":"trace[1378277753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"376.179886ms","start":"2026-03-17T20:45:53.984279Z","end":"2026-03-17T20:45:54.360459Z","steps":["trace[1378277753] 'agreement among raft nodes before linearized reading' (duration: 376.134126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:54.360474Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:53.984266Z","time spent":"376.205155ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-03-17T20:45:54.360513Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"378.602977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:54.360528Z","caller":"traceutil/trace.go:172","msg":"trace[630756111] range","detail":"{range_begin:/registry/leases; range_end:; response_count:0; response_revision:591; }","duration":"378.617516ms","start":"2026-03-17T20:45:53.981907Z","end":"2026-03-17T20:45:54.360525Z","steps":["trace[630756111] 'agreement among raft nodes before linearized reading' (duration: 378.596517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:54.360538Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:45:53.981892Z","time spent":"378.643986ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":20,"response count":0,"response size":28,"request content":"key:\"/registry/leases\" limit:1 "} {"level":"warn","ts":"2026-03-17T20:45:54.360703Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"227.863271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:54.360730Z","caller":"traceutil/trace.go:172","msg":"trace[532365109] range","detail":"{range_begin:/registry/rolebindings; range_end:; response_count:0; response_revision:591; }","duration":"227.89084ms","start":"2026-03-17T20:45:54.132833Z","end":"2026-03-17T20:45:54.360724Z","steps":["trace[532365109] 'agreement among raft nodes before linearized reading' (duration: 227.847681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:54.360824Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"229.102417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingadmissionpolicies\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:54.360884Z","caller":"traceutil/trace.go:172","msg":"trace[1450402918] range","detail":"{range_begin:/registry/validatingadmissionpolicies; range_end:; response_count:0; response_revision:591; }","duration":"229.165226ms","start":"2026-03-17T20:45:54.131704Z","end":"2026-03-17T20:45:54.360869Z","steps":["trace[1450402918] 'agreement among raft nodes before linearized reading' (duration: 229.080317ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:57.954044Z","caller":"traceutil/trace.go:172","msg":"trace[1498882828] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"202.380011ms","start":"2026-03-17T20:45:57.751639Z","end":"2026-03-17T20:45:57.954019Z","steps":["trace[1498882828] 'process raft request' (duration: 202.217232ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:57.954863Z","caller":"traceutil/trace.go:172","msg":"trace[1393735155] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:639; }","duration":"145.853459ms","start":"2026-03-17T20:45:57.808987Z","end":"2026-03-17T20:45:57.954841Z","steps":["trace[1393735155] 'read index received' (duration: 145.843679ms)","trace[1393735155] 'applied index is now lower than readState.Index' (duration: 8.37µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:57.955104Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"102.385212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:57.955205Z","caller":"traceutil/trace.go:172","msg":"trace[1753404669] range","detail":"{range_begin:/registry/podtemplates; range_end:; response_count:0; response_revision:618; }","duration":"102.493901ms","start":"2026-03-17T20:45:57.852686Z","end":"2026-03-17T20:45:57.955180Z","steps":["trace[1753404669] 'agreement among raft nodes before linearized reading' (duration: 102.344703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:45:57.955529Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"146.530579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" keys_only:true ","response":"range_response_count:80 size:6749"} {"level":"info","ts":"2026-03-17T20:45:57.955625Z","caller":"traceutil/trace.go:172","msg":"trace[2062277190] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:80; response_revision:618; }","duration":"146.638516ms","start":"2026-03-17T20:45:57.808972Z","end":"2026-03-17T20:45:57.955611Z","steps":["trace[2062277190] 'agreement among raft nodes before linearized reading' (duration: 146.001106ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:59.552377Z","caller":"traceutil/trace.go:172","msg":"trace[1144778443] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:667; }","duration":"100.266488ms","start":"2026-03-17T20:45:59.452049Z","end":"2026-03-17T20:45:59.552316Z","steps":["trace[1144778443] 'read index received' (duration: 100.245339ms)","trace[1144778443] 'applied index is now lower than readState.Index' (duration: 18.109µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:59.654295Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"202.233915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:59.654374Z","caller":"traceutil/trace.go:172","msg":"trace[1531878394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"202.320135ms","start":"2026-03-17T20:45:59.452035Z","end":"2026-03-17T20:45:59.654355Z","steps":["trace[1531878394] 'agreement among raft nodes before linearized reading' (duration: 100.398087ms)","trace[1531878394] 'range keys from in-memory index tree' (duration: 101.790829ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:59.655058Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"102.5486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:45:59.655221Z","caller":"traceutil/trace.go:172","msg":"trace[999167736] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"285.423488ms","start":"2026-03-17T20:45:59.369773Z","end":"2026-03-17T20:45:59.655197Z","steps":["trace[999167736] 'process raft request' (duration: 182.613011ms)","trace[999167736] 'compare' (duration: 102.337553ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:45:59.663773Z","caller":"traceutil/trace.go:172","msg":"trace[49867591] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:668; }","duration":"111.25801ms","start":"2026-03-17T20:45:59.552502Z","end":"2026-03-17T20:45:59.663760Z","steps":["trace[49867591] 'read index received' (duration: 111.25308ms)","trace[49867591] 'applied index is now lower than readState.Index' (duration: 4.17µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:45:59.663857Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"180.902234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:45:59.663872Z","caller":"traceutil/trace.go:172","msg":"trace[663081772] range","detail":"{range_begin:/registry/prioritylevelconfigurations; range_end:; response_count:0; response_revision:647; }","duration":"180.927634ms","start":"2026-03-17T20:45:59.482939Z","end":"2026-03-17T20:45:59.663867Z","steps":["trace[663081772] 'agreement among raft nodes before linearized reading' (duration: 180.885544ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:59.663893Z","caller":"traceutil/trace.go:172","msg":"trace[951128355] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"137.711876ms","start":"2026-03-17T20:45:59.526168Z","end":"2026-03-17T20:45:59.663880Z","steps":["trace[951128355] 'process raft request' (duration: 137.627148ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:45:59.855407Z","caller":"traceutil/trace.go:172","msg":"trace[1619861289] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"118.756015ms","start":"2026-03-17T20:45:59.736630Z","end":"2026-03-17T20:45:59.855386Z","steps":["trace[1619861289] 'process raft request' (duration: 118.583267ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:00.053227Z","caller":"traceutil/trace.go:172","msg":"trace[326999989] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"125.833558ms","start":"2026-03-17T20:45:59.927371Z","end":"2026-03-17T20:46:00.053205Z","steps":["trace[326999989] 'process raft request' (duration: 125.65608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:00.100739Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:34156","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:46:00.401099Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"136.132869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:46:00.401510Z","caller":"traceutil/trace.go:172","msg":"trace[1281067370] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"277.645366ms","start":"2026-03-17T20:46:00.123829Z","end":"2026-03-17T20:46:00.401474Z","steps":["trace[1281067370] 'process raft request' (duration: 140.974974ms)","trace[1281067370] 'compare' (duration: 135.669974ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:46:00.401981Z","caller":"traceutil/trace.go:172","msg":"trace[1825613644] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"275.846547ms","start":"2026-03-17T20:46:00.126122Z","end":"2026-03-17T20:46:00.401969Z","steps":["trace[1825613644] 'process raft request' (duration: 275.154735ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:00.402232Z","caller":"traceutil/trace.go:172","msg":"trace[1229478011] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:680; }","duration":"124.55025ms","start":"2026-03-17T20:46:00.277655Z","end":"2026-03-17T20:46:00.402205Z","steps":["trace[1229478011] 'read index received' (duration: 36.729µs)","trace[1229478011] 'applied index is now lower than readState.Index' (duration: 124.511091ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:00.402477Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"124.774208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourceclaimtemplates\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:00.402519Z","caller":"traceutil/trace.go:172","msg":"trace[1511760070] range","detail":"{range_begin:/registry/resourceclaimtemplates; range_end:; response_count:0; response_revision:661; }","duration":"124.858787ms","start":"2026-03-17T20:46:00.277649Z","end":"2026-03-17T20:46:00.402507Z","steps":["trace[1511760070] 'agreement among raft nodes before linearized reading' (duration: 124.694709ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:00.571071Z","caller":"traceutil/trace.go:172","msg":"trace[444301201] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:682; }","duration":"168.742387ms","start":"2026-03-17T20:46:00.402309Z","end":"2026-03-17T20:46:00.571051Z","steps":["trace[444301201] 'read index received' (duration: 168.735817ms)","trace[444301201] 'applied index is now lower than readState.Index' (duration: 5.67µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:00.689999Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"367.055637ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:00.690107Z","caller":"traceutil/trace.go:172","msg":"trace[1129365965] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:661; }","duration":"367.175486ms","start":"2026-03-17T20:46:00.322906Z","end":"2026-03-17T20:46:00.690081Z","steps":["trace[1129365965] 'agreement among raft nodes before linearized reading' (duration: 248.270421ms)","trace[1129365965] 'range keys from in-memory index tree' (duration: 118.730817ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:00.690203Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"298.231741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-17T20:46:00.690261Z","caller":"traceutil/trace.go:172","msg":"trace[169799564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"298.31471ms","start":"2026-03-17T20:46:00.391934Z","end":"2026-03-17T20:46:00.690248Z","steps":["trace[169799564] 'agreement among raft nodes before linearized reading' (duration: 179.220137ms)","trace[169799564] 'range keys from in-memory index tree' (duration: 118.863095ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:00.690250Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"298.33985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-17T20:46:00.690314Z","caller":"traceutil/trace.go:172","msg":"trace[487006778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"298.405779ms","start":"2026-03-17T20:46:00.391890Z","end":"2026-03-17T20:46:00.690296Z","steps":["trace[487006778] 'agreement among raft nodes before linearized reading' (duration: 179.279487ms)","trace[487006778] 'range keys from in-memory index tree' (duration: 118.931014ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:00.690808Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"119.31472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:46:00.692004Z","caller":"traceutil/trace.go:172","msg":"trace[951296918] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"366.199687ms","start":"2026-03-17T20:46:00.325783Z","end":"2026-03-17T20:46:00.691983Z","steps":["trace[951296918] 'process raft request' (duration: 245.321385ms)","trace[951296918] 'compare' (duration: 118.917704ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:46:00.692126Z","caller":"traceutil/trace.go:172","msg":"trace[1395187285] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"120.956121ms","start":"2026-03-17T20:46:00.571141Z","end":"2026-03-17T20:46:00.692097Z","steps":["trace[1395187285] 'read index received' (duration: 120.494607ms)","trace[1395187285] 'applied index is now lower than readState.Index' (duration: 459.284µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:00.692221Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"247.617928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:00.692461Z","caller":"traceutil/trace.go:172","msg":"trace[1777532541] range","detail":"{range_begin:/registry/csidrivers; range_end:; response_count:0; response_revision:662; }","duration":"247.860345ms","start":"2026-03-17T20:46:00.444587Z","end":"2026-03-17T20:46:00.692448Z","steps":["trace[1777532541] 'agreement among raft nodes before linearized reading' (duration: 247.585698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:00.692467Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"169.527928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:00.692491Z","caller":"traceutil/trace.go:172","msg":"trace[874891606] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:662; }","duration":"169.556207ms","start":"2026-03-17T20:46:00.522929Z","end":"2026-03-17T20:46:00.692485Z","steps":["trace[874891606] 'agreement among raft nodes before linearized reading' (duration: 169.502758ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:00.693061Z","caller":"traceutil/trace.go:172","msg":"trace[548489482] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"312.654157ms","start":"2026-03-17T20:46:00.380385Z","end":"2026-03-17T20:46:00.693040Z","steps":["trace[548489482] 'process raft request' (duration: 311.867616ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:00.693515Z","caller":"traceutil/trace.go:172","msg":"trace[1576644946] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"309.506803ms","start":"2026-03-17T20:46:00.384001Z","end":"2026-03-17T20:46:00.693508Z","steps":["trace[1576644946] 'process raft request' (duration: 308.982789ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:00.693605Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:46:00.383972Z","time spent":"309.567652ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:46:00.693524Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:46:00.380338Z","time spent":"312.792225ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T20:46:00.693732Z","caller":"traceutil/trace.go:172","msg":"trace[1685986502] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"286.038042ms","start":"2026-03-17T20:46:00.407689Z","end":"2026-03-17T20:46:00.693727Z","steps":["trace[1685986502] 'process raft request' (duration: 285.763174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:00.695964Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:46:00.325728Z","time spent":"366.318266ms","remote":"127.0.0.1:46248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T20:46:01.247960Z","caller":"traceutil/trace.go:172","msg":"trace[213283545] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:694; }","duration":"124.16758ms","start":"2026-03-17T20:46:01.123752Z","end":"2026-03-17T20:46:01.247920Z","steps":["trace[213283545] 'read index received' (duration: 124.15495ms)","trace[213283545] 'applied index is now lower than readState.Index' (duration: 10.64µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:01.248275Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"124.484218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" limit:1 ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-03-17T20:46:01.248367Z","caller":"traceutil/trace.go:172","msg":"trace[94007797] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:673; }","duration":"124.604616ms","start":"2026-03-17T20:46:01.123743Z","end":"2026-03-17T20:46:01.248347Z","steps":["trace[94007797] 'agreement among raft nodes before linearized reading' (duration: 124.274619ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:01.248938Z","caller":"traceutil/trace.go:172","msg":"trace[1959156804] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"219.873052ms","start":"2026-03-17T20:46:01.029044Z","end":"2026-03-17T20:46:01.248917Z","steps":["trace[1959156804] 'process raft request' (duration: 219.19452ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:01.899951Z","caller":"traceutil/trace.go:172","msg":"trace[692780752] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:697; }","duration":"176.242279ms","start":"2026-03-17T20:46:01.723681Z","end":"2026-03-17T20:46:01.899924Z","steps":["trace[692780752] 'read index received' (duration: 176.232109ms)","trace[692780752] 'applied index is now lower than readState.Index' (duration: 8.7µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:01.910448Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"186.753192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" limit:1 ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-03-17T20:46:01.910530Z","caller":"traceutil/trace.go:172","msg":"trace[253672070] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:676; }","duration":"186.84654ms","start":"2026-03-17T20:46:01.723663Z","end":"2026-03-17T20:46:01.910509Z","steps":["trace[253672070] 'agreement among raft nodes before linearized reading' (duration: 176.372226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:01.910723Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"149.182065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:01.910750Z","caller":"traceutil/trace.go:172","msg":"trace[159380762] range","detail":"{range_begin:/registry/roles; range_end:; response_count:0; response_revision:677; }","duration":"149.207484ms","start":"2026-03-17T20:46:01.761534Z","end":"2026-03-17T20:46:01.910741Z","steps":["trace[159380762] 'agreement among raft nodes before linearized reading' (duration: 149.164605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:01.910455Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"171.455317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:01.910797Z","caller":"traceutil/trace.go:172","msg":"trace[447571185] range","detail":"{range_begin:/registry/jobs; range_end:; response_count:0; response_revision:677; }","duration":"171.798814ms","start":"2026-03-17T20:46:01.738985Z","end":"2026-03-17T20:46:01.910784Z","steps":["trace[447571185] 'agreement among raft nodes before linearized reading' (duration: 171.431548ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:01.910932Z","caller":"traceutil/trace.go:172","msg":"trace[2035167222] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"234.73501ms","start":"2026-03-17T20:46:01.676173Z","end":"2026-03-17T20:46:01.910908Z","steps":["trace[2035167222] 'process raft request' (duration: 223.801042ms)","trace[2035167222] 'compare' (duration: 10.310905ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:02.197881Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"275.068074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" limit:1 ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-03-17T20:46:02.198002Z","caller":"traceutil/trace.go:172","msg":"trace[1742404282] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:677; }","duration":"275.212384ms","start":"2026-03-17T20:46:01.922765Z","end":"2026-03-17T20:46:02.197978Z","steps":["trace[1742404282] 'agreement among raft nodes before linearized reading' (duration: 93.522101ms)","trace[1742404282] 'range keys from in-memory index tree' (duration: 181.380154ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:02.199172Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"181.575224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:46:02.199881Z","caller":"traceutil/trace.go:172","msg":"trace[831736304] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"280.394352ms","start":"2026-03-17T20:46:01.919471Z","end":"2026-03-17T20:46:02.199865Z","steps":["trace[831736304] 'process raft request' (duration: 96.831397ms)","trace[831736304] 'compare' (duration: 181.370435ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:46:02.597284Z","caller":"traceutil/trace.go:172","msg":"trace[1433436693] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:699; }","duration":"191.099557ms","start":"2026-03-17T20:46:02.406149Z","end":"2026-03-17T20:46:02.597248Z","steps":["trace[1433436693] 'read index received' (duration: 191.089567ms)","trace[1433436693] 'applied index is now lower than readState.Index' (duration: 8.31µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:02.597487Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"191.319994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/servicecidrs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:02.597629Z","caller":"traceutil/trace.go:172","msg":"trace[1584491249] range","detail":"{range_begin:/registry/servicecidrs; range_end:; response_count:0; response_revision:678; }","duration":"191.475853ms","start":"2026-03-17T20:46:02.406129Z","end":"2026-03-17T20:46:02.597605Z","steps":["trace[1584491249] 'agreement among raft nodes before linearized reading' (duration: 191.248205ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:02.597798Z","caller":"traceutil/trace.go:172","msg":"trace[811301850] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"233.401123ms","start":"2026-03-17T20:46:02.364383Z","end":"2026-03-17T20:46:02.597784Z","steps":["trace[811301850] 'process raft request' (duration: 232.975767ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:02.827249Z","caller":"traceutil/trace.go:172","msg":"trace[523918942] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:700; }","duration":"118.049589ms","start":"2026-03-17T20:46:02.709164Z","end":"2026-03-17T20:46:02.827214Z","steps":["trace[523918942] 'read index received' (duration: 118.039119ms)","trace[523918942] 'applied index is now lower than readState.Index' (duration: 9.01µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:02.881633Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"172.461387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-17T20:46:02.881721Z","caller":"traceutil/trace.go:172","msg":"trace[1171876314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:679; }","duration":"172.560226ms","start":"2026-03-17T20:46:02.709139Z","end":"2026-03-17T20:46:02.881700Z","steps":["trace[1171876314] 'agreement among raft nodes before linearized reading' (duration: 118.274217ms)","trace[1171876314] 'range keys from in-memory index tree' (duration: 54.048341ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:46:02.891322Z","caller":"traceutil/trace.go:172","msg":"trace[806799610] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"335.378418ms","start":"2026-03-17T20:46:02.555915Z","end":"2026-03-17T20:46:02.891293Z","steps":["trace[806799610] 'process raft request' (duration: 334.972792ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:02.891446Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:46:02.555883Z","time spent":"335.511057ms","remote":"127.0.0.1:46248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T20:46:02.891553Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"167.480861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" limit:1 ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-03-17T20:46:02.891628Z","caller":"traceutil/trace.go:172","msg":"trace[547313101] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:680; }","duration":"167.57441ms","start":"2026-03-17T20:46:02.724042Z","end":"2026-03-17T20:46:02.891617Z","steps":["trace[547313101] 'agreement among raft nodes before linearized reading' (duration: 167.346812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:02.891622Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"123.003625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-17T20:46:02.891694Z","caller":"traceutil/trace.go:172","msg":"trace[1816255505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"123.135314ms","start":"2026-03-17T20:46:02.768543Z","end":"2026-03-17T20:46:02.891678Z","steps":["trace[1816255505] 'agreement among raft nodes before linearized reading' (duration: 122.866296ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:02.893400Z","caller":"traceutil/trace.go:172","msg":"trace[1245964587] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"193.067699ms","start":"2026-03-17T20:46:02.700302Z","end":"2026-03-17T20:46:02.893370Z","steps":["trace[1245964587] 'process raft request' (duration: 191.988428ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:02.893385Z","caller":"traceutil/trace.go:172","msg":"trace[316043653] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"193.313747ms","start":"2026-03-17T20:46:02.700019Z","end":"2026-03-17T20:46:02.893332Z","steps":["trace[316043653] 'process raft request' (duration: 192.220496ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:02.893449Z","caller":"traceutil/trace.go:172","msg":"trace[154373826] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"197.36733ms","start":"2026-03-17T20:46:02.696057Z","end":"2026-03-17T20:46:02.893425Z","steps":["trace[154373826] 'process raft request' (duration: 195.496767ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:03.072048Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:34174","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:46:03.085650Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:34200","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:46:03.106832Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:34234","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:46:03.117714Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:34236","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:46:03.130820Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:34260","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:46:03.145368Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:34272","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:46:03.162460Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:34290","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:46:03.181682Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:34312","server-name":"","error":"EOF"} {"level":"info","ts":"2026-03-17T20:46:03.241311Z","caller":"traceutil/trace.go:172","msg":"trace[154239994] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:708; }","duration":"157.154124ms","start":"2026-03-17T20:46:03.084112Z","end":"2026-03-17T20:46:03.241266Z","steps":["trace[154239994] 'read index received' (duration: 157.141504ms)","trace[154239994] 'applied index is now lower than readState.Index' (duration: 10.59µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:03.242899Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"158.76401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:03.242952Z","caller":"traceutil/trace.go:172","msg":"trace[1614206692] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:686; }","duration":"158.83652ms","start":"2026-03-17T20:46:03.084103Z","end":"2026-03-17T20:46:03.242939Z","steps":["trace[1614206692] 'agreement among raft nodes before linearized reading' (duration: 157.309111ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:03.243736Z","caller":"traceutil/trace.go:172","msg":"trace[1300767244] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"237.299987ms","start":"2026-03-17T20:46:03.006403Z","end":"2026-03-17T20:46:03.243703Z","steps":["trace[1300767244] 'process raft request' (duration: 235.036925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:03.268243Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"184.03532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:03.268362Z","caller":"traceutil/trace.go:172","msg":"trace[1356066812] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs; range_end:; response_count:0; response_revision:687; }","duration":"184.183299ms","start":"2026-03-17T20:46:03.084153Z","end":"2026-03-17T20:46:03.268336Z","steps":["trace[1356066812] 'agreement among raft nodes before linearized reading' (duration: 183.938671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:03.268587Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"142.041923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:03.268633Z","caller":"traceutil/trace.go:172","msg":"trace[1237372662] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools; range_end:; response_count:0; response_revision:688; }","duration":"142.128843ms","start":"2026-03-17T20:46:03.126496Z","end":"2026-03-17T20:46:03.268625Z","steps":["trace[1237372662] 'agreement among raft nodes before linearized reading' (duration: 141.970414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:03.268760Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"142.326181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:03.268799Z","caller":"traceutil/trace.go:172","msg":"trace[830931052] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:688; }","duration":"142.36396ms","start":"2026-03-17T20:46:03.126423Z","end":"2026-03-17T20:46:03.268787Z","steps":["trace[830931052] 'agreement among raft nodes before linearized reading' (duration: 142.303841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:03.269048Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"145.420006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" limit:1 ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-03-17T20:46:03.269083Z","caller":"traceutil/trace.go:172","msg":"trace[1192597063] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:688; }","duration":"145.448726ms","start":"2026-03-17T20:46:03.123619Z","end":"2026-03-17T20:46:03.269068Z","steps":["trace[1192597063] 'agreement among raft nodes before linearized reading' (duration: 145.300597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:03.269249Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"155.208978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:03.269459Z","caller":"traceutil/trace.go:172","msg":"trace[2098063823] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:688; }","duration":"155.264887ms","start":"2026-03-17T20:46:03.114005Z","end":"2026-03-17T20:46:03.269270Z","steps":["trace[2098063823] 'agreement among raft nodes before linearized reading' (duration: 155.191548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:03.269279Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"111.939302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:03.269490Z","caller":"traceutil/trace.go:172","msg":"trace[628871800] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:688; }","duration":"112.151811ms","start":"2026-03-17T20:46:03.157330Z","end":"2026-03-17T20:46:03.269482Z","steps":["trace[628871800] 'agreement among raft nodes before linearized reading' (duration: 111.922572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:03.269548Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"170.556747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T20:46:03.269634Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"112.393388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:03.269656Z","caller":"traceutil/trace.go:172","msg":"trace[955608679] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads; range_end:; response_count:0; response_revision:688; }","duration":"112.415538ms","start":"2026-03-17T20:46:03.157234Z","end":"2026-03-17T20:46:03.269649Z","steps":["trace[955608679] 'agreement among raft nodes before linearized reading' (duration: 112.380278ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:03.269718Z","caller":"traceutil/trace.go:172","msg":"trace[874885520] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes; range_end:; response_count:0; response_revision:688; }","duration":"170.664116ms","start":"2026-03-17T20:46:03.098969Z","end":"2026-03-17T20:46:03.269633Z","steps":["trace[874885520] 'agreement among raft nodes before linearized reading' (duration: 170.2295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:03.269763Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"129.383503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:03.268261Z","caller":"traceutil/trace.go:172","msg":"trace[610827917] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"177.962659ms","start":"2026-03-17T20:46:03.090278Z","end":"2026-03-17T20:46:03.268241Z","steps":["trace[610827917] 'process raft request' (duration: 177.81701ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:03.269788Z","caller":"traceutil/trace.go:172","msg":"trace[1782412463] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups; range_end:; response_count:0; response_revision:688; }","duration":"129.408293ms","start":"2026-03-17T20:46:03.140373Z","end":"2026-03-17T20:46:03.269782Z","steps":["trace[1782412463] 'agreement among raft nodes before linearized reading' (duration: 129.370833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:03.269903Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"155.913382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:03.269929Z","caller":"traceutil/trace.go:172","msg":"trace[711294038] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities; range_end:; response_count:0; response_revision:688; }","duration":"155.939612ms","start":"2026-03-17T20:46:03.113982Z","end":"2026-03-17T20:46:03.269921Z","steps":["trace[711294038] 'agreement among raft nodes before linearized reading' (duration: 155.895072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:03.270005Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"167.514791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:03.270025Z","caller":"traceutil/trace.go:172","msg":"trace[1333562156] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints; range_end:; response_count:0; response_revision:688; }","duration":"167.534951ms","start":"2026-03-17T20:46:03.102484Z","end":"2026-03-17T20:46:03.270019Z","steps":["trace[1333562156] 'agreement among raft nodes before linearized reading' (duration: 167.501001ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:03.270093Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"169.593814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T20:46:03.270109Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"167.64848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:03.270130Z","caller":"traceutil/trace.go:172","msg":"trace[1150049278] range","detail":"{range_begin:/registry/poddisruptionbudgets; range_end:; response_count:0; response_revision:688; }","duration":"169.630903ms","start":"2026-03-17T20:46:03.100488Z","end":"2026-03-17T20:46:03.270119Z","steps":["trace[1150049278] 'agreement among raft nodes before linearized reading' (duration: 169.575654ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:03.270139Z","caller":"traceutil/trace.go:172","msg":"trace[1368877466] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:688; }","duration":"167.67784ms","start":"2026-03-17T20:46:03.102453Z","end":"2026-03-17T20:46:03.270131Z","steps":["trace[1368877466] 'agreement among raft nodes before linearized reading' (duration: 167.633021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:03.269920Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"129.591262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:03.270214Z","caller":"traceutil/trace.go:172","msg":"trace[774704623] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:688; }","duration":"129.88469ms","start":"2026-03-17T20:46:03.140315Z","end":"2026-03-17T20:46:03.270200Z","steps":["trace[774704623] 'agreement among raft nodes before linearized reading' (duration: 129.557832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:03.440044Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"115.99735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" limit:1 ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-03-17T20:46:03.440138Z","caller":"traceutil/trace.go:172","msg":"trace[764689936] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:688; }","duration":"116.106969ms","start":"2026-03-17T20:46:03.324009Z","end":"2026-03-17T20:46:03.440115Z","steps":["trace[764689936] 'agreement among raft nodes before linearized reading' (duration: 40.062972ms)","trace[764689936] 'range keys from in-memory index tree' (duration: 75.829198ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:46:03.440716Z","caller":"traceutil/trace.go:172","msg":"trace[584872564] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"117.099801ms","start":"2026-03-17T20:46:03.323592Z","end":"2026-03-17T20:46:03.440692Z","steps":["trace[584872564] 'process raft request' (duration: 40.561499ms)","trace[584872564] 'compare' (duration: 76.117635ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:04.038770Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:34340","server-name":"","error":"EOF"} {"level":"warn","ts":"2026-03-17T20:46:04.076106Z","caller":"embed/config_logging.go:188","msg":"rejected connection on client endpoint","remote-addr":"127.0.0.1:34346","server-name":"","error":"EOF"} {"level":"info","ts":"2026-03-17T20:46:08.330002Z","caller":"traceutil/trace.go:172","msg":"trace[2004937796] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"112.999596ms","start":"2026-03-17T20:46:08.216982Z","end":"2026-03-17T20:46:08.329982Z","steps":["trace[2004937796] 'process raft request' (duration: 112.622126ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:09.092056Z","caller":"traceutil/trace.go:172","msg":"trace[747371675] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"118.499396ms","start":"2026-03-17T20:46:08.973519Z","end":"2026-03-17T20:46:09.092018Z","steps":["trace[747371675] 'process raft request' (duration: 64.639696ms)","trace[747371675] 'compare' (duration: 53.62737ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:46:09.092031Z","caller":"traceutil/trace.go:172","msg":"trace[1551591985] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"105.332069ms","start":"2026-03-17T20:46:08.986667Z","end":"2026-03-17T20:46:09.092000Z","steps":["trace[1551591985] 'process raft request' (duration: 105.26281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:21.439807Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"117.911086ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:21.439914Z","caller":"traceutil/trace.go:172","msg":"trace[1089854482] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:772; }","duration":"118.045417ms","start":"2026-03-17T20:46:21.321849Z","end":"2026-03-17T20:46:21.439894Z","steps":["trace[1089854482] 'range keys from in-memory index tree' (duration: 117.835126ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:25.293089Z","caller":"traceutil/trace.go:172","msg":"trace[1425315330] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"189.598137ms","start":"2026-03-17T20:46:25.103463Z","end":"2026-03-17T20:46:25.293061Z","steps":["trace[1425315330] 'process raft request' (duration: 189.392775ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:25.513864Z","caller":"traceutil/trace.go:172","msg":"trace[337453227] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:806; }","duration":"176.905222ms","start":"2026-03-17T20:46:25.336939Z","end":"2026-03-17T20:46:25.513844Z","steps":["trace[337453227] 'read index received' (duration: 176.898592ms)","trace[337453227] 'applied index is now lower than readState.Index' (duration: 5.34µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:25.513831Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"191.872257ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:25.513950Z","caller":"traceutil/trace.go:172","msg":"trace[315767472] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:779; }","duration":"192.007059ms","start":"2026-03-17T20:46:25.321927Z","end":"2026-03-17T20:46:25.513934Z","steps":["trace[315767472] 'range keys from in-memory index tree' (duration: 191.824736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:25.514131Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"177.184035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-17T20:46:25.514172Z","caller":"traceutil/trace.go:172","msg":"trace[1033006165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"177.227416ms","start":"2026-03-17T20:46:25.336933Z","end":"2026-03-17T20:46:25.514161Z","steps":["trace[1033006165] 'agreement among raft nodes before linearized reading' (duration: 177.064064ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:25.514387Z","caller":"traceutil/trace.go:172","msg":"trace[2116218700] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"378.726408ms","start":"2026-03-17T20:46:25.135642Z","end":"2026-03-17T20:46:25.514369Z","steps":["trace[2116218700] 'process raft request' (duration: 378.385403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:25.514757Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:46:25.135616Z","time spent":"378.987671ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:46:25.514521Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"148.34985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-17T20:46:25.515311Z","caller":"traceutil/trace.go:172","msg":"trace[654443009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"149.031659ms","start":"2026-03-17T20:46:25.366157Z","end":"2026-03-17T20:46:25.515189Z","steps":["trace[654443009] 'agreement among raft nodes before linearized reading' (duration: 148.29191ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:25.667682Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"134.891246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:25.667740Z","caller":"traceutil/trace.go:172","msg":"trace[2076707054] range","detail":"{range_begin:/registry/limitranges; range_end:; response_count:0; response_revision:780; }","duration":"134.958907ms","start":"2026-03-17T20:46:25.532768Z","end":"2026-03-17T20:46:25.667727Z","steps":["trace[2076707054] 'agreement among raft nodes before linearized reading' (duration: 91.36772ms)","trace[2076707054] 'range keys from in-memory index tree' (duration: 43.480426ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:46:25.667968Z","caller":"traceutil/trace.go:172","msg":"trace[1893724033] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"146.614199ms","start":"2026-03-17T20:46:25.521314Z","end":"2026-03-17T20:46:25.667928Z","steps":["trace[1893724033] 'process raft request' (duration: 102.849469ms)","trace[1893724033] 'compare' (duration: 43.607358ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:46:25.667995Z","caller":"traceutil/trace.go:172","msg":"trace[1642802335] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"146.362575ms","start":"2026-03-17T20:46:25.521607Z","end":"2026-03-17T20:46:25.667970Z","steps":["trace[1642802335] 'process raft request' (duration: 146.300105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:25.668150Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"106.167632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-17T20:46:25.668500Z","caller":"traceutil/trace.go:172","msg":"trace[1537888657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:782; }","duration":"106.505856ms","start":"2026-03-17T20:46:25.561972Z","end":"2026-03-17T20:46:25.668478Z","steps":["trace[1537888657] 'agreement among raft nodes before linearized reading' (duration: 106.105141ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:33.765381Z","caller":"traceutil/trace.go:172","msg":"trace[1732100088] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"134.924113ms","start":"2026-03-17T20:46:33.630419Z","end":"2026-03-17T20:46:33.765343Z","steps":["trace[1732100088] 'process raft request' (duration: 134.75313ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:33.914466Z","caller":"traceutil/trace.go:172","msg":"trace[2093225619] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"110.735243ms","start":"2026-03-17T20:46:33.803702Z","end":"2026-03-17T20:46:33.914438Z","steps":["trace[2093225619] 'process raft request' (duration: 72.437556ms)","trace[2093225619] 'compare' (duration: 38.162264ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:46:36.168358Z","caller":"traceutil/trace.go:172","msg":"trace[1157663179] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:838; }","duration":"326.287064ms","start":"2026-03-17T20:46:35.842031Z","end":"2026-03-17T20:46:36.168318Z","steps":["trace[1157663179] 'read index received' (duration: 326.272863ms)","trace[1157663179] 'applied index is now lower than readState.Index' (duration: 11.411µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:46:36.168522Z","caller":"traceutil/trace.go:172","msg":"trace[283312733] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"355.003849ms","start":"2026-03-17T20:46:35.813498Z","end":"2026-03-17T20:46:36.168502Z","steps":["trace[283312733] 'process raft request' (duration: 354.888597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:36.168679Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:46:35.813481Z","time spent":"355.128422ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":425,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:46:36.168652Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"326.59604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-17T20:46:36.168745Z","caller":"traceutil/trace.go:172","msg":"trace[1243929089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"326.701992ms","start":"2026-03-17T20:46:35.842025Z","end":"2026-03-17T20:46:36.168727Z","steps":["trace[1243929089] 'agreement among raft nodes before linearized reading' (duration: 326.420586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:36.168799Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:46:35.842010Z","time spent":"326.779803ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-03-17T20:46:36.213874Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"294.399904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-17T20:46:36.213964Z","caller":"traceutil/trace.go:172","msg":"trace[1983817114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"294.501166ms","start":"2026-03-17T20:46:35.919441Z","end":"2026-03-17T20:46:36.213942Z","steps":["trace[1983817114] 'agreement among raft nodes before linearized reading' (duration: 294.254401ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:36.213910Z","caller":"traceutil/trace.go:172","msg":"trace[961881657] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"396.688799ms","start":"2026-03-17T20:46:35.817205Z","end":"2026-03-17T20:46:36.213894Z","steps":["trace[961881657] 'process raft request' (duration: 396.585677ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:36.214096Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:46:35.817189Z","time spent":"396.842902ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:46:36.395817Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"163.322689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-17T20:46:36.395920Z","caller":"traceutil/trace.go:172","msg":"trace[1346470977] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:811; }","duration":"163.439791ms","start":"2026-03-17T20:46:36.232454Z","end":"2026-03-17T20:46:36.395894Z","steps":["trace[1346470977] 'agreement among raft nodes before linearized reading' (duration: 67.706536ms)","trace[1346470977] 'range keys from in-memory index tree' (duration: 95.553452ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:46:36.395964Z","caller":"traceutil/trace.go:172","msg":"trace[1496424701] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"178.487088ms","start":"2026-03-17T20:46:36.217428Z","end":"2026-03-17T20:46:36.395915Z","steps":["trace[1496424701] 'process raft request' (duration: 82.860374ms)","trace[1496424701] 'compare' (duration: 95.389789ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:46:38.633369Z","caller":"traceutil/trace.go:172","msg":"trace[1707000929] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:845; }","duration":"311.432948ms","start":"2026-03-17T20:46:38.321915Z","end":"2026-03-17T20:46:38.633348Z","steps":["trace[1707000929] 'read index received' (duration: 311.426708ms)","trace[1707000929] 'applied index is now lower than readState.Index' (duration: 5.57µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:38.633494Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"311.602363ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:38.633514Z","caller":"traceutil/trace.go:172","msg":"trace[1769598603] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:815; }","duration":"311.641064ms","start":"2026-03-17T20:46:38.321867Z","end":"2026-03-17T20:46:38.633508Z","steps":["trace[1769598603] 'agreement among raft nodes before linearized reading' (duration: 311.575692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:38.633694Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"106.018853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:38.633717Z","caller":"traceutil/trace.go:172","msg":"trace[106831034] range","detail":"{range_begin:/registry/clusterrolebindings; range_end:; response_count:0; response_revision:816; }","duration":"106.040083ms","start":"2026-03-17T20:46:38.527669Z","end":"2026-03-17T20:46:38.633709Z","steps":["trace[106831034] 'agreement among raft nodes before linearized reading' (duration: 105.983582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:38.633759Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"232.070193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-17T20:46:38.633863Z","caller":"traceutil/trace.go:172","msg":"trace[1348282932] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:816; }","duration":"232.160125ms","start":"2026-03-17T20:46:38.401657Z","end":"2026-03-17T20:46:38.633817Z","steps":["trace[1348282932] 'agreement among raft nodes before linearized reading' (duration: 231.965271ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:38.635057Z","caller":"traceutil/trace.go:172","msg":"trace[601220203] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"373.564408ms","start":"2026-03-17T20:46:38.260303Z","end":"2026-03-17T20:46:38.633867Z","steps":["trace[601220203] 'process raft request' (duration: 373.114049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:38.635335Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:46:38.260288Z","time spent":"374.921009ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:46:38.636460Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"182.663197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:38.636531Z","caller":"traceutil/trace.go:172","msg":"trace[1558859012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"185.395308ms","start":"2026-03-17T20:46:38.451123Z","end":"2026-03-17T20:46:38.636518Z","steps":["trace[1558859012] 'agreement among raft nodes before linearized reading' (duration: 182.574745ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:40.813626Z","caller":"traceutil/trace.go:172","msg":"trace[2119388933] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"142.253335ms","start":"2026-03-17T20:46:40.671348Z","end":"2026-03-17T20:46:40.813602Z","steps":["trace[2119388933] 'process raft request' (duration: 142.103711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:41.214872Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"100.53487ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-17T20:46:41.739012Z","caller":"traceutil/trace.go:172","msg":"trace[35959379] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"133.47266ms","start":"2026-03-17T20:46:41.605514Z","end":"2026-03-17T20:46:41.738987Z","steps":["trace[35959379] 'process raft request' (duration: 48.322502ms)","trace[35959379] 'compare' (duration: 85.015755ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:46:46.886496Z","caller":"traceutil/trace.go:172","msg":"trace[1442369901] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"174.65389ms","start":"2026-03-17T20:46:46.711807Z","end":"2026-03-17T20:46:46.886461Z","steps":["trace[1442369901] 'process raft request' (duration: 174.385833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:48.500499Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"177.759635ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:46:48.500623Z","caller":"traceutil/trace.go:172","msg":"trace[598460410] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:841; }","duration":"177.895269ms","start":"2026-03-17T20:46:48.322711Z","end":"2026-03-17T20:46:48.500606Z","steps":["trace[598460410] 'range keys from in-memory index tree' (duration: 177.710404ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:46:48.500792Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"474.750464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T20:46:48.500855Z","caller":"traceutil/trace.go:172","msg":"trace[2110836644] linearizableReadLoop","detail":"{readStateIndex:874; appliedIndex:873; }","duration":"138.356167ms","start":"2026-03-17T20:46:48.362487Z","end":"2026-03-17T20:46:48.500843Z","steps":["trace[2110836644] 'read index received' (duration: 47.692µs)","trace[2110836644] 'applied index is now lower than readState.Index' (duration: 138.307565ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:46:48.500960Z","caller":"traceutil/trace.go:172","msg":"trace[1339939256] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"553.817887ms","start":"2026-03-17T20:46:47.947130Z","end":"2026-03-17T20:46:48.500948Z","steps":["trace[1339939256] 'process raft request' (duration: 78.856678ms)","trace[1339939256] 'compare' (duration: 474.365523ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T20:46:48.501019Z","caller":"v3rpc/interceptor.go:202","msg":"request stats","start time":"2026-03-17T20:46:47.947089Z","time spent":"553.89507ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T20:46:48.501115Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"138.613674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-17T20:46:48.502220Z","caller":"traceutil/trace.go:172","msg":"trace[286961783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:842; }","duration":"138.681876ms","start":"2026-03-17T20:46:48.362480Z","end":"2026-03-17T20:46:48.501162Z","steps":["trace[286961783] 'agreement among raft nodes before linearized reading' (duration: 138.524901ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:46:52.662740Z","caller":"traceutil/trace.go:172","msg":"trace[1169592870] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"126.584635ms","start":"2026-03-17T20:46:52.536140Z","end":"2026-03-17T20:46:52.662724Z","steps":["trace[1169592870] 'process raft request' (duration: 126.482732ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:47:00.873012Z","caller":"traceutil/trace.go:172","msg":"trace[1633011949] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:908; }","duration":"149.814891ms","start":"2026-03-17T20:47:00.723169Z","end":"2026-03-17T20:47:00.872984Z","steps":["trace[1633011949] 'read index received' (duration: 149.806291ms)","trace[1633011949] 'applied index is now lower than readState.Index' (duration: 7.2µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:47:00.873352Z","caller":"traceutil/trace.go:172","msg":"trace[94659342] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"178.026302ms","start":"2026-03-17T20:47:00.695299Z","end":"2026-03-17T20:47:00.873325Z","steps":["trace[94659342] 'process raft request' (duration: 177.742953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:47:00.873381Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"150.194464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:auth-delegator\" limit:1 ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-03-17T20:47:00.873969Z","caller":"traceutil/trace.go:172","msg":"trace[1897326705] range","detail":"{range_begin:/registry/clusterroles/system:auth-delegator; range_end:; response_count:1; response_revision:875; }","duration":"150.785374ms","start":"2026-03-17T20:47:00.723157Z","end":"2026-03-17T20:47:00.873943Z","steps":["trace[1897326705] 'agreement among raft nodes before linearized reading' (duration: 149.978607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:47:00.873473Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"143.159761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-17T20:47:00.874168Z","caller":"traceutil/trace.go:172","msg":"trace[28939696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:875; }","duration":"143.867486ms","start":"2026-03-17T20:47:00.730281Z","end":"2026-03-17T20:47:00.874149Z","steps":["trace[28939696] 'agreement among raft nodes before linearized reading' (duration: 142.918053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:47:00.873501Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"133.857651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" limit:1 ","response":"range_response_count:1 size:678"} {"level":"info","ts":"2026-03-17T20:47:00.874295Z","caller":"traceutil/trace.go:172","msg":"trace[600032677] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:1; response_revision:875; }","duration":"134.653278ms","start":"2026-03-17T20:47:00.739632Z","end":"2026-03-17T20:47:00.874285Z","steps":["trace[600032677] 'agreement among raft nodes before linearized reading' (duration: 133.610813ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T20:47:27.538437Z","caller":"traceutil/trace.go:172","msg":"trace[914888531] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:990; }","duration":"216.237911ms","start":"2026-03-17T20:47:27.322171Z","end":"2026-03-17T20:47:27.538409Z","steps":["trace[914888531] 'read index received' (duration: 216.22353ms)","trace[914888531] 'applied index is now lower than readState.Index' (duration: 9.341µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T20:47:27.538518Z","caller":"traceutil/trace.go:172","msg":"trace[1638081729] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"227.730812ms","start":"2026-03-17T20:47:27.310774Z","end":"2026-03-17T20:47:27.538505Z","steps":["trace[1638081729] 'process raft request' (duration: 227.609647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:47:27.538636Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"202.29419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:47:27.538668Z","caller":"traceutil/trace.go:172","msg":"trace[908989979] range","detail":"{range_begin:/registry/namespaces; range_end:; response_count:0; response_revision:951; }","duration":"202.340832ms","start":"2026-03-17T20:47:27.336318Z","end":"2026-03-17T20:47:27.538659Z","steps":["trace[908989979] 'agreement among raft nodes before linearized reading' (duration: 202.262689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:47:27.538624Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"216.424829ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:47:27.538738Z","caller":"traceutil/trace.go:172","msg":"trace[1864785856] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:951; }","duration":"216.539034ms","start":"2026-03-17T20:47:27.322166Z","end":"2026-03-17T20:47:27.538705Z","steps":["trace[1864785856] 'agreement among raft nodes before linearized reading' (duration: 216.336375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:47:27.538999Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"125.644066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-17T20:47:27.539032Z","caller":"traceutil/trace.go:172","msg":"trace[2049690103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"125.679388ms","start":"2026-03-17T20:47:27.413346Z","end":"2026-03-17T20:47:27.539025Z","steps":["trace[2049690103] 'agreement among raft nodes before linearized reading' (duration: 125.586833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T20:47:27.760096Z","caller":"txn/util.go:93","msg":"apply request took too long","took":"131.307137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T20:47:27.760219Z","caller":"traceutil/trace.go:172","msg":"trace[1814053153] range","detail":"{range_begin:/registry/pods; range_end:; response_count:0; response_revision:952; }","duration":"131.562489ms","start":"2026-03-17T20:47:27.628635Z","end":"2026-03-17T20:47:27.760198Z","steps":["trace[1814053153] 'range keys from in-memory index tree' (duration: 131.216944ms)"],"step_count":1}