{"level":"warn","ts":"2026-07-02T00:22:11.438122Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T00:22:11.438253Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162:2380","--name=instance","--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"]} {"level":"warn","ts":"2026-07-02T00:22:11.438343Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T00:22:11.438353Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-07-02T00:22:11.438372Z","caller":"embed/etcd.go:494","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-07-02T00:22:11.439111Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-07-02T00:22:11.439196Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","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.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-02T00:22:11.443652Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.214044ms"} {"level":"info","ts":"2026-07-02T00:22:11.449423Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-07-02T00:22:11.449518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T00:22:11.44955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-07-02T00:22:11.449576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T00:22:11.449588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-07-02T00:22:11.449678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-07-02T00:22:11.454802Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T00:22:11.456388Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T00:22:11.457422Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-02T00:22:11.460042Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T00:22:11.460792Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T00:22:11.461024Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T00:22:11.46111Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T00:22:11.461299Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T00:22:11.461584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-07-02T00:22:11.461828Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-07-02T00:22:11.466685Z","caller":"embed/etcd.go:726","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-07-02T00:22:11.466858Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-07-02T00:22:11.466929Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-07-02T00:22:11.466999Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T00:22:11.46707Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T00:22:11.950698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T00:22:11.95078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T00:22:11.950821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-07-02T00:22:11.950849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-07-02T00:22:11.950867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-07-02T00:22:11.950883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-07-02T00:22:11.950899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-07-02T00:22:11.951759Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:22:11.95265Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T00:22:11.952866Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T00:22:11.954641Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:22:11.954764Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:22:11.954804Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:22:11.956964Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T00:22:11.957289Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T00:22:11.961038Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-07-02T00:22:11.970009Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T00:22:11.97011Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T00:22:13.562062Z","caller":"traceutil/trace.go:171","msg":"trace[1954331364] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"232.968418ms","start":"2026-07-02T00:22:13.329066Z","end":"2026-07-02T00:22:13.562035Z","steps":["trace[1954331364] 'read index received' (duration: 232.750853ms)","trace[1954331364] 'applied index is now lower than readState.Index' (duration: 216.075µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:13.562247Z","caller":"traceutil/trace.go:171","msg":"trace[1784278524] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"234.559958ms","start":"2026-07-02T00:22:13.327643Z","end":"2026-07-02T00:22:13.562203Z","steps":["trace[1784278524] 'process raft request' (duration: 234.142347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.88421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.103772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:22:13.88434Z","caller":"traceutil/trace.go:171","msg":"trace[350163554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2; }","duration":"555.270945ms","start":"2026-07-02T00:22:13.329037Z","end":"2026-07-02T00:22:13.884308Z","steps":["trace[350163554] 'agreement among raft nodes before linearized reading' (duration: 233.141892ms)","trace[350163554] 'range keys from in-memory index tree' (duration: 321.912648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:22:13.8844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.329021Z","time spent":"555.365418ms","remote":"127.0.0.1:42002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T00:22:13.885795Z","caller":"traceutil/trace.go:171","msg":"trace[1971457396] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"512.467796ms","start":"2026-07-02T00:22:13.373309Z","end":"2026-07-02T00:22:13.885777Z","steps":["trace[1971457396] 'process raft request' (duration: 512.212679ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:13.886031Z","caller":"traceutil/trace.go:171","msg":"trace[1655006001] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"511.480103ms","start":"2026-07-02T00:22:13.374498Z","end":"2026-07-02T00:22:13.885978Z","steps":["trace[1655006001] 'process raft request' (duration: 511.186535ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:13.886189Z","caller":"traceutil/trace.go:171","msg":"trace[322179822] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"511.35951ms","start":"2026-07-02T00:22:13.374735Z","end":"2026-07-02T00:22:13.886094Z","steps":["trace[322179822] 'process raft request' (duration: 511.173866ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:13.886267Z","caller":"traceutil/trace.go:171","msg":"trace[1044077179] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:5; }","duration":"324.105812ms","start":"2026-07-02T00:22:13.562136Z","end":"2026-07-02T00:22:13.886242Z","steps":["trace[1044077179] 'read index received' (duration: 323.353793ms)","trace[1044077179] 'applied index is now lower than readState.Index' (duration: 751.249µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:22:13.886392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.672859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-02T00:22:13.886409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.173569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:22:13.88643Z","caller":"traceutil/trace.go:171","msg":"trace[594012033] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"460.7447ms","start":"2026-07-02T00:22:13.425673Z","end":"2026-07-02T00:22:13.886418Z","steps":["trace[594012033] 'agreement among raft nodes before linearized reading' (duration: 460.627727ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:13.886445Z","caller":"traceutil/trace.go:171","msg":"trace[1464404784] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"503.22189ms","start":"2026-07-02T00:22:13.383211Z","end":"2026-07-02T00:22:13.886433Z","steps":["trace[1464404784] 'agreement among raft nodes before linearized reading' (duration: 503.153989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.886461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.425661Z","time spent":"460.793451ms","remote":"127.0.0.1:42094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-02T00:22:13.886478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.383203Z","time spent":"503.268412ms","remote":"127.0.0.1:42094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-02T00:22:13.886602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.864025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-02T00:22:13.88661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.840564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-02T00:22:13.88667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.521691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:22:13.886674Z","caller":"traceutil/trace.go:171","msg":"trace[505376664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:9; }","duration":"556.899556ms","start":"2026-07-02T00:22:13.329748Z","end":"2026-07-02T00:22:13.886647Z","steps":["trace[505376664] 'agreement among raft nodes before linearized reading' (duration: 556.821044ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:13.886695Z","caller":"traceutil/trace.go:171","msg":"trace[433449868] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:9; }","duration":"557.560372ms","start":"2026-07-02T00:22:13.329128Z","end":"2026-07-02T00:22:13.886688Z","steps":["trace[433449868] 'agreement among raft nodes before linearized reading' (duration: 557.517661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.886714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.329744Z","time spent":"556.961927ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T00:22:13.886718Z","caller":"traceutil/trace.go:171","msg":"trace[721459788] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"511.397391ms","start":"2026-07-02T00:22:13.375309Z","end":"2026-07-02T00:22:13.886707Z","steps":["trace[721459788] 'process raft request' (duration: 510.631032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.886765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.638443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:22:13.886789Z","caller":"traceutil/trace.go:171","msg":"trace[485318182] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:9; }","duration":"557.683055ms","start":"2026-07-02T00:22:13.3291Z","end":"2026-07-02T00:22:13.886783Z","steps":["trace[485318182] 'agreement among raft nodes before linearized reading' (duration: 557.641904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.886803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.460107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-02T00:22:13.886812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.329074Z","time spent":"557.733305ms","remote":"127.0.0.1:42380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-07-02T00:22:13.886809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.373292Z","time spent":"512.59621ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:22:13.886828Z","caller":"traceutil/trace.go:171","msg":"trace[631089255] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:9; }","duration":"452.56353ms","start":"2026-07-02T00:22:13.434257Z","end":"2026-07-02T00:22:13.88682Z","steps":["trace[631089255] 'agreement among raft nodes before linearized reading' (duration: 452.526359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.886903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.434223Z","time spent":"452.670912ms","remote":"127.0.0.1:42094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-07-02T00:22:13.886984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.448402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-cjdp4\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-07-02T00:22:13.886992Z","caller":"traceutil/trace.go:171","msg":"trace[2003733341] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"510.741295ms","start":"2026-07-02T00:22:13.376238Z","end":"2026-07-02T00:22:13.886979Z","steps":["trace[2003733341] 'process raft request' (duration: 509.934095ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:13.887013Z","caller":"traceutil/trace.go:171","msg":"trace[1840338906] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-cjdp4; range_end:; response_count:1; response_revision:9; }","duration":"320.524544ms","start":"2026-07-02T00:22:13.56648Z","end":"2026-07-02T00:22:13.887005Z","steps":["trace[1840338906] 'agreement among raft nodes before linearized reading' (duration: 320.466163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.88704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.566461Z","time spent":"320.573315ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":883,"request content":"key:\"/registry/certificatesigningrequests/csr-cjdp4\" "} {"level":"warn","ts":"2026-07-02T00:22:13.887059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.376226Z","time spent":"510.793496ms","remote":"127.0.0.1:42508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:22:13.886722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.329121Z","time spent":"557.594402ms","remote":"127.0.0.1:42166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":26,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"info","ts":"2026-07-02T00:22:13.88718Z","caller":"traceutil/trace.go:171","msg":"trace[1836831740] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"511.606406ms","start":"2026-07-02T00:22:13.375564Z","end":"2026-07-02T00:22:13.88717Z","steps":["trace[1836831740] 'process raft request' (duration: 510.573181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.887222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.375515Z","time spent":"511.686298ms","remote":"127.0.0.1:42508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:22:13.887198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.374487Z","time spent":"511.608556ms","remote":"127.0.0.1:42508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:22:13.886626Z","caller":"traceutil/trace.go:171","msg":"trace[1649608041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:9; }","duration":"556.896776ms","start":"2026-07-02T00:22:13.329721Z","end":"2026-07-02T00:22:13.886618Z","steps":["trace[1649608041] 'agreement among raft nodes before linearized reading' (duration: 556.857995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.88738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.329716Z","time spent":"557.655614ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T00:22:13.887524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.375277Z","time spent":"511.468273ms","remote":"127.0.0.1:42508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:22:13.88762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.374727Z","time spent":"511.546225ms","remote":"127.0.0.1:42508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:22:13.886859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.96491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-02T00:22:13.887733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.346084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:22:13.88675Z","caller":"traceutil/trace.go:171","msg":"trace[845469490] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"471.736059ms","start":"2026-07-02T00:22:13.414981Z","end":"2026-07-02T00:22:13.886717Z","steps":["trace[845469490] 'process raft request' (duration: 471.227647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.888193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.414965Z","time spent":"473.112762ms","remote":"127.0.0.1:42144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:22:13.887783Z","caller":"traceutil/trace.go:171","msg":"trace[1506821970] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"512.407486ms","start":"2026-07-02T00:22:13.375361Z","end":"2026-07-02T00:22:13.887769Z","steps":["trace[1506821970] 'agreement among raft nodes before linearized reading' (duration: 512.277733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.888825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.374852Z","time spent":"513.951244ms","remote":"127.0.0.1:42072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-07-02T00:22:13.88774Z","caller":"traceutil/trace.go:171","msg":"trace[2088629447] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:9; }","duration":"557.419359ms","start":"2026-07-02T00:22:13.330307Z","end":"2026-07-02T00:22:13.887726Z","steps":["trace[2088629447] 'agreement among raft nodes before linearized reading' (duration: 556.527027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.889188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.3303Z","time spent":"558.868055ms","remote":"127.0.0.1:42166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":26,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-07-02T00:22:13.887547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.306086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:22:13.889589Z","caller":"traceutil/trace.go:171","msg":"trace[2023732979] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:0; response_revision:9; }","duration":"518.074964ms","start":"2026-07-02T00:22:13.371487Z","end":"2026-07-02T00:22:13.889562Z","steps":["trace[2023732979] 'agreement among raft nodes before linearized reading' (duration: 515.313326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.889682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.371461Z","time spent":"518.207178ms","remote":"127.0.0.1:42020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-07-02T00:22:13.887842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.651751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:22:13.890178Z","caller":"traceutil/trace.go:171","msg":"trace[975186530] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"516.987909ms","start":"2026-07-02T00:22:13.373176Z","end":"2026-07-02T00:22:13.890163Z","steps":["trace[975186530] 'agreement among raft nodes before linearized reading' (duration: 514.581989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:13.890271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:13.373168Z","time spent":"517.087781ms","remote":"127.0.0.1:42094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-02T00:22:14.085379Z","caller":"traceutil/trace.go:171","msg":"trace[1357644219] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"192.041477ms","start":"2026-07-02T00:22:13.893312Z","end":"2026-07-02T00:22:14.085354Z","steps":["trace[1357644219] 'process raft request' (duration: 184.699516ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.085492Z","caller":"traceutil/trace.go:171","msg":"trace[268455324] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"190.479748ms","start":"2026-07-02T00:22:13.895004Z","end":"2026-07-02T00:22:14.085483Z","steps":["trace[268455324] 'process raft request' (duration: 190.260313ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.085563Z","caller":"traceutil/trace.go:171","msg":"trace[1304325136] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"190.420506ms","start":"2026-07-02T00:22:13.895116Z","end":"2026-07-02T00:22:14.085536Z","steps":["trace[1304325136] 'process raft request' (duration: 190.228181ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.08574Z","caller":"traceutil/trace.go:171","msg":"trace[282286606] transaction","detail":"{read_only:false; number_of_response:0; response_revision:16; }","duration":"190.114139ms","start":"2026-07-02T00:22:13.895614Z","end":"2026-07-02T00:22:14.085728Z","steps":["trace[282286606] 'process raft request' (duration: 189.784151ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.085564Z","caller":"traceutil/trace.go:171","msg":"trace[1578323244] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"190.205041ms","start":"2026-07-02T00:22:13.895334Z","end":"2026-07-02T00:22:14.085539Z","steps":["trace[1578323244] 'process raft request' (duration: 190.033707ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.085931Z","caller":"traceutil/trace.go:171","msg":"trace[1388234866] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"186.276305ms","start":"2026-07-02T00:22:13.899634Z","end":"2026-07-02T00:22:14.08591Z","steps":["trace[1388234866] 'process raft request' (duration: 186.150602ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.08545Z","caller":"traceutil/trace.go:171","msg":"trace[922333665] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"190.464857ms","start":"2026-07-02T00:22:13.894975Z","end":"2026-07-02T00:22:14.08544Z","steps":["trace[922333665] 'process raft request' (duration: 190.328493ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.085367Z","caller":"traceutil/trace.go:171","msg":"trace[285601976] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"191.934714ms","start":"2026-07-02T00:22:13.893388Z","end":"2026-07-02T00:22:14.085322Z","steps":["trace[285601976] 'process raft request' (duration: 191.824691ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.085992Z","caller":"traceutil/trace.go:171","msg":"trace[816025731] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:13; }","duration":"181.740533ms","start":"2026-07-02T00:22:13.904214Z","end":"2026-07-02T00:22:14.085955Z","steps":["trace[816025731] 'read index received' (duration: 173.629584ms)","trace[816025731] 'applied index is now lower than readState.Index' (duration: 8.109229ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:14.086223Z","caller":"traceutil/trace.go:171","msg":"trace[2064314907] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"190.390625ms","start":"2026-07-02T00:22:13.895735Z","end":"2026-07-02T00:22:14.086126Z","steps":["trace[2064314907] 'process raft request' (duration: 189.696129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:14.086299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.821575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-07-02T00:22:14.086349Z","caller":"traceutil/trace.go:171","msg":"trace[1616105577] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"192.910257ms","start":"2026-07-02T00:22:13.893423Z","end":"2026-07-02T00:22:14.086333Z","steps":["trace[1616105577] 'agreement among raft nodes before linearized reading' (duration: 192.655541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:14.086345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.673776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:22:14.086399Z","caller":"traceutil/trace.go:171","msg":"trace[1094998771] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"181.281283ms","start":"2026-07-02T00:22:13.905103Z","end":"2026-07-02T00:22:14.086384Z","steps":["trace[1094998771] 'process raft request' (duration: 180.803051ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.086425Z","caller":"traceutil/trace.go:171","msg":"trace[340228300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19; }","duration":"191.80426ms","start":"2026-07-02T00:22:13.894582Z","end":"2026-07-02T00:22:14.086386Z","steps":["trace[340228300] 'agreement among raft nodes before linearized reading' (duration: 191.516503ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.08657Z","caller":"traceutil/trace.go:171","msg":"trace[1102509297] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"142.414369ms","start":"2026-07-02T00:22:13.944133Z","end":"2026-07-02T00:22:14.086548Z","steps":["trace[1102509297] 'process raft request' (duration: 142.258806ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.086668Z","caller":"traceutil/trace.go:171","msg":"trace[1584731140] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"142.82634ms","start":"2026-07-02T00:22:13.943827Z","end":"2026-07-02T00:22:14.086653Z","steps":["trace[1584731140] 'process raft request' (duration: 142.40489ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:14.086736Z","caller":"traceutil/trace.go:171","msg":"trace[597076111] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"136.785672ms","start":"2026-07-02T00:22:13.949938Z","end":"2026-07-02T00:22:14.086724Z","steps":["trace[597076111] 'process raft request' (duration: 136.529466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:14.086737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.931868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:22:14.086814Z","caller":"traceutil/trace.go:171","msg":"trace[1658033835] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:22; }","duration":"180.040772ms","start":"2026-07-02T00:22:13.906758Z","end":"2026-07-02T00:22:14.086799Z","steps":["trace[1658033835] 'agreement among raft nodes before linearized reading' (duration: 179.783735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:14.086814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.76183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:22:14.086905Z","caller":"traceutil/trace.go:171","msg":"trace[1208716145] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:22; }","duration":"140.927543ms","start":"2026-07-02T00:22:13.945963Z","end":"2026-07-02T00:22:14.086891Z","steps":["trace[1208716145] 'agreement among raft nodes before linearized reading' (duration: 140.764739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:45.276716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.715435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-02T00:22:45.276773Z","caller":"traceutil/trace.go:171","msg":"trace[1992198950] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:480; }","duration":"281.818337ms","start":"2026-07-02T00:22:44.994943Z","end":"2026-07-02T00:22:45.276761Z","steps":["trace[1992198950] 'range keys from in-memory index tree' (duration: 281.619553ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:48.909038Z","caller":"traceutil/trace.go:171","msg":"trace[1422721905] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"145.296212ms","start":"2026-07-02T00:22:48.76371Z","end":"2026-07-02T00:22:48.909006Z","steps":["trace[1422721905] 'process raft request' (duration: 145.175749ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:48.915108Z","caller":"traceutil/trace.go:171","msg":"trace[957511207] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"146.397368ms","start":"2026-07-02T00:22:48.768694Z","end":"2026-07-02T00:22:48.915091Z","steps":["trace[957511207] 'process raft request' (duration: 145.806923ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:48.915212Z","caller":"traceutil/trace.go:171","msg":"trace[407436674] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"144.298217ms","start":"2026-07-02T00:22:48.770907Z","end":"2026-07-02T00:22:48.915206Z","steps":["trace[407436674] 'process raft request' (duration: 143.667632ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:48.915277Z","caller":"traceutil/trace.go:171","msg":"trace[804708886] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"138.576097ms","start":"2026-07-02T00:22:48.776694Z","end":"2026-07-02T00:22:48.91527Z","steps":["trace[804708886] 'process raft request' (duration: 137.916571ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:48.915353Z","caller":"traceutil/trace.go:171","msg":"trace[1259807289] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"137.690795ms","start":"2026-07-02T00:22:48.777656Z","end":"2026-07-02T00:22:48.915347Z","steps":["trace[1259807289] 'process raft request' (duration: 136.982308ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:48.91542Z","caller":"traceutil/trace.go:171","msg":"trace[599844311] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"137.275474ms","start":"2026-07-02T00:22:48.778139Z","end":"2026-07-02T00:22:48.915415Z","steps":["trace[599844311] 'process raft request' (duration: 136.521306ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:48.915496Z","caller":"traceutil/trace.go:171","msg":"trace[1345019601] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"134.426104ms","start":"2026-07-02T00:22:48.781065Z","end":"2026-07-02T00:22:48.915491Z","steps":["trace[1345019601] 'process raft request' (duration: 133.620455ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:48.915566Z","caller":"traceutil/trace.go:171","msg":"trace[1640761810] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"134.086497ms","start":"2026-07-02T00:22:48.781474Z","end":"2026-07-02T00:22:48.915561Z","steps":["trace[1640761810] 'process raft request' (duration: 133.236556ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:48.915886Z","caller":"traceutil/trace.go:171","msg":"trace[2066259462] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"127.006562ms","start":"2026-07-02T00:22:48.788857Z","end":"2026-07-02T00:22:48.915864Z","steps":["trace[2066259462] 'process raft request' (duration: 125.916976ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:48.916085Z","caller":"traceutil/trace.go:171","msg":"trace[101616172] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"122.953843ms","start":"2026-07-02T00:22:48.793124Z","end":"2026-07-02T00:22:48.916078Z","steps":["trace[101616172] 'process raft request' (duration: 121.714993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:52.14764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.319108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:22:52.147829Z","caller":"traceutil/trace.go:171","msg":"trace[1336030128] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"382.208538ms","start":"2026-07-02T00:22:51.765603Z","end":"2026-07-02T00:22:52.147811Z","steps":["trace[1336030128] 'read index received' (duration: 75.157752ms)","trace[1336030128] 'applied index is now lower than readState.Index' (duration: 307.049356ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:52.147874Z","caller":"traceutil/trace.go:171","msg":"trace[828650004] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"407.503108ms","start":"2026-07-02T00:22:51.740337Z","end":"2026-07-02T00:22:52.14784Z","steps":["trace[828650004] 'process raft request' (duration: 100.553114ms)","trace[828650004] 'compare' (duration: 306.166184ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:22:52.147992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.383602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:22:52.148032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:51.740308Z","time spent":"407.643561ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:22:52.148059Z","caller":"traceutil/trace.go:171","msg":"trace[1367846322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"382.477213ms","start":"2026-07-02T00:22:51.76557Z","end":"2026-07-02T00:22:52.148048Z","steps":["trace[1367846322] 'agreement among raft nodes before linearized reading' (duration: 382.34001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:52.148157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:51.765551Z","time spent":"382.595087ms","remote":"127.0.0.1:42002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T00:22:52.264259Z","caller":"traceutil/trace.go:171","msg":"trace[2039295398] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"116.349781ms","start":"2026-07-02T00:22:52.147883Z","end":"2026-07-02T00:22:52.264233Z","steps":["trace[2039295398] 'read index received' (duration: 115.810138ms)","trace[2039295398] 'applied index is now lower than readState.Index' (duration: 538.393µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:52.264374Z","caller":"traceutil/trace.go:171","msg":"trace[1137766847] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"263.018976ms","start":"2026-07-02T00:22:52.001308Z","end":"2026-07-02T00:22:52.264327Z","steps":["trace[1137766847] 'process raft request' (duration: 262.606026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:52.264458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.045564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:22:52.264649Z","caller":"traceutil/trace.go:171","msg":"trace[1490329801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"114.376792ms","start":"2026-07-02T00:22:52.150247Z","end":"2026-07-02T00:22:52.264624Z","steps":["trace[1490329801] 'agreement among raft nodes before linearized reading' (duration: 114.132876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:52.264654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.920523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T00:22:52.264773Z","caller":"traceutil/trace.go:171","msg":"trace[939369826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"161.067847ms","start":"2026-07-02T00:22:52.103665Z","end":"2026-07-02T00:22:52.264733Z","steps":["trace[939369826] 'agreement among raft nodes before linearized reading' (duration: 160.689678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:55.295436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.318709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-88wns\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-02T00:22:55.295546Z","caller":"traceutil/trace.go:171","msg":"trace[48230646] range","detail":"{range_begin:/registry/pods/kube-system/cilium-88wns; range_end:; response_count:1; response_revision:631; }","duration":"198.474463ms","start":"2026-07-02T00:22:55.097052Z","end":"2026-07-02T00:22:55.295526Z","steps":["trace[48230646] 'range keys from in-memory index tree' (duration: 198.137785ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:55.506988Z","caller":"traceutil/trace.go:171","msg":"trace[1091754192] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"341.423888ms","start":"2026-07-02T00:22:55.165544Z","end":"2026-07-02T00:22:55.506968Z","steps":["trace[1091754192] 'process raft request' (duration: 340.832693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:55.507112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:55.165515Z","time spent":"341.529271ms","remote":"127.0.0.1:42086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:22:55.564364Z","caller":"traceutil/trace.go:171","msg":"trace[705252425] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"253.679706ms","start":"2026-07-02T00:22:55.310656Z","end":"2026-07-02T00:22:55.564336Z","steps":["trace[705252425] 'process raft request' (duration: 253.510432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:56.26394Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085745745221189223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:22:56.764986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085745745221189223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:22:57.131477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:55.657243Z","time spent":"1.47422611s","remote":"127.0.0.1:57560","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-02T00:22:57.218703Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.647294142s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T00:22:57.219116Z","caller":"traceutil/trace.go:171","msg":"trace[812620744] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"1.455719987s","start":"2026-07-02T00:22:55.763377Z","end":"2026-07-02T00:22:57.219097Z","steps":["trace[812620744] 'read index received' (duration: 1.455507122s)","trace[812620744] 'applied index is now lower than readState.Index' (duration: 211.865µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:57.219222Z","caller":"traceutil/trace.go:171","msg":"trace[510401171] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.647897806s","start":"2026-07-02T00:22:55.571315Z","end":"2026-07-02T00:22:57.219212Z","steps":["trace[510401171] 'process raft request' (duration: 1.647586499s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:57.219303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:55.571297Z","time spent":"1.647949578s","remote":"127.0.0.1:42490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:22:57.219457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456066735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:22:57.219471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058529763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-02T00:22:57.219515Z","caller":"traceutil/trace.go:171","msg":"trace[1302373915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"1.456182998s","start":"2026-07-02T00:22:55.763318Z","end":"2026-07-02T00:22:57.219501Z","steps":["trace[1302373915] 'agreement among raft nodes before linearized reading' (duration: 1.456069555s)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:57.219534Z","caller":"traceutil/trace.go:171","msg":"trace[1897886742] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"1.058638315s","start":"2026-07-02T00:22:56.160877Z","end":"2026-07-02T00:22:57.219515Z","steps":["trace[1897886742] 'agreement among raft nodes before linearized reading' (duration: 1.058433921s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:57.219558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:55.763288Z","time spent":"1.4562628s","remote":"127.0.0.1:42002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T00:22:57.219607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.671901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T00:22:57.21964Z","caller":"traceutil/trace.go:171","msg":"trace[1317482683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"937.741412ms","start":"2026-07-02T00:22:56.281888Z","end":"2026-07-02T00:22:57.219629Z","steps":["trace[1317482683] 'agreement among raft nodes before linearized reading' (duration: 937.64108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:57.219662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:56.281838Z","time spent":"937.818354ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T00:22:57.219654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.632746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T00:22:57.219775Z","caller":"traceutil/trace.go:171","msg":"trace[576655534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"852.78035ms","start":"2026-07-02T00:22:56.366949Z","end":"2026-07-02T00:22:57.219729Z","steps":["trace[576655534] 'agreement among raft nodes before linearized reading' (duration: 852.495753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:57.219849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:56.366932Z","time spent":"852.903072ms","remote":"127.0.0.1:42246","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-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T00:22:57.219563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:56.160835Z","time spent":"1.058723118s","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T00:22:57.220976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.777657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T00:22:57.221136Z","caller":"traceutil/trace.go:171","msg":"trace[894288627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"850.007323ms","start":"2026-07-02T00:22:56.371079Z","end":"2026-07-02T00:22:57.221086Z","steps":["trace[894288627] 'agreement among raft nodes before linearized reading' (duration: 848.30387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:57.221222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:22:56.371057Z","time spent":"850.152146ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T00:22:57.38793Z","caller":"traceutil/trace.go:171","msg":"trace[1375121471] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"161.841036ms","start":"2026-07-02T00:22:57.226043Z","end":"2026-07-02T00:22:57.387884Z","steps":["trace[1375121471] 'process raft request' (duration: 70.043797ms)","trace[1375121471] 'compare' (duration: 91.135323ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:22:57.388041Z","caller":"traceutil/trace.go:171","msg":"trace[1219317601] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"161.885447ms","start":"2026-07-02T00:22:57.226128Z","end":"2026-07-02T00:22:57.388014Z","steps":["trace[1219317601] 'process raft request' (duration: 161.730603ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:57.388057Z","caller":"traceutil/trace.go:171","msg":"trace[969197708] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:657; }","duration":"158.642648ms","start":"2026-07-02T00:22:57.229392Z","end":"2026-07-02T00:22:57.388035Z","steps":["trace[969197708] 'read index received' (duration: 66.714785ms)","trace[969197708] 'applied index is now lower than readState.Index' (duration: 91.926873ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:22:57.388283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.323296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:22:57.388314Z","caller":"traceutil/trace.go:171","msg":"trace[1257195790] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:638; }","duration":"157.374267ms","start":"2026-07-02T00:22:57.230929Z","end":"2026-07-02T00:22:57.388303Z","steps":["trace[1257195790] 'agreement among raft nodes before linearized reading' (duration: 157.315656ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:57.38839Z","caller":"traceutil/trace.go:171","msg":"trace[1512625553] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"161.774485ms","start":"2026-07-02T00:22:57.226599Z","end":"2026-07-02T00:22:57.388373Z","steps":["trace[1512625553] 'process raft request' (duration: 161.341634ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:57.38851Z","caller":"traceutil/trace.go:171","msg":"trace[467521542] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"161.600351ms","start":"2026-07-02T00:22:57.226894Z","end":"2026-07-02T00:22:57.388494Z","steps":["trace[467521542] 'process raft request' (duration: 161.104878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:22:57.389628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.401247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:22:57.390049Z","caller":"traceutil/trace.go:171","msg":"trace[805731414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"160.639397ms","start":"2026-07-02T00:22:57.229386Z","end":"2026-07-02T00:22:57.390025Z","steps":["trace[805731414] 'agreement among raft nodes before linearized reading' (duration: 158.794751ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:22:59.382916Z","caller":"traceutil/trace.go:171","msg":"trace[1576045858] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"164.331507ms","start":"2026-07-02T00:22:59.218555Z","end":"2026-07-02T00:22:59.382887Z","steps":["trace[1576045858] 'process raft request' (duration: 156.536686ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:02.299762Z","caller":"traceutil/trace.go:171","msg":"trace[976742455] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"139.840317ms","start":"2026-07-02T00:23:02.159852Z","end":"2026-07-02T00:23:02.299693Z","steps":["trace[976742455] 'process raft request' (duration: 69.799621ms)","trace[976742455] 'compare' (duration: 69.862422ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:07.769893Z","caller":"traceutil/trace.go:171","msg":"trace[67842963] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"125.885215ms","start":"2026-07-02T00:23:07.643947Z","end":"2026-07-02T00:23:07.769832Z","steps":["trace[67842963] 'process raft request' (duration: 72.030095ms)","trace[67842963] 'compare' (duration: 53.621024ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:07.991075Z","caller":"traceutil/trace.go:171","msg":"trace[1148134628] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"211.663347ms","start":"2026-07-02T00:23:07.779392Z","end":"2026-07-02T00:23:07.991055Z","steps":["trace[1148134628] 'process raft request' (duration: 112.845165ms)","trace[1148134628] 'compare' (duration: 98.501654ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:07.997601Z","caller":"traceutil/trace.go:171","msg":"trace[1842046719] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"211.575546ms","start":"2026-07-02T00:23:07.786011Z","end":"2026-07-02T00:23:07.997586Z","steps":["trace[1842046719] 'process raft request' (duration: 211.478624ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:13.757696Z","caller":"traceutil/trace.go:171","msg":"trace[285728487] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"152.124759ms","start":"2026-07-02T00:23:13.605546Z","end":"2026-07-02T00:23:13.75767Z","steps":["trace[285728487] 'process raft request' (duration: 151.990726ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:13.783975Z","caller":"traceutil/trace.go:171","msg":"trace[1959152337] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"176.894385ms","start":"2026-07-02T00:23:13.607051Z","end":"2026-07-02T00:23:13.783945Z","steps":["trace[1959152337] 'process raft request' (duration: 176.724461ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:13.784035Z","caller":"traceutil/trace.go:171","msg":"trace[1268956923] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"177.873159ms","start":"2026-07-02T00:23:13.606118Z","end":"2026-07-02T00:23:13.783991Z","steps":["trace[1268956923] 'process raft request' (duration: 177.528001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:17.970081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.116081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T00:23:17.970179Z","caller":"traceutil/trace.go:171","msg":"trace[1901462435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:810; }","duration":"175.255934ms","start":"2026-07-02T00:23:17.794894Z","end":"2026-07-02T00:23:17.97015Z","steps":["trace[1901462435] 'range keys from in-memory index tree' (duration: 174.971708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:17.970328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.130013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T00:23:17.970409Z","caller":"traceutil/trace.go:171","msg":"trace[1543501951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:810; }","duration":"173.234476ms","start":"2026-07-02T00:23:17.797154Z","end":"2026-07-02T00:23:17.970388Z","steps":["trace[1543501951] 'range keys from in-memory index tree' (duration: 172.934649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:17.970837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.589898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T00:23:17.970898Z","caller":"traceutil/trace.go:171","msg":"trace[1978765162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:810; }","duration":"196.732581ms","start":"2026-07-02T00:23:17.774151Z","end":"2026-07-02T00:23:17.970883Z","steps":["trace[1978765162] 'range keys from in-memory index tree' (duration: 196.222329ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:18.268412Z","caller":"traceutil/trace.go:171","msg":"trace[1909291617] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"293.132634ms","start":"2026-07-02T00:23:17.975261Z","end":"2026-07-02T00:23:18.268393Z","steps":["trace[1909291617] 'process raft request' (duration: 293.005641ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:18.31497Z","caller":"traceutil/trace.go:171","msg":"trace[1981058944] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"339.631664ms","start":"2026-07-02T00:23:17.975296Z","end":"2026-07-02T00:23:18.314928Z","steps":["trace[1981058944] 'process raft request' (duration: 339.355757ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:18.315003Z","caller":"traceutil/trace.go:171","msg":"trace[1407062848] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"195.84164ms","start":"2026-07-02T00:23:18.119142Z","end":"2026-07-02T00:23:18.314984Z","steps":["trace[1407062848] 'process raft request' (duration: 195.726647ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:18.31501Z","caller":"traceutil/trace.go:171","msg":"trace[856653331] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"339.569613ms","start":"2026-07-02T00:23:17.975403Z","end":"2026-07-02T00:23:18.314973Z","steps":["trace[856653331] 'process raft request' (duration: 339.388619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:18.315195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:17.975275Z","time spent":"339.783538ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:23:18.315221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:17.975394Z","time spent":"339.700066ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:23:18.315357Z","caller":"traceutil/trace.go:171","msg":"trace[1670841711] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"104.596144ms","start":"2026-07-02T00:23:18.210745Z","end":"2026-07-02T00:23:18.315341Z","steps":["trace[1670841711] 'process raft request' (duration: 104.178944ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:27.523232Z","caller":"traceutil/trace.go:171","msg":"trace[349262557] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"108.597392ms","start":"2026-07-02T00:23:27.414611Z","end":"2026-07-02T00:23:27.523209Z","steps":["trace[349262557] 'process raft request' (duration: 108.543511ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:27.523442Z","caller":"traceutil/trace.go:171","msg":"trace[1408933510] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"112.334313ms","start":"2026-07-02T00:23:27.411085Z","end":"2026-07-02T00:23:27.523419Z","steps":["trace[1408933510] 'process raft request' (duration: 112.007035ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:27.523481Z","caller":"traceutil/trace.go:171","msg":"trace[1548944576] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"112.57826ms","start":"2026-07-02T00:23:27.410827Z","end":"2026-07-02T00:23:27.523405Z","steps":["trace[1548944576] 'process raft request' (duration: 112.135229ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:27.782738Z","caller":"traceutil/trace.go:171","msg":"trace[1830950314] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"181.162099ms","start":"2026-07-02T00:23:27.601547Z","end":"2026-07-02T00:23:27.782709Z","steps":["trace[1830950314] 'process raft request' (duration: 145.941307ms)","trace[1830950314] 'compare' (duration: 35.076729ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:27.783094Z","caller":"traceutil/trace.go:171","msg":"trace[1365922356] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"180.561725ms","start":"2026-07-02T00:23:27.602519Z","end":"2026-07-02T00:23:27.783081Z","steps":["trace[1365922356] 'process raft request' (duration: 180.147305ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:27.783096Z","caller":"traceutil/trace.go:171","msg":"trace[1310172592] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"180.109194ms","start":"2026-07-02T00:23:27.602963Z","end":"2026-07-02T00:23:27.783072Z","steps":["trace[1310172592] 'process raft request' (duration: 179.904209ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:27.783166Z","caller":"traceutil/trace.go:171","msg":"trace[322751341] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:948; }","duration":"180.410811ms","start":"2026-07-02T00:23:27.602733Z","end":"2026-07-02T00:23:27.783144Z","steps":["trace[322751341] 'read index received' (duration: 47.522674ms)","trace[322751341] 'applied index is now lower than readState.Index' (duration: 132.887027ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:23:27.783216Z","caller":"traceutil/trace.go:171","msg":"trace[1852837765] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"179.793176ms","start":"2026-07-02T00:23:27.603409Z","end":"2026-07-02T00:23:27.783202Z","steps":["trace[1852837765] 'process raft request' (duration: 179.561071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:27.783415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.664287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-t8j7c\" ","response":"range_response_count:1 size:4881"} {"level":"info","ts":"2026-07-02T00:23:27.78346Z","caller":"traceutil/trace.go:171","msg":"trace[1046554492] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-t8j7c; range_end:; response_count:1; response_revision:923; }","duration":"180.74212ms","start":"2026-07-02T00:23:27.602705Z","end":"2026-07-02T00:23:27.783447Z","steps":["trace[1046554492] 'agreement among raft nodes before linearized reading' (duration: 180.602866ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:27.783461Z","caller":"traceutil/trace.go:171","msg":"trace[1680365258] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"177.287544ms","start":"2026-07-02T00:23:27.606128Z","end":"2026-07-02T00:23:27.783415Z","steps":["trace[1680365258] 'process raft request' (duration: 176.889545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:27.783539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.410503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4228"} {"level":"warn","ts":"2026-07-02T00:23:27.783546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.444764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T00:23:27.783567Z","caller":"traceutil/trace.go:171","msg":"trace[1215650986] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:923; }","duration":"125.476075ms","start":"2026-07-02T00:23:27.658081Z","end":"2026-07-02T00:23:27.783557Z","steps":["trace[1215650986] 'agreement among raft nodes before linearized reading' (duration: 125.402113ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:27.783587Z","caller":"traceutil/trace.go:171","msg":"trace[1812520557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:923; }","duration":"176.523696ms","start":"2026-07-02T00:23:27.607052Z","end":"2026-07-02T00:23:27.783576Z","steps":["trace[1812520557] 'agreement among raft nodes before linearized reading' (duration: 176.355841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:53.482052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.244451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:23:53.482126Z","caller":"traceutil/trace.go:171","msg":"trace[656289758] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1026; }","duration":"148.369473ms","start":"2026-07-02T00:23:53.333742Z","end":"2026-07-02T00:23:53.482111Z","steps":["trace[656289758] 'count revisions from in-memory index tree' (duration: 148.152128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:54.20067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.396766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:23:54.200744Z","caller":"traceutil/trace.go:171","msg":"trace[1873177858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"438.52744ms","start":"2026-07-02T00:23:53.7622Z","end":"2026-07-02T00:23:54.200727Z","steps":["trace[1873177858] 'range keys from in-memory index tree' (duration: 438.251663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:23:54.200776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:23:53.762179Z","time spent":"438.589301ms","remote":"127.0.0.1:42002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T00:23:54.201239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.886452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T00:23:54.201308Z","caller":"traceutil/trace.go:171","msg":"trace[1014708025] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1027; }","duration":"246.990864ms","start":"2026-07-02T00:23:53.9543Z","end":"2026-07-02T00:23:54.201291Z","steps":["trace[1014708025] 'range keys from in-memory index tree' (duration: 246.702698ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:23:54.433721Z","caller":"traceutil/trace.go:171","msg":"trace[1169015258] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"223.454342ms","start":"2026-07-02T00:23:54.210244Z","end":"2026-07-02T00:23:54.433699Z","steps":["trace[1169015258] 'process raft request' (duration: 223.308949ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:16.786441Z","caller":"traceutil/trace.go:171","msg":"trace[1668960667] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"166.410932ms","start":"2026-07-02T00:24:16.620007Z","end":"2026-07-02T00:24:16.786418Z","steps":["trace[1668960667] 'process raft request' (duration: 143.544446ms)","trace[1668960667] 'compare' (duration: 22.591619ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:17.427455Z","caller":"traceutil/trace.go:171","msg":"trace[2102698191] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1148; }","duration":"108.458572ms","start":"2026-07-02T00:24:17.318975Z","end":"2026-07-02T00:24:17.427433Z","steps":["trace[2102698191] 'read index received' (duration: 108.257447ms)","trace[2102698191] 'applied index is now lower than readState.Index' (duration: 200.345µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:17.42755Z","caller":"traceutil/trace.go:171","msg":"trace[1796425190] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"112.458879ms","start":"2026-07-02T00:24:17.315065Z","end":"2026-07-02T00:24:17.427524Z","steps":["trace[1796425190] 'process raft request' (duration: 112.239464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:17.427628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.624765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:24:17.427695Z","caller":"traceutil/trace.go:171","msg":"trace[1347194044] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1106; }","duration":"108.760739ms","start":"2026-07-02T00:24:17.318919Z","end":"2026-07-02T00:24:17.42768Z","steps":["trace[1347194044] 'agreement among raft nodes before linearized reading' (duration: 108.619655ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:17.748313Z","caller":"traceutil/trace.go:171","msg":"trace[920050933] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"170.84545ms","start":"2026-07-02T00:24:17.57743Z","end":"2026-07-02T00:24:17.748275Z","steps":["trace[920050933] 'process raft request' (duration: 150.768332ms)","trace[920050933] 'compare' (duration: 19.821962ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:18.803177Z","caller":"traceutil/trace.go:171","msg":"trace[2086468127] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"260.74622ms","start":"2026-07-02T00:24:18.54241Z","end":"2026-07-02T00:24:18.803157Z","steps":["trace[2086468127] 'process raft request' (duration: 260.434042ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:19.019143Z","caller":"traceutil/trace.go:171","msg":"trace[1105680583] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"212.354642ms","start":"2026-07-02T00:24:18.806764Z","end":"2026-07-02T00:24:19.019119Z","steps":["trace[1105680583] 'process raft request' (duration: 212.205388ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:19.030205Z","caller":"traceutil/trace.go:171","msg":"trace[452304736] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1151; }","duration":"129.402141ms","start":"2026-07-02T00:24:18.90078Z","end":"2026-07-02T00:24:19.030182Z","steps":["trace[452304736] 'read index received' (duration: 118.190689ms)","trace[452304736] 'applied index is now lower than readState.Index' (duration: 11.210382ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:19.030257Z","caller":"traceutil/trace.go:171","msg":"trace[2104316502] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"223.462052ms","start":"2026-07-02T00:24:18.806761Z","end":"2026-07-02T00:24:19.030223Z","steps":["trace[2104316502] 'process raft request' (duration: 223.252407ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:19.030298Z","caller":"traceutil/trace.go:171","msg":"trace[1731206806] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"222.794146ms","start":"2026-07-02T00:24:18.80747Z","end":"2026-07-02T00:24:19.030264Z","steps":["trace[1731206806] 'process raft request' (duration: 222.674403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:19.030372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.574095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:24:19.030861Z","caller":"traceutil/trace.go:171","msg":"trace[770436489] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1111; }","duration":"129.682558ms","start":"2026-07-02T00:24:18.900728Z","end":"2026-07-02T00:24:19.030411Z","steps":["trace[770436489] 'agreement among raft nodes before linearized reading' (duration: 129.579615ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:19.939541Z","caller":"traceutil/trace.go:171","msg":"trace[1514287055] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"185.888167ms","start":"2026-07-02T00:24:19.753627Z","end":"2026-07-02T00:24:19.939515Z","steps":["trace[1514287055] 'process raft request' (duration: 185.754583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:20.535537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.386839ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-02T00:24:20.535721Z","caller":"traceutil/trace.go:171","msg":"trace[1765826764] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1157; }","duration":"380.096405ms","start":"2026-07-02T00:24:20.155603Z","end":"2026-07-02T00:24:20.535699Z","steps":["trace[1765826764] 'read index received' (duration: 138.524932ms)","trace[1765826764] 'applied index is now lower than readState.Index' (duration: 241.569743ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:20.535954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.338742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:24:20.536018Z","caller":"traceutil/trace.go:171","msg":"trace[1051902644] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1114; }","duration":"380.415703ms","start":"2026-07-02T00:24:20.155587Z","end":"2026-07-02T00:24:20.536002Z","steps":["trace[1051902644] 'agreement among raft nodes before linearized reading' (duration: 380.298161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:20.536054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:20.155536Z","time spent":"380.508145ms","remote":"127.0.0.1:42114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-02T00:24:20.536475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.717421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:24:20.536599Z","caller":"traceutil/trace.go:171","msg":"trace[2044731352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"180.888865ms","start":"2026-07-02T00:24:20.355691Z","end":"2026-07-02T00:24:20.53658Z","steps":["trace[2044731352] 'agreement among raft nodes before linearized reading' (duration: 180.711551ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:35.265357Z","caller":"traceutil/trace.go:171","msg":"trace[355248518] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"121.871247ms","start":"2026-07-02T00:24:35.143465Z","end":"2026-07-02T00:24:35.265336Z","steps":["trace[355248518] 'process raft request' (duration: 121.701373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:35.265514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.539986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-02T00:24:35.265567Z","caller":"traceutil/trace.go:171","msg":"trace[2066074804] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1213; }","duration":"120.622537ms","start":"2026-07-02T00:24:35.144929Z","end":"2026-07-02T00:24:35.265552Z","steps":["trace[2066074804] 'agreement among raft nodes before linearized reading' (duration: 120.513645ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:35.265627Z","caller":"traceutil/trace.go:171","msg":"trace[1497479038] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1261; }","duration":"120.34124ms","start":"2026-07-02T00:24:35.144961Z","end":"2026-07-02T00:24:35.265302Z","steps":["trace[1497479038] 'read index received' (duration: 120.178086ms)","trace[1497479038] 'applied index is now lower than readState.Index' (duration: 162.304µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:35.26598Z","caller":"traceutil/trace.go:171","msg":"trace[199990029] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"119.813718ms","start":"2026-07-02T00:24:35.146144Z","end":"2026-07-02T00:24:35.265957Z","steps":["trace[199990029] 'process raft request' (duration: 119.573681ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:36.367267Z","caller":"traceutil/trace.go:171","msg":"trace[773109804] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"376.840167ms","start":"2026-07-02T00:24:35.990403Z","end":"2026-07-02T00:24:36.367243Z","steps":["trace[773109804] 'process raft request' (duration: 376.697233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:36.367447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:35.99038Z","time spent":"376.95834ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:24:36.367807Z","caller":"traceutil/trace.go:171","msg":"trace[496815544] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1266; }","duration":"197.13727ms","start":"2026-07-02T00:24:36.170658Z","end":"2026-07-02T00:24:36.367795Z","steps":["trace[496815544] 'read index received' (duration: 197.13326ms)","trace[496815544] 'applied index is now lower than readState.Index' (duration: 3.27µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:24:36.367882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.218684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:24:36.367917Z","caller":"traceutil/trace.go:171","msg":"trace[1387802013] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1217; }","duration":"197.260475ms","start":"2026-07-02T00:24:36.170648Z","end":"2026-07-02T00:24:36.367908Z","steps":["trace[1387802013] 'agreement among raft nodes before linearized reading' (duration: 197.197893ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:36.805301Z","caller":"traceutil/trace.go:171","msg":"trace[1395486397] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"139.61593ms","start":"2026-07-02T00:24:36.665669Z","end":"2026-07-02T00:24:36.805284Z","steps":["trace[1395486397] 'process raft request' (duration: 139.338293ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:49.688017Z","caller":"traceutil/trace.go:171","msg":"trace[2023735957] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"328.916275ms","start":"2026-07-02T00:24:49.359082Z","end":"2026-07-02T00:24:49.687999Z","steps":["trace[2023735957] 'read index received' (duration: 328.768901ms)","trace[2023735957] 'applied index is now lower than readState.Index' (duration: 146.484µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:24:49.688116Z","caller":"traceutil/trace.go:171","msg":"trace[2107501435] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"344.793448ms","start":"2026-07-02T00:24:49.343297Z","end":"2026-07-02T00:24:49.68809Z","steps":["trace[2107501435] 'process raft request' (duration: 344.576053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:49.688203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.161963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T00:24:49.688237Z","caller":"traceutil/trace.go:171","msg":"trace[1519318031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1259; }","duration":"325.204744ms","start":"2026-07-02T00:24:49.363023Z","end":"2026-07-02T00:24:49.688228Z","steps":["trace[1519318031] 'agreement among raft nodes before linearized reading' (duration: 325.094701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:49.688261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:49.362993Z","time spent":"325.261765ms","remote":"127.0.0.1:42246","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-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T00:24:49.68826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:49.343273Z","time spent":"344.899571ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:24:49.688308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.200631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T00:24:49.6884Z","caller":"traceutil/trace.go:171","msg":"trace[273389466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1259; }","duration":"329.353085ms","start":"2026-07-02T00:24:49.359026Z","end":"2026-07-02T00:24:49.68838Z","steps":["trace[273389466] 'agreement among raft nodes before linearized reading' (duration: 329.082218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:24:49.68845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:24:49.359005Z","time spent":"329.435047ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T00:24:49.688353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.846656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T00:24:49.688514Z","caller":"traceutil/trace.go:171","msg":"trace[452892419] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1259; }","duration":"216.02619ms","start":"2026-07-02T00:24:49.472462Z","end":"2026-07-02T00:24:49.688488Z","steps":["trace[452892419] 'agreement among raft nodes before linearized reading' (duration: 215.865876ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:54.636203Z","caller":"traceutil/trace.go:171","msg":"trace[79714604] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"187.784337ms","start":"2026-07-02T00:24:54.448389Z","end":"2026-07-02T00:24:54.636173Z","steps":["trace[79714604] 'process raft request' (duration: 187.52849ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:55.348238Z","caller":"traceutil/trace.go:171","msg":"trace[514913179] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"192.134921ms","start":"2026-07-02T00:24:55.156068Z","end":"2026-07-02T00:24:55.348203Z","steps":["trace[514913179] 'process raft request' (duration: 192.030178ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:55.934564Z","caller":"traceutil/trace.go:171","msg":"trace[1722651857] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"104.806908ms","start":"2026-07-02T00:24:55.829732Z","end":"2026-07-02T00:24:55.934539Z","steps":["trace[1722651857] 'process raft request' (duration: 104.666865ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:55.961091Z","caller":"traceutil/trace.go:171","msg":"trace[1502011378] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"129.947656ms","start":"2026-07-02T00:24:55.831105Z","end":"2026-07-02T00:24:55.961053Z","steps":["trace[1502011378] 'process raft request' (duration: 129.916855ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:55.96125Z","caller":"traceutil/trace.go:171","msg":"trace[150551550] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"130.548441ms","start":"2026-07-02T00:24:55.830692Z","end":"2026-07-02T00:24:55.96124Z","steps":["trace[150551550] 'process raft request' (duration: 130.309445ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:55.961348Z","caller":"traceutil/trace.go:171","msg":"trace[1045342135] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"131.222367ms","start":"2026-07-02T00:24:55.830117Z","end":"2026-07-02T00:24:55.961339Z","steps":["trace[1045342135] 'process raft request' (duration: 130.783147ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:24:56.858545Z","caller":"traceutil/trace.go:171","msg":"trace[908280149] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"217.216799ms","start":"2026-07-02T00:24:56.641299Z","end":"2026-07-02T00:24:56.858516Z","steps":["trace[908280149] 'process raft request' (duration: 217.055835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:25:09.264316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085745745221190871,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:25:09.76507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085745745221190871,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:25:10.181315Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.575562233s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T00:25:10.181679Z","caller":"traceutil/trace.go:171","msg":"trace[1825265083] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1442; }","duration":"1.418184872s","start":"2026-07-02T00:25:08.763469Z","end":"2026-07-02T00:25:10.181654Z","steps":["trace[1825265083] 'read index received' (duration: 1.418107771s)","trace[1825265083] 'applied index is now lower than readState.Index' (duration: 75.931µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:25:10.181793Z","caller":"traceutil/trace.go:171","msg":"trace[423474370] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"1.576148857s","start":"2026-07-02T00:25:08.605629Z","end":"2026-07-02T00:25:10.181778Z","steps":["trace[423474370] 'process raft request' (duration: 1.575876501s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:25:10.181908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418390187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:25:10.181962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:25:08.605611Z","time spent":"1.576217689s","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:25:10.181984Z","caller":"traceutil/trace.go:171","msg":"trace[1809396462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1388; }","duration":"1.418549281s","start":"2026-07-02T00:25:08.763416Z","end":"2026-07-02T00:25:10.181965Z","steps":["trace[1809396462] 'agreement among raft nodes before linearized reading' (duration: 1.418359956s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:25:10.182042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:25:08.763394Z","time spent":"1.418631323s","remote":"127.0.0.1:41996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T00:25:10.185387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.822565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-02T00:25:10.185596Z","caller":"traceutil/trace.go:171","msg":"trace[1311676133] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1389; }","duration":"984.074761ms","start":"2026-07-02T00:25:09.201496Z","end":"2026-07-02T00:25:10.185571Z","steps":["trace[1311676133] 'agreement among raft nodes before linearized reading' (duration: 983.736943ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:25:10.185901Z","caller":"traceutil/trace.go:171","msg":"trace[1555283995] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"212.328452ms","start":"2026-07-02T00:25:09.973559Z","end":"2026-07-02T00:25:10.185888Z","steps":["trace[1555283995] 'process raft request' (duration: 211.465202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:25:10.185854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:25:09.201476Z","time spent":"984.358268ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-02T00:25:10.186355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.641066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-07-02T00:25:10.18642Z","caller":"traceutil/trace.go:171","msg":"trace[1464771515] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1389; }","duration":"128.825461ms","start":"2026-07-02T00:25:10.057579Z","end":"2026-07-02T00:25:10.186405Z","steps":["trace[1464771515] 'agreement among raft nodes before linearized reading' (duration: 128.448182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:25:10.186549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.26641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T00:25:10.18657Z","caller":"traceutil/trace.go:171","msg":"trace[647218887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1389; }","duration":"155.310602ms","start":"2026-07-02T00:25:10.031253Z","end":"2026-07-02T00:25:10.186564Z","steps":["trace[647218887] 'agreement among raft nodes before linearized reading' (duration: 155.23803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:25:10.186715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.619799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-02T00:25:10.186738Z","caller":"traceutil/trace.go:171","msg":"trace[113226106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1389; }","duration":"155.66564ms","start":"2026-07-02T00:25:10.031066Z","end":"2026-07-02T00:25:10.186731Z","steps":["trace[113226106] 'agreement among raft nodes before linearized reading' (duration: 155.555427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:25:10.186819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.157996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:25:10.186838Z","caller":"traceutil/trace.go:171","msg":"trace[572652705] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1389; }","duration":"772.213068ms","start":"2026-07-02T00:25:09.414619Z","end":"2026-07-02T00:25:10.186832Z","steps":["trace[572652705] 'agreement among raft nodes before linearized reading' (duration: 772.177827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:25:10.18686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:25:09.414603Z","time spent":"772.249179ms","remote":"127.0.0.1:42364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-07-02T00:25:10.187229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.928756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T00:25:10.187256Z","caller":"traceutil/trace.go:171","msg":"trace[1998839979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1389; }","duration":"194.003789ms","start":"2026-07-02T00:25:09.993245Z","end":"2026-07-02T00:25:10.187249Z","steps":["trace[1998839979] 'agreement among raft nodes before linearized reading' (duration: 193.752583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:25:10.187458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.797965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T00:25:10.187483Z","caller":"traceutil/trace.go:171","msg":"trace[95791754] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1389; }","duration":"687.862176ms","start":"2026-07-02T00:25:09.499613Z","end":"2026-07-02T00:25:10.187475Z","steps":["trace[95791754] 'agreement among raft nodes before linearized reading' (duration: 687.730443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:25:10.187515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:25:09.499596Z","time spent":"687.913987ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T00:25:10.187601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.831934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T00:25:10.18763Z","caller":"traceutil/trace.go:171","msg":"trace[156207407] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1389; }","duration":"758.888676ms","start":"2026-07-02T00:25:09.428725Z","end":"2026-07-02T00:25:10.187614Z","steps":["trace[156207407] 'agreement among raft nodes before linearized reading' (duration: 758.848975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:25:10.187655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:25:09.428706Z","time spent":"758.939956ms","remote":"127.0.0.1:39944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-07-02T00:25:10.185732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.469034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T00:25:10.188131Z","caller":"traceutil/trace.go:171","msg":"trace[1231969716] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1389; }","duration":"159.908473ms","start":"2026-07-02T00:25:10.028212Z","end":"2026-07-02T00:25:10.188121Z","steps":["trace[1231969716] 'agreement among raft nodes before linearized reading' (duration: 157.393192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:25:10.18823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.811362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T00:25:10.188257Z","caller":"traceutil/trace.go:171","msg":"trace[410961605] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1389; }","duration":"831.875184ms","start":"2026-07-02T00:25:09.356372Z","end":"2026-07-02T00:25:10.188248Z","steps":["trace[410961605] 'agreement among raft nodes before linearized reading' (duration: 831.823532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:25:10.188334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:25:09.356327Z","time spent":"831.996717ms","remote":"127.0.0.1:42160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-02T00:26:06.795987Z","caller":"traceutil/trace.go:171","msg":"trace[753937169] linearizableReadLoop","detail":"{readStateIndex:1719; appliedIndex:1717; }","duration":"113.434382ms","start":"2026-07-02T00:26:06.682525Z","end":"2026-07-02T00:26:06.79596Z","steps":["trace[753937169] 'read index received' (duration: 22.137603ms)","trace[753937169] 'applied index is now lower than readState.Index' (duration: 91.295669ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:26:06.796182Z","caller":"traceutil/trace.go:171","msg":"trace[1139679188] transaction","detail":"{read_only:false; response_revision:1652; number_of_response:1; }","duration":"119.281193ms","start":"2026-07-02T00:26:06.676761Z","end":"2026-07-02T00:26:06.796042Z","steps":["trace[1139679188] 'process raft request' (duration: 60.759924ms)","trace[1139679188] 'compare' (duration: 58.319344ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:26:06.796243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.680988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-02T00:26:06.796298Z","caller":"traceutil/trace.go:171","msg":"trace[1338160870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1652; }","duration":"113.74575ms","start":"2026-07-02T00:26:06.68254Z","end":"2026-07-02T00:26:06.796286Z","steps":["trace[1338160870] 'agreement among raft nodes before linearized reading' (duration: 113.585986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:26:06.797738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.189303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-02T00:26:06.797796Z","caller":"traceutil/trace.go:171","msg":"trace[1975273190] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1652; }","duration":"115.355197ms","start":"2026-07-02T00:26:06.682425Z","end":"2026-07-02T00:26:06.797781Z","steps":["trace[1975273190] 'agreement among raft nodes before linearized reading' (duration: 113.687528ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:26:20.979466Z","caller":"traceutil/trace.go:171","msg":"trace[111647603] transaction","detail":"{read_only:false; response_revision:1713; number_of_response:1; }","duration":"446.468901ms","start":"2026-07-02T00:26:20.532976Z","end":"2026-07-02T00:26:20.979445Z","steps":["trace[111647603] 'process raft request' (duration: 446.345038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:26:20.979602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:26:20.532952Z","time spent":"446.579374ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:26:20.979957Z","caller":"traceutil/trace.go:171","msg":"trace[1663368063] linearizableReadLoop","detail":"{readStateIndex:1783; appliedIndex:1783; }","duration":"389.338545ms","start":"2026-07-02T00:26:20.590606Z","end":"2026-07-02T00:26:20.979945Z","steps":["trace[1663368063] 'read index received' (duration: 389.334205ms)","trace[1663368063] 'applied index is now lower than readState.Index' (duration: 3.41µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:26:20.9801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.503094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T00:26:20.980134Z","caller":"traceutil/trace.go:171","msg":"trace[361190376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1713; }","duration":"248.576426ms","start":"2026-07-02T00:26:20.731549Z","end":"2026-07-02T00:26:20.980126Z","steps":["trace[361190376] 'agreement among raft nodes before linearized reading' (duration: 248.466003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:26:20.980349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.745534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T00:26:20.981295Z","caller":"traceutil/trace.go:171","msg":"trace[264843306] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1713; }","duration":"390.694987ms","start":"2026-07-02T00:26:20.59057Z","end":"2026-07-02T00:26:20.981265Z","steps":["trace[264843306] 'agreement among raft nodes before linearized reading' (duration: 389.719234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:26:20.980861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.370348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-02T00:26:20.981371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:26:20.590555Z","time spent":"390.799369ms","remote":"127.0.0.1:42246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T00:26:20.98141Z","caller":"traceutil/trace.go:171","msg":"trace[895366153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1713; }","duration":"125.948083ms","start":"2026-07-02T00:26:20.855441Z","end":"2026-07-02T00:26:20.981389Z","steps":["trace[895366153] 'agreement among raft nodes before linearized reading' (duration: 125.343228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:26:20.980954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.257101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:26:20.981514Z","caller":"traceutil/trace.go:171","msg":"trace[470351467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1713; }","duration":"217.835605ms","start":"2026-07-02T00:26:20.763651Z","end":"2026-07-02T00:26:20.981487Z","steps":["trace[470351467] 'agreement among raft nodes before linearized reading' (duration: 217.21973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:26:20.981099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.530388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T00:26:20.981599Z","caller":"traceutil/trace.go:171","msg":"trace[1155586246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1713; }","duration":"218.053181ms","start":"2026-07-02T00:26:20.76353Z","end":"2026-07-02T00:26:20.981584Z","steps":["trace[1155586246] 'agreement among raft nodes before linearized reading' (duration: 217.23292ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:27:05.256914Z","caller":"traceutil/trace.go:171","msg":"trace[1403180830] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"138.012616ms","start":"2026-07-02T00:27:05.118871Z","end":"2026-07-02T00:27:05.256884Z","steps":["trace[1403180830] 'process raft request' (duration: 137.520125ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:29:13.151186Z","caller":"traceutil/trace.go:171","msg":"trace[441356228] transaction","detail":"{read_only:false; response_revision:2537; number_of_response:1; }","duration":"197.325894ms","start":"2026-07-02T00:29:12.953842Z","end":"2026-07-02T00:29:13.151168Z","steps":["trace[441356228] 'process raft request' (duration: 197.220912ms)"],"step_count":1}