{"level":"warn","ts":"2026-07-21T20:51:29.798375Z","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-21T20:51:29.798464Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.133: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.19.213.133:2380","--initial-cluster=instance=https://199.19.213.133:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.133:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.133: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-21T20:51:29.798526Z","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-21T20:51:29.798538Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.133:2380"]} {"level":"info","ts":"2026-07-21T20:51:29.798557Z","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-21T20:51:29.799157Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.133:2379"]} {"level":"info","ts":"2026-07-21T20:51:29.799231Z","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.19.213.133:2380"],"listen-peer-urls":["https://199.19.213.133:2380"],"advertise-client-urls":["https://199.19.213.133:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.133:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.133: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-21T20:51:29.804693Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.249917ms"} {"level":"info","ts":"2026-07-21T20:51:29.814611Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"21b354698260dd82","cluster-id":"b778216e1db811d5"} {"level":"info","ts":"2026-07-21T20:51:29.814683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T20:51:29.814701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became follower at term 0"} {"level":"info","ts":"2026-07-21T20:51:29.814719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 21b354698260dd82 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T20:51:29.814728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became follower at term 1"} {"level":"info","ts":"2026-07-21T20:51:29.814768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 switched to configuration voters=(2428377436218514818)"} {"level":"warn","ts":"2026-07-21T20:51:29.819668Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T20:51:29.821411Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T20:51:29.822585Z","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-21T20:51:29.824558Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"21b354698260dd82","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T20:51:29.824713Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"21b354698260dd82","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T20:51:29.824932Z","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-21T20:51:29.825016Z","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-21T20:51:29.825029Z","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-21T20:51:29.828617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 switched to configuration voters=(2428377436218514818)"} {"level":"info","ts":"2026-07-21T20:51:29.828761Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b778216e1db811d5","local-member-id":"21b354698260dd82","added-peer-id":"21b354698260dd82","added-peer-peer-urls":["https://199.19.213.133:2380"]} {"level":"info","ts":"2026-07-21T20:51:29.83059Z","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-21T20:51:29.830772Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.133:2380"} {"level":"info","ts":"2026-07-21T20:51:29.830802Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.133:2380"} {"level":"info","ts":"2026-07-21T20:51:29.831238Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"21b354698260dd82","initial-advertise-peer-urls":["https://199.19.213.133:2380"],"listen-peer-urls":["https://199.19.213.133:2380"],"advertise-client-urls":["https://199.19.213.133:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.133:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T20:51:29.831284Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T20:51:30.416402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T20:51:30.416453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T20:51:30.416477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 received MsgPreVoteResp from 21b354698260dd82 at term 1"} {"level":"info","ts":"2026-07-21T20:51:30.416489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became candidate at term 2"} {"level":"info","ts":"2026-07-21T20:51:30.416495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 received MsgVoteResp from 21b354698260dd82 at term 2"} {"level":"info","ts":"2026-07-21T20:51:30.416504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became leader at term 2"} {"level":"info","ts":"2026-07-21T20:51:30.416516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 21b354698260dd82 elected leader 21b354698260dd82 at term 2"} {"level":"info","ts":"2026-07-21T20:51:30.424522Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"21b354698260dd82","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.133:2379]}","request-path":"/0/members/21b354698260dd82/attributes","cluster-id":"b778216e1db811d5","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T20:51:30.424639Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T20:51:30.424697Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T20:51:30.424801Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:51:30.425948Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b778216e1db811d5","local-member-id":"21b354698260dd82","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:51:30.426038Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:51:30.426091Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:51:30.427828Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.133:2379"} {"level":"info","ts":"2026-07-21T20:51:30.428407Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T20:51:30.428437Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T20:51:30.430382Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T20:51:32.878521Z","caller":"traceutil/trace.go:171","msg":"trace[1881393643] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"181.431324ms","start":"2026-07-21T20:51:32.697065Z","end":"2026-07-21T20:51:32.878496Z","steps":["trace[1881393643] 'read index received' (duration: 181.189818ms)","trace[1881393643] 'applied index is now lower than readState.Index' (duration: 238.286µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:51:32.878591Z","caller":"traceutil/trace.go:171","msg":"trace[96611881] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"182.305819ms","start":"2026-07-21T20:51:32.696246Z","end":"2026-07-21T20:51:32.878552Z","steps":["trace[96611881] 'process raft request' (duration: 182.097103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:32.878769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.64012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:51:32.878865Z","caller":"traceutil/trace.go:171","msg":"trace[1762016645] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:112; }","duration":"181.801945ms","start":"2026-07-21T20:51:32.697047Z","end":"2026-07-21T20:51:32.878849Z","steps":["trace[1762016645] 'agreement among raft nodes before linearized reading' (duration: 181.595768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:51:33.089768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.712824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-21T20:51:33.089946Z","caller":"traceutil/trace.go:171","msg":"trace[1689454127] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:117; }","duration":"204.241125ms","start":"2026-07-21T20:51:32.885685Z","end":"2026-07-21T20:51:33.089927Z","steps":["trace[1689454127] 'read index received' (duration: 73.721729ms)","trace[1689454127] 'applied index is now lower than readState.Index' (duration: 130.517936ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:51:33.090045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.349698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:51:33.090071Z","caller":"traceutil/trace.go:171","msg":"trace[1746253646] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:113; }","duration":"204.419459ms","start":"2026-07-21T20:51:32.885642Z","end":"2026-07-21T20:51:33.090061Z","steps":["trace[1746253646] 'agreement among raft nodes before linearized reading' (duration: 204.335317ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:51:33.090026Z","caller":"traceutil/trace.go:171","msg":"trace[630596000] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"205.88261ms","start":"2026-07-21T20:51:32.884093Z","end":"2026-07-21T20:51:33.089976Z","steps":["trace[630596000] 'process raft request' (duration: 75.377585ms)","trace[630596000] 'compare' (duration: 129.5615ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:52:10.198359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.908124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:52:10.198867Z","caller":"traceutil/trace.go:171","msg":"trace[636422597] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"241.898949ms","start":"2026-07-21T20:52:09.956947Z","end":"2026-07-21T20:52:10.198846Z","steps":["trace[636422597] 'process raft request' (duration: 44.28449ms)","trace[636422597] 'compare' (duration: 196.744291ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:13.895791Z","caller":"traceutil/trace.go:171","msg":"trace[850849845] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"252.002474ms","start":"2026-07-21T20:52:13.643761Z","end":"2026-07-21T20:52:13.895763Z","steps":["trace[850849845] 'process raft request' (duration: 251.725108ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:14.281039Z","caller":"traceutil/trace.go:171","msg":"trace[1252414530] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:653; }","duration":"479.263593ms","start":"2026-07-21T20:52:13.80171Z","end":"2026-07-21T20:52:14.280974Z","steps":["trace[1252414530] 'read index received' (duration: 93.818405ms)","trace[1252414530] 'applied index is now lower than readState.Index' (duration: 385.444178ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:14.281121Z","caller":"traceutil/trace.go:171","msg":"trace[1683022006] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"608.347625ms","start":"2026-07-21T20:52:13.67274Z","end":"2026-07-21T20:52:14.281087Z","steps":["trace[1683022006] 'process raft request' (duration: 607.698132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:14.281204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.478877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.133\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T20:52:14.281263Z","caller":"traceutil/trace.go:171","msg":"trace[863214575] range","detail":"{range_begin:/registry/masterleases/199.19.213.133; range_end:; response_count:1; response_revision:632; }","duration":"479.545398ms","start":"2026-07-21T20:52:13.801697Z","end":"2026-07-21T20:52:14.281242Z","steps":["trace[863214575] 'agreement among raft nodes before linearized reading' (duration: 479.444986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:14.281307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:13.80163Z","time spent":"479.66841ms","remote":"127.0.0.1:37744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.133\" "} {"level":"warn","ts":"2026-07-21T20:52:14.281287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:13.672698Z","time spent":"608.497578ms","remote":"127.0.0.1:37798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:52:14.281685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.834411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-21T20:52:14.281701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.110753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:52:14.281987Z","caller":"traceutil/trace.go:171","msg":"trace[215476357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"283.45899ms","start":"2026-07-21T20:52:13.998512Z","end":"2026-07-21T20:52:14.281971Z","steps":["trace[215476357] 'agreement among raft nodes before linearized reading' (duration: 283.144474ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:14.281945Z","caller":"traceutil/trace.go:171","msg":"trace[577150246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"316.155397ms","start":"2026-07-21T20:52:13.965755Z","end":"2026-07-21T20:52:14.28191Z","steps":["trace[577150246] 'agreement among raft nodes before linearized reading' (duration: 315.668407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:14.282642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:52:13.965732Z","time spent":"316.888632ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T20:52:14.540218Z","caller":"traceutil/trace.go:171","msg":"trace[1099691071] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"256.902047ms","start":"2026-07-21T20:52:14.283279Z","end":"2026-07-21T20:52:14.540181Z","steps":["trace[1099691071] 'read index received' (duration: 194.355989ms)","trace[1099691071] 'applied index is now lower than readState.Index' (duration: 62.543748ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:52:14.540542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.229713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:52:14.540628Z","caller":"traceutil/trace.go:171","msg":"trace[1074955777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"257.365515ms","start":"2026-07-21T20:52:14.28325Z","end":"2026-07-21T20:52:14.540615Z","steps":["trace[1074955777] 'agreement among raft nodes before linearized reading' (duration: 257.216292ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:14.543091Z","caller":"traceutil/trace.go:171","msg":"trace[1287457783] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"256.927288ms","start":"2026-07-21T20:52:14.286148Z","end":"2026-07-21T20:52:14.543075Z","steps":["trace[1287457783] 'process raft request' (duration: 256.416718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:14.543299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.08104ms","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-21T20:52:14.543422Z","caller":"traceutil/trace.go:171","msg":"trace[543389196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"203.251873ms","start":"2026-07-21T20:52:14.340151Z","end":"2026-07-21T20:52:14.543403Z","steps":["trace[543389196] 'agreement among raft nodes before linearized reading' (duration: 202.849665ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:14.543581Z","caller":"traceutil/trace.go:171","msg":"trace[1797724497] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"202.983268ms","start":"2026-07-21T20:52:14.34058Z","end":"2026-07-21T20:52:14.543563Z","steps":["trace[1797724497] 'process raft request' (duration: 202.316625ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:14.543318Z","caller":"traceutil/trace.go:171","msg":"trace[608439497] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"254.9957ms","start":"2026-07-21T20:52:14.288303Z","end":"2026-07-21T20:52:14.543299Z","steps":["trace[608439497] 'process raft request' (duration: 254.432929ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:14.543363Z","caller":"traceutil/trace.go:171","msg":"trace[441444059] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"254.95806ms","start":"2026-07-21T20:52:14.288387Z","end":"2026-07-21T20:52:14.543345Z","steps":["trace[441444059] 'process raft request' (duration: 254.394239ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:37.194336Z","caller":"traceutil/trace.go:171","msg":"trace[1665908356] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"109.548774ms","start":"2026-07-21T20:53:37.084757Z","end":"2026-07-21T20:53:37.194305Z","steps":["trace[1665908356] 'process raft request' (duration: 109.321111ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:41.568629Z","caller":"traceutil/trace.go:171","msg":"trace[1728707924] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"140.438309ms","start":"2026-07-21T20:53:41.428159Z","end":"2026-07-21T20:53:41.568597Z","steps":["trace[1728707924] 'process raft request' (duration: 111.407784ms)","trace[1728707924] 'compare' (duration: 28.886613ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:07.399215Z","caller":"traceutil/trace.go:171","msg":"trace[1674196000] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"126.596424ms","start":"2026-07-21T20:54:07.272578Z","end":"2026-07-21T20:54:07.399174Z","steps":["trace[1674196000] 'process raft request' (duration: 86.616027ms)","trace[1674196000] 'compare' (duration: 39.860665ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:07.697868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.325214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:07.698105Z","caller":"traceutil/trace.go:171","msg":"trace[1654604608] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"217.12432ms","start":"2026-07-21T20:54:07.480949Z","end":"2026-07-21T20:54:07.698074Z","steps":["trace[1654604608] 'process raft request' (duration: 37.501793ms)","trace[1654604608] 'compare' (duration: 179.05525ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:07.698186Z","caller":"traceutil/trace.go:171","msg":"trace[1093117266] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"216.977609ms","start":"2026-07-21T20:54:07.481186Z","end":"2026-07-21T20:54:07.698163Z","steps":["trace[1093117266] 'process raft request' (duration: 216.790647ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:07.698209Z","caller":"traceutil/trace.go:171","msg":"trace[1739672827] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"211.144814ms","start":"2026-07-21T20:54:07.48704Z","end":"2026-07-21T20:54:07.698184Z","steps":["trace[1739672827] 'process raft request' (duration: 211.025242ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:07.698127Z","caller":"traceutil/trace.go:171","msg":"trace[876596852] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1304; }","duration":"211.045163ms","start":"2026-07-21T20:54:07.48706Z","end":"2026-07-21T20:54:07.698105Z","steps":["trace[876596852] 'read index received' (duration: 31.448086ms)","trace[876596852] 'applied index is now lower than readState.Index' (duration: 179.595887ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:07.698449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.374227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:07.69852Z","caller":"traceutil/trace.go:171","msg":"trace[2040418669] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:1257; }","duration":"211.470218ms","start":"2026-07-21T20:54:07.487031Z","end":"2026-07-21T20:54:07.698502Z","steps":["trace[2040418669] 'agreement among raft nodes before linearized reading' (duration: 211.131413ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:07.761944Z","caller":"traceutil/trace.go:171","msg":"trace[1359345888] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"193.708749ms","start":"2026-07-21T20:54:07.568202Z","end":"2026-07-21T20:54:07.761911Z","steps":["trace[1359345888] 'process raft request' (duration: 193.389625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:07.762149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.671862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:54:07.762226Z","caller":"traceutil/trace.go:171","msg":"trace[549444742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1258; }","duration":"187.806923ms","start":"2026-07-21T20:54:07.574398Z","end":"2026-07-21T20:54:07.762205Z","steps":["trace[549444742] 'agreement among raft nodes before linearized reading' (duration: 187.525099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:07.762207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.915594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T20:54:07.76233Z","caller":"traceutil/trace.go:171","msg":"trace[759435948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1258; }","duration":"188.093456ms","start":"2026-07-21T20:54:07.574208Z","end":"2026-07-21T20:54:07.762301Z","steps":["trace[759435948] 'agreement among raft nodes before linearized reading' (duration: 187.665351ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:08.505326Z","caller":"traceutil/trace.go:171","msg":"trace[1051777715] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"150.520969ms","start":"2026-07-21T20:54:08.354575Z","end":"2026-07-21T20:54:08.505096Z","steps":["trace[1051777715] 'process raft request' (duration: 150.335237ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:09.026058Z","caller":"traceutil/trace.go:171","msg":"trace[1042698726] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"111.81843ms","start":"2026-07-21T20:54:08.914212Z","end":"2026-07-21T20:54:09.02603Z","steps":["trace[1042698726] 'process raft request' (duration: 111.584107ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:10.012538Z","caller":"traceutil/trace.go:171","msg":"trace[1943245073] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1322; }","duration":"140.025539ms","start":"2026-07-21T20:54:09.872493Z","end":"2026-07-21T20:54:10.012518Z","steps":["trace[1943245073] 'read index received' (duration: 25.316815ms)","trace[1943245073] 'applied index is now lower than readState.Index' (duration: 114.707924ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:10.012689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.188502ms","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-21T20:54:10.012716Z","caller":"traceutil/trace.go:171","msg":"trace[749119123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1273; }","duration":"140.251614ms","start":"2026-07-21T20:54:09.872456Z","end":"2026-07-21T20:54:10.012708Z","steps":["trace[749119123] 'agreement among raft nodes before linearized reading' (duration: 140.158253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:10.012868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.036086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-541206b8-fef3-46e1-9ace-07fcb10cdb34\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-07-21T20:54:10.012944Z","caller":"traceutil/trace.go:171","msg":"trace[395926544] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-541206b8-fef3-46e1-9ace-07fcb10cdb34; range_end:; response_count:1; response_revision:1273; }","duration":"100.142617ms","start":"2026-07-21T20:54:09.912784Z","end":"2026-07-21T20:54:10.012927Z","steps":["trace[395926544] 'agreement among raft nodes before linearized reading' (duration: 99.945445ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:10.01318Z","caller":"traceutil/trace.go:171","msg":"trace[545112745] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"147.508737ms","start":"2026-07-21T20:54:09.86559Z","end":"2026-07-21T20:54:10.013099Z","steps":["trace[545112745] 'process raft request' (duration: 126.339445ms)","trace[545112745] 'compare' (duration: 20.503703ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:12.123167Z","caller":"traceutil/trace.go:171","msg":"trace[679088843] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"125.870561ms","start":"2026-07-21T20:54:11.997273Z","end":"2026-07-21T20:54:12.123143Z","steps":["trace[679088843] 'read index received' (duration: 54.434757ms)","trace[679088843] 'applied index is now lower than readState.Index' (duration: 71.434884ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:12.123227Z","caller":"traceutil/trace.go:171","msg":"trace[1288683611] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"179.026383ms","start":"2026-07-21T20:54:11.944152Z","end":"2026-07-21T20:54:12.123178Z","steps":["trace[1288683611] 'process raft request' (duration: 107.537398ms)","trace[1288683611] 'compare' (duration: 71.304193ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:12.12339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.117094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:12.123447Z","caller":"traceutil/trace.go:171","msg":"trace[1916858648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"126.228486ms","start":"2026-07-21T20:54:11.997205Z","end":"2026-07-21T20:54:12.123434Z","steps":["trace[1916858648] 'agreement among raft nodes before linearized reading' (duration: 126.016093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:12.123595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.026896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-541206b8-fef3-46e1-9ace-07fcb10cdb34\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-07-21T20:54:12.123649Z","caller":"traceutil/trace.go:171","msg":"trace[421431107] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-541206b8-fef3-46e1-9ace-07fcb10cdb34; range_end:; response_count:1; response_revision:1282; }","duration":"102.130916ms","start":"2026-07-21T20:54:12.021502Z","end":"2026-07-21T20:54:12.123632Z","steps":["trace[421431107] 'agreement among raft nodes before linearized reading' (duration: 101.978935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:12.123971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.461635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:54:12.124009Z","caller":"traceutil/trace.go:171","msg":"trace[611760618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1282; }","duration":"107.568386ms","start":"2026-07-21T20:54:12.016433Z","end":"2026-07-21T20:54:12.124002Z","steps":["trace[611760618] 'agreement among raft nodes before linearized reading' (duration: 106.877387ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:12.334714Z","caller":"traceutil/trace.go:171","msg":"trace[1998322027] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"206.562142ms","start":"2026-07-21T20:54:12.128121Z","end":"2026-07-21T20:54:12.334683Z","steps":["trace[1998322027] 'process raft request' (duration: 140.631549ms)","trace[1998322027] 'compare' (duration: 65.800842ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:12.384596Z","caller":"traceutil/trace.go:171","msg":"trace[757692545] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1334; }","duration":"103.583836ms","start":"2026-07-21T20:54:12.280987Z","end":"2026-07-21T20:54:12.38457Z","steps":["trace[757692545] 'read index received' (duration: 103.385913ms)","trace[757692545] 'applied index is now lower than readState.Index' (duration: 196.763µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:12.384845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.839759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-07-21T20:54:12.384888Z","caller":"traceutil/trace.go:171","msg":"trace[1617790334] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1285; }","duration":"103.90164ms","start":"2026-07-21T20:54:12.280974Z","end":"2026-07-21T20:54:12.384876Z","steps":["trace[1617790334] 'agreement among raft nodes before linearized reading' (duration: 103.727948ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:12.384989Z","caller":"traceutil/trace.go:171","msg":"trace[1101965386] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"255.581961ms","start":"2026-07-21T20:54:12.129364Z","end":"2026-07-21T20:54:12.384946Z","steps":["trace[1101965386] 'process raft request' (duration: 254.978403ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:12.385042Z","caller":"traceutil/trace.go:171","msg":"trace[517236709] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"254.840771ms","start":"2026-07-21T20:54:12.130178Z","end":"2026-07-21T20:54:12.385019Z","steps":["trace[517236709] 'process raft request' (duration: 254.341115ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:15.34079Z","caller":"traceutil/trace.go:171","msg":"trace[454330028] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"219.170743ms","start":"2026-07-21T20:54:15.121596Z","end":"2026-07-21T20:54:15.340766Z","steps":["trace[454330028] 'read index received' (duration: 218.99821ms)","trace[454330028] 'applied index is now lower than readState.Index' (duration: 171.423µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:15.340878Z","caller":"traceutil/trace.go:171","msg":"trace[1059406591] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"221.981149ms","start":"2026-07-21T20:54:15.118859Z","end":"2026-07-21T20:54:15.34084Z","steps":["trace[1059406591] 'process raft request' (duration: 221.636744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:15.341007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.3817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-541206b8-fef3-46e1-9ace-07fcb10cdb34\" ","response":"range_response_count:1 size:3884"} {"level":"info","ts":"2026-07-21T20:54:15.341067Z","caller":"traceutil/trace.go:171","msg":"trace[678236055] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-541206b8-fef3-46e1-9ace-07fcb10cdb34; range_end:; response_count:1; response_revision:1295; }","duration":"122.499261ms","start":"2026-07-21T20:54:15.218549Z","end":"2026-07-21T20:54:15.341048Z","steps":["trace[678236055] 'agreement among raft nodes before linearized reading' (duration: 122.380609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:15.341166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.535298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-21T20:54:15.34127Z","caller":"traceutil/trace.go:171","msg":"trace[1510941434] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1295; }","duration":"219.676119ms","start":"2026-07-21T20:54:15.12157Z","end":"2026-07-21T20:54:15.341246Z","steps":["trace[1510941434] 'agreement among raft nodes before linearized reading' (duration: 219.382185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:15.341155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.895743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:15.341497Z","caller":"traceutil/trace.go:171","msg":"trace[239280832] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1295; }","duration":"104.294238ms","start":"2026-07-21T20:54:15.237181Z","end":"2026-07-21T20:54:15.341475Z","steps":["trace[239280832] 'agreement among raft nodes before linearized reading' (duration: 103.868263ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:15.587098Z","caller":"traceutil/trace.go:171","msg":"trace[1998351678] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"156.437542ms","start":"2026-07-21T20:54:15.43062Z","end":"2026-07-21T20:54:15.587058Z","steps":["trace[1998351678] 'process raft request' (duration: 121.837302ms)","trace[1998351678] 'compare' (duration: 34.437048ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:15.797754Z","caller":"traceutil/trace.go:171","msg":"trace[2146019270] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"134.482924ms","start":"2026-07-21T20:54:15.663238Z","end":"2026-07-21T20:54:15.797721Z","steps":["trace[2146019270] 'process raft request' (duration: 43.1555ms)","trace[2146019270] 'compare' (duration: 91.06744ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:16.19137Z","caller":"traceutil/trace.go:171","msg":"trace[840983066] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"388.826221ms","start":"2026-07-21T20:54:15.802511Z","end":"2026-07-21T20:54:16.191337Z","steps":["trace[840983066] 'process raft request' (duration: 388.75022ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:16.191417Z","caller":"traceutil/trace.go:171","msg":"trace[1783784039] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"389.071193ms","start":"2026-07-21T20:54:15.802309Z","end":"2026-07-21T20:54:16.19138Z","steps":["trace[1783784039] 'process raft request' (duration: 388.78478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:16.19157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:15.802486Z","time spent":"388.992662ms","remote":"127.0.0.1:37786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":752,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:54:16.191608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:15.802291Z","time spent":"389.204786ms","remote":"127.0.0.1:37852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1240,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:54:16.254516Z","caller":"traceutil/trace.go:171","msg":"trace[1738676797] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1353; }","duration":"235.747359ms","start":"2026-07-21T20:54:16.018707Z","end":"2026-07-21T20:54:16.254455Z","steps":["trace[1738676797] 'read index received' (duration: 172.429343ms)","trace[1738676797] 'applied index is now lower than readState.Index' (duration: 63.317056ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:16.254772Z","caller":"traceutil/trace.go:171","msg":"trace[407084773] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"358.779606ms","start":"2026-07-21T20:54:15.895955Z","end":"2026-07-21T20:54:16.254735Z","steps":["trace[407084773] 'process raft request' (duration: 358.2997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:16.255008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:15.895934Z","time spent":"358.973368ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:54:16.254776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.044432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T20:54:16.255222Z","caller":"traceutil/trace.go:171","msg":"trace[1883903711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1304; }","duration":"236.548179ms","start":"2026-07-21T20:54:16.018653Z","end":"2026-07-21T20:54:16.255202Z","steps":["trace[1883903711] 'agreement among raft nodes before linearized reading' (duration: 235.966232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:16.42321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.034946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:16.423465Z","caller":"traceutil/trace.go:171","msg":"trace[1554586166] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"164.019637ms","start":"2026-07-21T20:54:16.259427Z","end":"2026-07-21T20:54:16.423447Z","steps":["trace[1554586166] 'process raft request' (duration: 163.956036ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:16.423496Z","caller":"traceutil/trace.go:171","msg":"trace[1821293387] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1356; }","duration":"168.892108ms","start":"2026-07-21T20:54:16.254585Z","end":"2026-07-21T20:54:16.423477Z","steps":["trace[1821293387] 'read index received' (duration: 52.514927ms)","trace[1821293387] 'applied index is now lower than readState.Index' (duration: 116.375031ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:16.423711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.142887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:16.423781Z","caller":"traceutil/trace.go:171","msg":"trace[839059591] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1306; }","duration":"197.254429ms","start":"2026-07-21T20:54:16.226515Z","end":"2026-07-21T20:54:16.42377Z","steps":["trace[839059591] 'agreement among raft nodes before linearized reading' (duration: 197.156928ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:16.423864Z","caller":"traceutil/trace.go:171","msg":"trace[950489788] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"222.950886ms","start":"2026-07-21T20:54:16.200788Z","end":"2026-07-21T20:54:16.423739Z","steps":["trace[950489788] 'process raft request' (duration: 106.303992ms)","trace[950489788] 'compare' (duration: 115.926355ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:16.42382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.167802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:16.423938Z","caller":"traceutil/trace.go:171","msg":"trace[1518210556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1306; }","duration":"349.312294ms","start":"2026-07-21T20:54:16.074607Z","end":"2026-07-21T20:54:16.423919Z","steps":["trace[1518210556] 'agreement among raft nodes before linearized reading' (duration: 349.107802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:16.424025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:16.074599Z","time spent":"349.413615ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T20:54:16.699531Z","caller":"traceutil/trace.go:171","msg":"trace[121202668] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"271.737686ms","start":"2026-07-21T20:54:16.427765Z","end":"2026-07-21T20:54:16.699502Z","steps":["trace[121202668] 'process raft request' (duration: 270.659072ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:16.703094Z","caller":"traceutil/trace.go:171","msg":"trace[956142269] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"273.550811ms","start":"2026-07-21T20:54:16.429519Z","end":"2026-07-21T20:54:16.70307Z","steps":["trace[956142269] 'process raft request' (duration: 273.50361ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:16.703113Z","caller":"traceutil/trace.go:171","msg":"trace[272852528] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"274.017866ms","start":"2026-07-21T20:54:16.429073Z","end":"2026-07-21T20:54:16.703091Z","steps":["trace[272852528] 'process raft request' (duration: 273.830894ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:17.631296Z","caller":"traceutil/trace.go:171","msg":"trace[668246553] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1362; }","duration":"145.352016ms","start":"2026-07-21T20:54:17.485924Z","end":"2026-07-21T20:54:17.631276Z","steps":["trace[668246553] 'read index received' (duration: 28.636084ms)","trace[668246553] 'applied index is now lower than readState.Index' (duration: 116.714862ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:17.631342Z","caller":"traceutil/trace.go:171","msg":"trace[837382420] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"145.930154ms","start":"2026-07-21T20:54:17.485374Z","end":"2026-07-21T20:54:17.631304Z","steps":["trace[837382420] 'process raft request' (duration: 81.004529ms)","trace[837382420] 'compare' (duration: 64.814463ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:17.631487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.545549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-07-21T20:54:17.631533Z","caller":"traceutil/trace.go:171","msg":"trace[2101301170] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1312; }","duration":"145.63116ms","start":"2026-07-21T20:54:17.485893Z","end":"2026-07-21T20:54:17.631524Z","steps":["trace[2101301170] 'agreement among raft nodes before linearized reading' (duration: 145.495429ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:17.942686Z","caller":"traceutil/trace.go:171","msg":"trace[1382174585] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"211.624177ms","start":"2026-07-21T20:54:17.731039Z","end":"2026-07-21T20:54:17.942663Z","steps":["trace[1382174585] 'read index received' (duration: 180.007266ms)","trace[1382174585] 'applied index is now lower than readState.Index' (duration: 31.615641ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:17.942777Z","caller":"traceutil/trace.go:171","msg":"trace[1563176282] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"304.604958ms","start":"2026-07-21T20:54:17.638138Z","end":"2026-07-21T20:54:17.942743Z","steps":["trace[1563176282] 'process raft request' (duration: 272.796075ms)","trace[1563176282] 'compare' (duration: 31.617251ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:17.942979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.931561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"warn","ts":"2026-07-21T20:54:17.943488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.618838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"warn","ts":"2026-07-21T20:54:17.943512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:17.638125Z","time spent":"305.259717ms","remote":"127.0.0.1:37892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:54:17.943529Z","caller":"traceutil/trace.go:171","msg":"trace[191717765] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1313; }","duration":"212.494769ms","start":"2026-07-21T20:54:17.731022Z","end":"2026-07-21T20:54:17.943516Z","steps":["trace[191717765] 'agreement among raft nodes before linearized reading' (duration: 211.785749ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:17.943538Z","caller":"traceutil/trace.go:171","msg":"trace[2101995966] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1313; }","duration":"111.75431ms","start":"2026-07-21T20:54:17.831771Z","end":"2026-07-21T20:54:17.943526Z","steps":["trace[2101995966] 'agreement among raft nodes before linearized reading' (duration: 111.625069ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:18.650389Z","caller":"traceutil/trace.go:171","msg":"trace[905174381] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"205.928853ms","start":"2026-07-21T20:54:18.444435Z","end":"2026-07-21T20:54:18.650364Z","steps":["trace[905174381] 'process raft request' (duration: 141.435755ms)","trace[905174381] 'compare' (duration: 64.384586ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:18.884609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.953489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:18.884916Z","caller":"traceutil/trace.go:171","msg":"trace[838842454] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"173.50159ms","start":"2026-07-21T20:54:18.711363Z","end":"2026-07-21T20:54:18.884864Z","steps":["trace[838842454] 'process raft request' (duration: 173.401239ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:18.884905Z","caller":"traceutil/trace.go:171","msg":"trace[1567553272] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"174.99409ms","start":"2026-07-21T20:54:18.709871Z","end":"2026-07-21T20:54:18.884865Z","steps":["trace[1567553272] 'process raft request' (duration: 51.652856ms)","trace[1567553272] 'compare' (duration: 122.738816ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:19.326353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.111697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:19.326429Z","caller":"traceutil/trace.go:171","msg":"trace[1916096529] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1372; }","duration":"327.052272ms","start":"2026-07-21T20:54:18.999359Z","end":"2026-07-21T20:54:19.326412Z","steps":["trace[1916096529] 'read index received' (duration: 50.743433ms)","trace[1916096529] 'applied index is now lower than readState.Index' (duration: 276.307999ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:19.326469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.111733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:19.326485Z","caller":"traceutil/trace.go:171","msg":"trace[499138643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"327.168454ms","start":"2026-07-21T20:54:18.99931Z","end":"2026-07-21T20:54:19.326478Z","steps":["trace[499138643] 'agreement among raft nodes before linearized reading' (duration: 327.139294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:19.326509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:18.999295Z","time spent":"327.209064ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T20:54:19.326629Z","caller":"traceutil/trace.go:171","msg":"trace[255953831] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"332.183398ms","start":"2026-07-21T20:54:18.994436Z","end":"2026-07-21T20:54:19.32662Z","steps":["trace[255953831] 'process raft request' (duration: 55.629835ms)","trace[255953831] 'compare' (duration: 275.827624ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:19.326995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:18.994411Z","time spent":"332.238428ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:54:19.448596Z","caller":"traceutil/trace.go:171","msg":"trace[1070442902] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"120.754019ms","start":"2026-07-21T20:54:19.327819Z","end":"2026-07-21T20:54:19.448573Z","steps":["trace[1070442902] 'read index received' (duration: 44.086648ms)","trace[1070442902] 'applied index is now lower than readState.Index' (duration: 76.666311ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:19.448776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.934702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:19.448851Z","caller":"traceutil/trace.go:171","msg":"trace[1343811606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"121.062533ms","start":"2026-07-21T20:54:19.32777Z","end":"2026-07-21T20:54:19.448833Z","steps":["trace[1343811606] 'agreement among raft nodes before linearized reading' (duration: 120.921972ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:20.196057Z","caller":"traceutil/trace.go:171","msg":"trace[949637661] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"207.221272ms","start":"2026-07-21T20:54:19.988783Z","end":"2026-07-21T20:54:20.196005Z","steps":["trace[949637661] 'process raft request' (duration: 206.720086ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:20.596032Z","caller":"traceutil/trace.go:171","msg":"trace[935629265] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"118.855673ms","start":"2026-07-21T20:54:20.477136Z","end":"2026-07-21T20:54:20.595991Z","steps":["trace[935629265] 'process raft request' (duration: 22.91141ms)","trace[935629265] 'compare' (duration: 95.738221ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:21.028406Z","caller":"traceutil/trace.go:171","msg":"trace[115991634] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"135.106498ms","start":"2026-07-21T20:54:20.893265Z","end":"2026-07-21T20:54:21.028371Z","steps":["trace[115991634] 'process raft request' (duration: 134.727624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:21.348661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.193721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:21.348759Z","caller":"traceutil/trace.go:171","msg":"trace[1779817262] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1379; }","duration":"350.166712ms","start":"2026-07-21T20:54:20.998576Z","end":"2026-07-21T20:54:21.348743Z","steps":["trace[1779817262] 'read index received' (duration: 29.673355ms)","trace[1779817262] 'applied index is now lower than readState.Index' (duration: 320.491867ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:21.348861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.254763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:21.348844Z","caller":"traceutil/trace.go:171","msg":"trace[406159794] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"454.880557ms","start":"2026-07-21T20:54:20.89393Z","end":"2026-07-21T20:54:21.34881Z","steps":["trace[406159794] 'process raft request' (duration: 291.070478ms)","trace[406159794] 'compare' (duration: 162.981299ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:21.348899Z","caller":"traceutil/trace.go:171","msg":"trace[536947244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"350.366145ms","start":"2026-07-21T20:54:20.998522Z","end":"2026-07-21T20:54:21.348888Z","steps":["trace[536947244] 'agreement among raft nodes before linearized reading' (duration: 350.262824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:21.348977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:20.998499Z","time spent":"350.467066ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:54:21.349005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:20.893901Z","time spent":"455.018208ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:54:22.763796Z","caller":"traceutil/trace.go:171","msg":"trace[836425675] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"157.465786ms","start":"2026-07-21T20:54:22.606302Z","end":"2026-07-21T20:54:22.763768Z","steps":["trace[836425675] 'process raft request' (duration: 157.280734ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:23.287015Z","caller":"traceutil/trace.go:171","msg":"trace[385802015] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"249.657335ms","start":"2026-07-21T20:54:23.037333Z","end":"2026-07-21T20:54:23.28699Z","steps":["trace[385802015] 'process raft request' (duration: 249.493753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:23.494634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.910135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:54:23.494723Z","caller":"traceutil/trace.go:171","msg":"trace[1045359800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1333; }","duration":"141.066737ms","start":"2026-07-21T20:54:23.353632Z","end":"2026-07-21T20:54:23.494698Z","steps":["trace[1045359800] 'range keys from in-memory index tree' (duration: 140.656831ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:24.56105Z","caller":"traceutil/trace.go:171","msg":"trace[870634223] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"149.896596ms","start":"2026-07-21T20:54:24.411128Z","end":"2026-07-21T20:54:24.561025Z","steps":["trace[870634223] 'process raft request' (duration: 149.753694ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:24.920395Z","caller":"traceutil/trace.go:171","msg":"trace[1137760224] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"146.116819ms","start":"2026-07-21T20:54:24.774249Z","end":"2026-07-21T20:54:24.920366Z","steps":["trace[1137760224] 'process raft request' (duration: 145.915666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:25.939374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.536149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:25.93949Z","caller":"traceutil/trace.go:171","msg":"trace[198397675] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1342; }","duration":"114.734242ms","start":"2026-07-21T20:54:25.824734Z","end":"2026-07-21T20:54:25.939468Z","steps":["trace[198397675] 'count revisions from in-memory index tree' (duration: 114.424468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:25.939801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.080473ms","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-21T20:54:25.939884Z","caller":"traceutil/trace.go:171","msg":"trace[53446225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1342; }","duration":"110.223595ms","start":"2026-07-21T20:54:25.829642Z","end":"2026-07-21T20:54:25.939865Z","steps":["trace[53446225] 'range keys from in-memory index tree' (duration: 109.8729ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:26.533869Z","caller":"traceutil/trace.go:171","msg":"trace[489938940] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"245.231377ms","start":"2026-07-21T20:54:26.28861Z","end":"2026-07-21T20:54:26.533841Z","steps":["trace[489938940] 'process raft request' (duration: 245.059325ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:26.722454Z","caller":"traceutil/trace.go:171","msg":"trace[735038329] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"155.636052ms","start":"2026-07-21T20:54:26.566787Z","end":"2026-07-21T20:54:26.722423Z","steps":["trace[735038329] 'process raft request' (duration: 97.422932ms)","trace[735038329] 'compare' (duration: 57.913036ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:27.887896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.789351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:54:27.88798Z","caller":"traceutil/trace.go:171","msg":"trace[261773875] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1348; }","duration":"103.913263ms","start":"2026-07-21T20:54:27.784048Z","end":"2026-07-21T20:54:27.887961Z","steps":["trace[261773875] 'count revisions from in-memory index tree' (duration: 103.70689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:28.51288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15961495429152743893,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T20:54:28.751197Z","caller":"traceutil/trace.go:171","msg":"trace[1720974659] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1402; }","duration":"738.429833ms","start":"2026-07-21T20:54:28.012747Z","end":"2026-07-21T20:54:28.751177Z","steps":["trace[1720974659] 'read index received' (duration: 738.281021ms)","trace[1720974659] 'applied index is now lower than readState.Index' (duration: 147.992µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:28.751248Z","caller":"traceutil/trace.go:171","msg":"trace[1052577828] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"747.896721ms","start":"2026-07-21T20:54:28.003326Z","end":"2026-07-21T20:54:28.751223Z","steps":["trace[1052577828] 'process raft request' (duration: 747.718999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:28.75135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.583785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:54:28.751382Z","caller":"traceutil/trace.go:171","msg":"trace[1328725323] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1349; }","duration":"738.674107ms","start":"2026-07-21T20:54:28.0127Z","end":"2026-07-21T20:54:28.751374Z","steps":["trace[1328725323] 'agreement among raft nodes before linearized reading' (duration: 738.575535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:28.751425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:28.012681Z","time spent":"738.737157ms","remote":"127.0.0.1:54398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-21T20:54:28.751493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:28.003302Z","time spent":"748.054243ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:54:29.159046Z","caller":"traceutil/trace.go:171","msg":"trace[451028666] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"407.75915ms","start":"2026-07-21T20:54:28.751251Z","end":"2026-07-21T20:54:29.15901Z","steps":["trace[451028666] 'read index received' (duration: 407.498857ms)","trace[451028666] 'applied index is now lower than readState.Index' (duration: 258.483µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:29.159226Z","caller":"traceutil/trace.go:171","msg":"trace[1883933705] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"431.531248ms","start":"2026-07-21T20:54:28.727654Z","end":"2026-07-21T20:54:29.159186Z","steps":["trace[1883933705] 'process raft request' (duration: 431.055363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:29.159393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:28.72763Z","time spent":"431.631829ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:54:29.159477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.737479ms","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-21T20:54:29.159554Z","caller":"traceutil/trace.go:171","msg":"trace[1882784654] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1350; }","duration":"621.866441ms","start":"2026-07-21T20:54:28.537666Z","end":"2026-07-21T20:54:29.159533Z","steps":["trace[1882784654] 'agreement among raft nodes before linearized reading' (duration: 621.647148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:29.159596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:28.537646Z","time spent":"621.942271ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T20:54:30.294522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092861998s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-21T20:54:30.294788Z","caller":"traceutil/trace.go:171","msg":"trace[1109191324] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1404; }","duration":"1.135635333s","start":"2026-07-21T20:54:29.159135Z","end":"2026-07-21T20:54:30.29477Z","steps":["trace[1109191324] 'read index received' (duration: 42.477691ms)","trace[1109191324] 'applied index is now lower than readState.Index' (duration: 1.093156322s)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:30.294989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196886629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T20:54:30.295027Z","caller":"traceutil/trace.go:171","msg":"trace[1287995376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1350; }","duration":"1.1969336s","start":"2026-07-21T20:54:29.098083Z","end":"2026-07-21T20:54:30.295017Z","steps":["trace[1287995376] 'agreement among raft nodes before linearized reading' (duration: 1.196798008s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:30.295063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:29.097975Z","time spent":"1.197080271s","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T20:54:30.295082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295826065s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:30.295163Z","caller":"traceutil/trace.go:171","msg":"trace[748059197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1350; }","duration":"1.295939586s","start":"2026-07-21T20:54:28.999196Z","end":"2026-07-21T20:54:30.295135Z","steps":["trace[748059197] 'agreement among raft nodes before linearized reading' (duration: 1.295794164s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:30.295205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:28.999179Z","time spent":"1.296017657s","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:54:30.296385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.942324ms","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-21T20:54:30.296442Z","caller":"traceutil/trace.go:171","msg":"trace[1080031906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1352; }","duration":"891.073365ms","start":"2026-07-21T20:54:29.405355Z","end":"2026-07-21T20:54:30.296428Z","steps":["trace[1080031906] 'agreement among raft nodes before linearized reading' (duration: 890.883073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:30.296477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:29.405312Z","time spent":"891.157526ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-21T20:54:30.296469Z","caller":"traceutil/trace.go:171","msg":"trace[2072159136] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"930.216094ms","start":"2026-07-21T20:54:29.366229Z","end":"2026-07-21T20:54:30.296445Z","steps":["trace[2072159136] 'process raft request' (duration: 929.86765ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:30.296438Z","caller":"traceutil/trace.go:171","msg":"trace[693549457] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"1.132761105s","start":"2026-07-21T20:54:29.163637Z","end":"2026-07-21T20:54:30.296398Z","steps":["trace[693549457] 'process raft request' (duration: 1.131027364s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:30.29651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.251542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:54:30.296589Z","caller":"traceutil/trace.go:171","msg":"trace[464368711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1352; }","duration":"617.375554ms","start":"2026-07-21T20:54:29.679196Z","end":"2026-07-21T20:54:30.296572Z","steps":["trace[464368711] 'agreement among raft nodes before linearized reading' (duration: 617.130231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:30.296609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:29.366204Z","time spent":"930.319786ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:54:30.296636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:29.679137Z","time spent":"617.491374ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T20:54:30.296662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:29.163619Z","time spent":"1.132927858s","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:54:30.537983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.636173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:30.538204Z","caller":"traceutil/trace.go:171","msg":"trace[82354754] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"236.1714ms","start":"2026-07-21T20:54:30.302014Z","end":"2026-07-21T20:54:30.538185Z","steps":["trace[82354754] 'process raft request' (duration: 128.236952ms)","trace[82354754] 'compare' (duration: 107.528172ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:30.538833Z","caller":"traceutil/trace.go:171","msg":"trace[880154903] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1407; }","duration":"234.058383ms","start":"2026-07-21T20:54:30.30473Z","end":"2026-07-21T20:54:30.538788Z","steps":["trace[880154903] 'read index received' (duration: 86.43272ms)","trace[880154903] 'applied index is now lower than readState.Index' (duration: 147.624433ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:30.538916Z","caller":"traceutil/trace.go:171","msg":"trace[1678800951] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"236.632435ms","start":"2026-07-21T20:54:30.30224Z","end":"2026-07-21T20:54:30.538873Z","steps":["trace[1678800951] 'process raft request' (duration: 235.881726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:30.539005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.266936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:30.539233Z","caller":"traceutil/trace.go:171","msg":"trace[1577820411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1355; }","duration":"234.551438ms","start":"2026-07-21T20:54:30.304667Z","end":"2026-07-21T20:54:30.539218Z","steps":["trace[1577820411] 'agreement among raft nodes before linearized reading' (duration: 234.281925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:30.539252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.729286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:30.539334Z","caller":"traceutil/trace.go:171","msg":"trace[1220712381] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1355; }","duration":"184.866979ms","start":"2026-07-21T20:54:30.354449Z","end":"2026-07-21T20:54:30.539316Z","steps":["trace[1220712381] 'agreement among raft nodes before linearized reading' (duration: 184.700217ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:30.873027Z","caller":"traceutil/trace.go:171","msg":"trace[176353553] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"112.385923ms","start":"2026-07-21T20:54:30.760616Z","end":"2026-07-21T20:54:30.873002Z","steps":["trace[176353553] 'process raft request' (duration: 112.214091ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:31.317072Z","caller":"traceutil/trace.go:171","msg":"trace[584586715] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"152.203139ms","start":"2026-07-21T20:54:31.164808Z","end":"2026-07-21T20:54:31.317011Z","steps":["trace[584586715] 'process raft request' (duration: 151.930045ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:32.748468Z","caller":"traceutil/trace.go:171","msg":"trace[428919083] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"184.786652ms","start":"2026-07-21T20:54:32.563652Z","end":"2026-07-21T20:54:32.748438Z","steps":["trace[428919083] 'process raft request' (duration: 125.595623ms)","trace[428919083] 'compare' (duration: 58.914604ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:32.748529Z","caller":"traceutil/trace.go:171","msg":"trace[2061856637] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"183.929401ms","start":"2026-07-21T20:54:32.564541Z","end":"2026-07-21T20:54:32.748471Z","steps":["trace[2061856637] 'process raft request' (duration: 183.79854ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:33.103574Z","caller":"traceutil/trace.go:171","msg":"trace[1636529118] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"102.309913ms","start":"2026-07-21T20:54:33.001238Z","end":"2026-07-21T20:54:33.103548Z","steps":["trace[1636529118] 'read index received' (duration: 102.202281ms)","trace[1636529118] 'applied index is now lower than readState.Index' (duration: 106.562µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:33.103719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.469794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:33.103757Z","caller":"traceutil/trace.go:171","msg":"trace[1395661419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1362; }","duration":"102.554655ms","start":"2026-07-21T20:54:33.001193Z","end":"2026-07-21T20:54:33.103747Z","steps":["trace[1395661419] 'agreement among raft nodes before linearized reading' (duration: 102.452854ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:33.103872Z","caller":"traceutil/trace.go:171","msg":"trace[1614079272] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"153.986896ms","start":"2026-07-21T20:54:32.949848Z","end":"2026-07-21T20:54:33.103835Z","steps":["trace[1614079272] 'process raft request' (duration: 153.51584ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:35.018241Z","caller":"traceutil/trace.go:171","msg":"trace[1448264047] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"170.134235ms","start":"2026-07-21T20:54:34.848076Z","end":"2026-07-21T20:54:35.01821Z","steps":["trace[1448264047] 'process raft request' (duration: 169.966583ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:35.138718Z","caller":"traceutil/trace.go:171","msg":"trace[349583238] linearizableReadLoop","detail":"{readStateIndex:1424; appliedIndex:1422; }","duration":"138.376968ms","start":"2026-07-21T20:54:35.000286Z","end":"2026-07-21T20:54:35.138663Z","steps":["trace[349583238] 'read index received' (duration: 17.770001ms)","trace[349583238] 'applied index is now lower than readState.Index' (duration: 120.582717ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:35.138798Z","caller":"traceutil/trace.go:171","msg":"trace[402343346] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"290.59723ms","start":"2026-07-21T20:54:34.848113Z","end":"2026-07-21T20:54:35.13871Z","steps":["trace[402343346] 'process raft request' (duration: 277.216694ms)","trace[402343346] 'compare' (duration: 13.159863ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:35.138845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.552291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:35.138895Z","caller":"traceutil/trace.go:171","msg":"trace[1091404741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1368; }","duration":"138.640482ms","start":"2026-07-21T20:54:35.000241Z","end":"2026-07-21T20:54:35.138882Z","steps":["trace[1091404741] 'agreement among raft nodes before linearized reading' (duration: 138.564371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:37.429285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.340376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:37.42942Z","caller":"traceutil/trace.go:171","msg":"trace[1024154976] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"226.887ms","start":"2026-07-21T20:54:37.202507Z","end":"2026-07-21T20:54:37.429394Z","steps":["trace[1024154976] 'process raft request' (duration: 49.32625ms)","trace[1024154976] 'compare' (duration: 177.072043ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:38.842582Z","caller":"traceutil/trace.go:171","msg":"trace[1738620073] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"129.27141ms","start":"2026-07-21T20:54:38.71328Z","end":"2026-07-21T20:54:38.842551Z","steps":["trace[1738620073] 'process raft request' (duration: 129.134438ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:39.316769Z","caller":"traceutil/trace.go:171","msg":"trace[585209077] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"110.588836ms","start":"2026-07-21T20:54:39.206143Z","end":"2026-07-21T20:54:39.316732Z","steps":["trace[585209077] 'process raft request' (duration: 31.913944ms)","trace[585209077] 'compare' (duration: 78.569331ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:39.423412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.087196ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-21T20:54:39.544857Z","caller":"traceutil/trace.go:171","msg":"trace[1322151713] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"175.313187ms","start":"2026-07-21T20:54:39.369514Z","end":"2026-07-21T20:54:39.544828Z","steps":["trace[1322151713] 'process raft request' (duration: 175.155055ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:39.545241Z","caller":"traceutil/trace.go:171","msg":"trace[2003052308] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1439; }","duration":"110.576038ms","start":"2026-07-21T20:54:39.434625Z","end":"2026-07-21T20:54:39.545201Z","steps":["trace[2003052308] 'read index received' (duration: 110.544817ms)","trace[2003052308] 'applied index is now lower than readState.Index' (duration: 28.331µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:39.54555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.904481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-21T20:54:39.54561Z","caller":"traceutil/trace.go:171","msg":"trace[1508240180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1382; }","duration":"111.027242ms","start":"2026-07-21T20:54:39.434567Z","end":"2026-07-21T20:54:39.545595Z","steps":["trace[1508240180] 'agreement among raft nodes before linearized reading' (duration: 110.755839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:39.780018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.689993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:39.780298Z","caller":"traceutil/trace.go:171","msg":"trace[415561367] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"229.75662ms","start":"2026-07-21T20:54:39.550507Z","end":"2026-07-21T20:54:39.780264Z","steps":["trace[415561367] 'process raft request' (duration: 229.649299ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:39.780311Z","caller":"traceutil/trace.go:171","msg":"trace[1139017477] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"315.980186ms","start":"2026-07-21T20:54:39.46426Z","end":"2026-07-21T20:54:39.78024Z","steps":["trace[1139017477] 'process raft request' (duration: 164.961739ms)","trace[1139017477] 'compare' (duration: 150.559961ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:39.780602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:39.464232Z","time spent":"316.250099ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:54:41.509591Z","caller":"traceutil/trace.go:171","msg":"trace[846118359] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"121.999715ms","start":"2026-07-21T20:54:41.387563Z","end":"2026-07-21T20:54:41.509563Z","steps":["trace[846118359] 'process raft request' (duration: 121.828283ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:41.55519Z","caller":"traceutil/trace.go:171","msg":"trace[642386499] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"115.864729ms","start":"2026-07-21T20:54:41.439304Z","end":"2026-07-21T20:54:41.555168Z","steps":["trace[642386499] 'process raft request' (duration: 115.764307ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:41.897935Z","caller":"traceutil/trace.go:171","msg":"trace[491620921] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"111.218353ms","start":"2026-07-21T20:54:41.786683Z","end":"2026-07-21T20:54:41.897901Z","steps":["trace[491620921] 'process raft request' (duration: 42.252092ms)","trace[491620921] 'compare' (duration: 68.849499ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:42.624026Z","caller":"traceutil/trace.go:171","msg":"trace[861795721] linearizableReadLoop","detail":"{readStateIndex:1451; appliedIndex:1450; }","duration":"151.702939ms","start":"2026-07-21T20:54:42.472298Z","end":"2026-07-21T20:54:42.624001Z","steps":["trace[861795721] 'read index received' (duration: 151.448176ms)","trace[861795721] 'applied index is now lower than readState.Index' (duration: 253.193µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:42.624057Z","caller":"traceutil/trace.go:171","msg":"trace[1024772721] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"296.383252ms","start":"2026-07-21T20:54:42.327642Z","end":"2026-07-21T20:54:42.624025Z","steps":["trace[1024772721] 'process raft request' (duration: 296.1901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:42.624546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.234735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-07-21T20:54:42.624607Z","caller":"traceutil/trace.go:171","msg":"trace[1142309572] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1394; }","duration":"152.307687ms","start":"2026-07-21T20:54:42.472286Z","end":"2026-07-21T20:54:42.624594Z","steps":["trace[1142309572] 'agreement among raft nodes before linearized reading' (duration: 151.851801ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:43.44918Z","caller":"traceutil/trace.go:171","msg":"trace[288325481] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"109.739741ms","start":"2026-07-21T20:54:43.339412Z","end":"2026-07-21T20:54:43.449152Z","steps":["trace[288325481] 'process raft request' (duration: 109.501548ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:44.917558Z","caller":"traceutil/trace.go:171","msg":"trace[1401232942] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"197.117475ms","start":"2026-07-21T20:54:44.720415Z","end":"2026-07-21T20:54:44.917533Z","steps":["trace[1401232942] 'process raft request' (duration: 196.968963ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:49.287211Z","caller":"traceutil/trace.go:171","msg":"trace[691548107] linearizableReadLoop","detail":"{readStateIndex:1477; appliedIndex:1476; }","duration":"147.516355ms","start":"2026-07-21T20:54:49.139664Z","end":"2026-07-21T20:54:49.28718Z","steps":["trace[691548107] 'read index received' (duration: 91.916726ms)","trace[691548107] 'applied index is now lower than readState.Index' (duration: 55.598119ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:49.287302Z","caller":"traceutil/trace.go:171","msg":"trace[428115397] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"200.888658ms","start":"2026-07-21T20:54:49.08637Z","end":"2026-07-21T20:54:49.287258Z","steps":["trace[428115397] 'process raft request' (duration: 145.209478ms)","trace[428115397] 'compare' (duration: 55.463498ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:49.287338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.661497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:54:49.287404Z","caller":"traceutil/trace.go:171","msg":"trace[1717273512] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1419; }","duration":"147.754459ms","start":"2026-07-21T20:54:49.139634Z","end":"2026-07-21T20:54:49.287389Z","steps":["trace[1717273512] 'agreement among raft nodes before linearized reading' (duration: 147.643936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:49.833187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.823553ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-21T20:54:49.833317Z","caller":"traceutil/trace.go:171","msg":"trace[1643481669] linearizableReadLoop","detail":"{readStateIndex:1479; appliedIndex:1478; }","duration":"393.78612ms","start":"2026-07-21T20:54:49.439508Z","end":"2026-07-21T20:54:49.833294Z","steps":["trace[1643481669] 'read index received' (duration: 11.836315ms)","trace[1643481669] 'applied index is now lower than readState.Index' (duration: 381.947845ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:49.833542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.021212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:49.833579Z","caller":"traceutil/trace.go:171","msg":"trace[1532598779] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1420; }","duration":"394.111073ms","start":"2026-07-21T20:54:49.439457Z","end":"2026-07-21T20:54:49.833568Z","steps":["trace[1532598779] 'agreement among raft nodes before linearized reading' (duration: 393.982121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:49.833656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.191498ms","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-21T20:54:49.833751Z","caller":"traceutil/trace.go:171","msg":"trace[1406718665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1420; }","duration":"230.33427ms","start":"2026-07-21T20:54:49.603395Z","end":"2026-07-21T20:54:49.83373Z","steps":["trace[1406718665] 'agreement among raft nodes before linearized reading' (duration: 230.055345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:49.833619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:49.439433Z","time spent":"394.175324ms","remote":"127.0.0.1:37852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-07-21T20:54:50.327584Z","caller":"traceutil/trace.go:171","msg":"trace[131133360] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1480; }","duration":"329.54629ms","start":"2026-07-21T20:54:49.998012Z","end":"2026-07-21T20:54:50.327558Z","steps":["trace[131133360] 'read index received' (duration: 329.329717ms)","trace[131133360] 'applied index is now lower than readState.Index' (duration: 215.173µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:50.327745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.722702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:50.327724Z","caller":"traceutil/trace.go:171","msg":"trace[2047374937] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"399.810051ms","start":"2026-07-21T20:54:49.927884Z","end":"2026-07-21T20:54:50.327694Z","steps":["trace[2047374937] 'process raft request' (duration: 399.474607ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:50.327787Z","caller":"traceutil/trace.go:171","msg":"trace[400401415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1422; }","duration":"329.813624ms","start":"2026-07-21T20:54:49.997961Z","end":"2026-07-21T20:54:50.327774Z","steps":["trace[400401415] 'agreement among raft nodes before linearized reading' (duration: 329.736203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:50.327827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:49.997932Z","time spent":"329.887214ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:54:50.327882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:49.927851Z","time spent":"399.942562ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:54:50.328057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.983231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-21T20:54:50.328138Z","caller":"traceutil/trace.go:171","msg":"trace[2004906960] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1422; }","duration":"198.065802ms","start":"2026-07-21T20:54:50.130058Z","end":"2026-07-21T20:54:50.328124Z","steps":["trace[2004906960] 'agreement among raft nodes before linearized reading' (duration: 197.829979ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:50.595876Z","caller":"traceutil/trace.go:171","msg":"trace[671756678] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"260.899281ms","start":"2026-07-21T20:54:50.334946Z","end":"2026-07-21T20:54:50.595845Z","steps":["trace[671756678] 'process raft request' (duration: 203.926824ms)","trace[671756678] 'compare' (duration: 56.839746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:51.812473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.305298ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:51.812635Z","caller":"traceutil/trace.go:171","msg":"trace[1182888176] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1426; }","duration":"119.605752ms","start":"2026-07-21T20:54:51.69301Z","end":"2026-07-21T20:54:51.812616Z","steps":["trace[1182888176] 'range keys from in-memory index tree' (duration: 119.287358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:51.812753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.105385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:51.8129Z","caller":"traceutil/trace.go:171","msg":"trace[1617339830] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"444.815605ms","start":"2026-07-21T20:54:51.36806Z","end":"2026-07-21T20:54:51.812876Z","steps":["trace[1617339830] 'process raft request' (duration: 122.483387ms)","trace[1617339830] 'compare' (duration: 321.897223ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:51.812985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:51.368046Z","time spent":"444.897656ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}