{"level":"warn","ts":"2026-05-12T11:20:44.264071Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T11:20:44.264156Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.215: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.215:2380","--initial-cluster=instance=https://199.19.213.215:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.215:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.215: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-05-12T11:20:44.264219Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T11:20:44.264229Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.215:2380"]} {"level":"info","ts":"2026-05-12T11:20:44.264349Z","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-05-12T11:20:44.26512Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.215:2379"]} {"level":"info","ts":"2026-05-12T11:20:44.265227Z","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.215:2380"],"listen-peer-urls":["https://199.19.213.215:2380"],"advertise-client-urls":["https://199.19.213.215:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.215:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.215: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-05-12T11:20:44.341915Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"76.361487ms"} {"level":"info","ts":"2026-05-12T11:20:44.377788Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e6e5712872899056","cluster-id":"5d812b997db5c8f"} {"level":"info","ts":"2026-05-12T11:20:44.37786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T11:20:44.37788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became follower at term 0"} {"level":"info","ts":"2026-05-12T11:20:44.377888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e6e5712872899056 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T11:20:44.377899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became follower at term 1"} {"level":"info","ts":"2026-05-12T11:20:44.377928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 switched to configuration voters=(16637828816924414038)"} {"level":"warn","ts":"2026-05-12T11:20:44.447944Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T11:20:44.458011Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T11:20:44.468789Z","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-05-12T11:20:44.524607Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e6e5712872899056","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T11:20:44.52488Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e6e5712872899056","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T11:20:44.525078Z","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-05-12T11:20:44.525142Z","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-05-12T11:20:44.525157Z","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-05-12T11:20:44.527327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 switched to configuration voters=(16637828816924414038)"} {"level":"info","ts":"2026-05-12T11:20:44.527672Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5d812b997db5c8f","local-member-id":"e6e5712872899056","added-peer-id":"e6e5712872899056","added-peer-peer-urls":["https://199.19.213.215:2380"]} {"level":"info","ts":"2026-05-12T11:20:44.532156Z","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-05-12T11:20:44.532451Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e6e5712872899056","initial-advertise-peer-urls":["https://199.19.213.215:2380"],"listen-peer-urls":["https://199.19.213.215:2380"],"advertise-client-urls":["https://199.19.213.215:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.215:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T11:20:44.532511Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T11:20:44.533418Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.215:2380"} {"level":"info","ts":"2026-05-12T11:20:44.533512Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.215:2380"} {"level":"info","ts":"2026-05-12T11:20:44.879421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T11:20:44.879541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T11:20:44.87958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 received MsgPreVoteResp from e6e5712872899056 at term 1"} {"level":"info","ts":"2026-05-12T11:20:44.879631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became candidate at term 2"} {"level":"info","ts":"2026-05-12T11:20:44.879659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 received MsgVoteResp from e6e5712872899056 at term 2"} {"level":"info","ts":"2026-05-12T11:20:44.879721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became leader at term 2"} {"level":"info","ts":"2026-05-12T11:20:44.879751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e6e5712872899056 elected leader e6e5712872899056 at term 2"} {"level":"info","ts":"2026-05-12T11:20:44.98043Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e6e5712872899056","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.215:2379]}","request-path":"/0/members/e6e5712872899056/attributes","cluster-id":"5d812b997db5c8f","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T11:20:44.98058Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T11:20:44.981074Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T11:20:44.981181Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T11:20:44.98065Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T11:20:44.980802Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:20:44.984636Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.215:2379"} {"level":"info","ts":"2026-05-12T11:20:44.987169Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T11:20:44.989437Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5d812b997db5c8f","local-member-id":"e6e5712872899056","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:20:44.989608Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:20:44.990231Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-12T11:20:46.411673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.859324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-12T11:20:46.411723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.449046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.215\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:20:46.411783Z","caller":"traceutil/trace.go:171","msg":"trace[845534755] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"120.022389ms","start":"2026-05-12T11:20:46.291735Z","end":"2026-05-12T11:20:46.411758Z","steps":["trace[845534755] 'range keys from in-memory index tree' (duration: 119.774021ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:46.41179Z","caller":"traceutil/trace.go:171","msg":"trace[176477232] range","detail":"{range_begin:/registry/masterleases/199.19.213.215; range_end:; response_count:0; response_revision:3; }","duration":"121.562259ms","start":"2026-05-12T11:20:46.290214Z","end":"2026-05-12T11:20:46.411776Z","steps":["trace[176477232] 'range keys from in-memory index tree' (duration: 121.377023ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:46.442376Z","caller":"traceutil/trace.go:171","msg":"trace[295753610] linearizableReadLoop","detail":"{readStateIndex:7; appliedIndex:6; }","duration":"148.755501ms","start":"2026-05-12T11:20:46.293599Z","end":"2026-05-12T11:20:46.442354Z","steps":["trace[295753610] 'read index received' (duration: 148.638447ms)","trace[295753610] 'applied index is now lower than readState.Index' (duration: 115.964µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:46.44242Z","caller":"traceutil/trace.go:171","msg":"trace[1828850282] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"150.400444ms","start":"2026-05-12T11:20:46.291999Z","end":"2026-05-12T11:20:46.4424Z","steps":["trace[1828850282] 'process raft request' (duration: 150.246459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:46.442799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.165973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:20:46.443078Z","caller":"traceutil/trace.go:171","msg":"trace[963507350] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:4; }","duration":"149.266237ms","start":"2026-05-12T11:20:46.293594Z","end":"2026-05-12T11:20:46.442861Z","steps":["trace[963507350] 'agreement among raft nodes before linearized reading' (duration: 148.872134ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:46.526655Z","caller":"traceutil/trace.go:171","msg":"trace[1729909372] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"232.878618ms","start":"2026-05-12T11:20:46.293757Z","end":"2026-05-12T11:20:46.526635Z","steps":["trace[1729909372] 'process raft request' (duration: 231.668349ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:46.526964Z","caller":"traceutil/trace.go:171","msg":"trace[152262942] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"232.876488ms","start":"2026-05-12T11:20:46.294076Z","end":"2026-05-12T11:20:46.526952Z","steps":["trace[152262942] 'process raft request' (duration: 232.500617ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:46.527129Z","caller":"traceutil/trace.go:171","msg":"trace[1149721032] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"233.114976ms","start":"2026-05-12T11:20:46.293952Z","end":"2026-05-12T11:20:46.527067Z","steps":["trace[1149721032] 'process raft request' (duration: 232.59832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:46.527165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.514184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:20:46.52723Z","caller":"traceutil/trace.go:171","msg":"trace[2067407866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:9; }","duration":"168.605367ms","start":"2026-05-12T11:20:46.35861Z","end":"2026-05-12T11:20:46.527215Z","steps":["trace[2067407866] 'agreement among raft nodes before linearized reading' (duration: 168.40136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:46.527267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.56365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-12T11:20:46.527194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.577096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-12T11:20:46.527419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.795754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:20:46.527431Z","caller":"traceutil/trace.go:171","msg":"trace[2075009326] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"233.611341ms","start":"2026-05-12T11:20:46.293809Z","end":"2026-05-12T11:20:46.52742Z","steps":["trace[2075009326] 'process raft request' (duration: 232.682132ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:46.527453Z","caller":"traceutil/trace.go:171","msg":"trace[138071370] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"178.871717ms","start":"2026-05-12T11:20:46.348572Z","end":"2026-05-12T11:20:46.527444Z","steps":["trace[138071370] 'agreement among raft nodes before linearized reading' (duration: 178.827106ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:46.527511Z","caller":"traceutil/trace.go:171","msg":"trace[1375677732] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"233.641553ms","start":"2026-05-12T11:20:46.293858Z","end":"2026-05-12T11:20:46.5275Z","steps":["trace[1375677732] 'process raft request' (duration: 232.668072ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:46.527516Z","caller":"traceutil/trace.go:171","msg":"trace[495490954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:9; }","duration":"168.876126ms","start":"2026-05-12T11:20:46.358552Z","end":"2026-05-12T11:20:46.527428Z","steps":["trace[495490954] 'agreement among raft nodes before linearized reading' (duration: 168.425071ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:46.527372Z","caller":"traceutil/trace.go:171","msg":"trace[755932903] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:9; }","duration":"233.714345ms","start":"2026-05-12T11:20:46.293634Z","end":"2026-05-12T11:20:46.527348Z","steps":["trace[755932903] 'agreement among raft nodes before linearized reading' (duration: 233.506328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:46.527758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.264522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:20:46.527809Z","caller":"traceutil/trace.go:171","msg":"trace[1962678957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9; }","duration":"113.352264ms","start":"2026-05-12T11:20:46.414445Z","end":"2026-05-12T11:20:46.527797Z","steps":["trace[1962678957] 'agreement among raft nodes before linearized reading' (duration: 113.110597ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:46.56389Z","caller":"traceutil/trace.go:171","msg":"trace[1270517539] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"117.652093ms","start":"2026-05-12T11:20:46.446209Z","end":"2026-05-12T11:20:46.563861Z","steps":["trace[1270517539] 'process raft request' (duration: 117.491498ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:46.563911Z","caller":"traceutil/trace.go:171","msg":"trace[520200809] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"117.52744ms","start":"2026-05-12T11:20:46.446354Z","end":"2026-05-12T11:20:46.563882Z","steps":["trace[520200809] 'process raft request' (duration: 117.425917ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:46.563958Z","caller":"traceutil/trace.go:171","msg":"trace[223826293] transaction","detail":"{read_only:false; number_of_response:0; response_revision:11; }","duration":"117.515789ms","start":"2026-05-12T11:20:46.446422Z","end":"2026-05-12T11:20:46.563938Z","steps":["trace[223826293] 'process raft request' (duration: 117.389715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:46.989364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.983607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-12T11:20:46.989673Z","caller":"traceutil/trace.go:171","msg":"trace[207479422] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"422.325604ms","start":"2026-05-12T11:20:46.567309Z","end":"2026-05-12T11:20:46.989635Z","steps":["trace[207479422] 'process raft request' (duration: 70.539292ms)","trace[207479422] 'compare' (duration: 350.839722ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:46.989848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.567257Z","time spent":"422.519591ms","remote":"127.0.0.1:54066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T11:20:46.989882Z","caller":"traceutil/trace.go:171","msg":"trace[341676197] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"421.86742ms","start":"2026-05-12T11:20:46.567968Z","end":"2026-05-12T11:20:46.989836Z","steps":["trace[341676197] 'process raft request' (duration: 421.533129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:46.990044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.567952Z","time spent":"422.030545ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T11:20:46.990499Z","caller":"traceutil/trace.go:171","msg":"trace[1411875306] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"407.317883ms","start":"2026-05-12T11:20:46.58317Z","end":"2026-05-12T11:20:46.990488Z","steps":["trace[1411875306] 'process raft request' (duration: 407.149007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:46.990606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.583134Z","time spent":"407.425497ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T11:20:46.990596Z","caller":"traceutil/trace.go:171","msg":"trace[470782658] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"407.316773ms","start":"2026-05-12T11:20:46.583253Z","end":"2026-05-12T11:20:46.990569Z","steps":["trace[470782658] 'process raft request' (duration: 407.176849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:46.990727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.583246Z","time spent":"407.420546ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:20:46.99091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.897213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:20:46.990996Z","caller":"traceutil/trace.go:171","msg":"trace[879290199] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:20; }","duration":"423.000057ms","start":"2026-05-12T11:20:46.567968Z","end":"2026-05-12T11:20:46.990968Z","steps":["trace[879290199] 'agreement among raft nodes before linearized reading' (duration: 422.810401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:46.991054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.567954Z","time spent":"423.08613ms","remote":"127.0.0.1:53608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"warn","ts":"2026-05-12T11:20:46.991191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.61757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-12T11:20:46.991231Z","caller":"traceutil/trace.go:171","msg":"trace[973808060] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"425.718274ms","start":"2026-05-12T11:20:46.5655Z","end":"2026-05-12T11:20:46.991218Z","steps":["trace[973808060] 'agreement among raft nodes before linearized reading' (duration: 425.480335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:46.991255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.565481Z","time spent":"425.768075ms","remote":"127.0.0.1:53610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-12T11:20:46.991484Z","caller":"traceutil/trace.go:171","msg":"trace[1826484091] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:20; }","duration":"406.550718ms","start":"2026-05-12T11:20:46.584872Z","end":"2026-05-12T11:20:46.991423Z","steps":["trace[1826484091] 'read index received' (duration: 53.05365ms)","trace[1826484091] 'applied index is now lower than readState.Index' (duration: 352.537387ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:47.018344Z","caller":"traceutil/trace.go:171","msg":"trace[1818259831] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"429.889397ms","start":"2026-05-12T11:20:46.588431Z","end":"2026-05-12T11:20:47.018321Z","steps":["trace[1818259831] 'process raft request' (duration: 429.845816ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:47.018475Z","caller":"traceutil/trace.go:171","msg":"trace[1947770518] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"430.465905ms","start":"2026-05-12T11:20:46.588002Z","end":"2026-05-12T11:20:47.018468Z","steps":["trace[1947770518] 'process raft request' (duration: 430.205107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:47.018547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.587986Z","time spent":"430.517267ms","remote":"127.0.0.1:54066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T11:20:47.018683Z","caller":"traceutil/trace.go:171","msg":"trace[806437446] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"430.694802ms","start":"2026-05-12T11:20:46.58798Z","end":"2026-05-12T11:20:47.018675Z","steps":["trace[806437446] 'process raft request' (duration: 430.197187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:47.018753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.587957Z","time spent":"430.772615ms","remote":"127.0.0.1:54066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T11:20:47.018775Z","caller":"traceutil/trace.go:171","msg":"trace[967594995] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"430.709513ms","start":"2026-05-12T11:20:46.588024Z","end":"2026-05-12T11:20:47.018734Z","steps":["trace[967594995] 'process raft request' (duration: 430.222038ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:47.01837Z","caller":"traceutil/trace.go:171","msg":"trace[1776841601] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"430.6238ms","start":"2026-05-12T11:20:46.587731Z","end":"2026-05-12T11:20:47.018355Z","steps":["trace[1776841601] 'process raft request' (duration: 430.29217ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:47.018674Z","caller":"traceutil/trace.go:171","msg":"trace[863923183] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"430.690492ms","start":"2026-05-12T11:20:46.587956Z","end":"2026-05-12T11:20:47.018646Z","steps":["trace[863923183] 'process raft request' (duration: 430.185736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:47.018956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.587714Z","time spent":"431.174017ms","remote":"127.0.0.1:53976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:20:47.018923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.588014Z","time spent":"430.804906ms","remote":"127.0.0.1:53608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:20:47.019195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.58842Z","time spent":"430.00396ms","remote":"127.0.0.1:54066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:20:47.019222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.58794Z","time spent":"431.070394ms","remote":"127.0.0.1:54066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:20:47.241598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.725475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-12T11:20:47.241827Z","caller":"traceutil/trace.go:171","msg":"trace[557970035] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"529.03772ms","start":"2026-05-12T11:20:46.712747Z","end":"2026-05-12T11:20:47.241784Z","steps":["trace[557970035] 'process raft request' (duration: 388.056977ms)","trace[557970035] 'compare' (duration: 140.596451ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:47.241967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.712733Z","time spent":"529.167885ms","remote":"127.0.0.1:53678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T11:20:47.242214Z","caller":"traceutil/trace.go:171","msg":"trace[1521623138] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"485.503664ms","start":"2026-05-12T11:20:46.756648Z","end":"2026-05-12T11:20:47.242151Z","steps":["trace[1521623138] 'process raft request' (duration: 485.06185ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:47.242343Z","caller":"traceutil/trace.go:171","msg":"trace[1585861700] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"246.592081ms","start":"2026-05-12T11:20:46.995726Z","end":"2026-05-12T11:20:47.242319Z","steps":["trace[1585861700] 'process raft request' (duration: 246.477977ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:47.24247Z","caller":"traceutil/trace.go:171","msg":"trace[1900614759] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"248.319446ms","start":"2026-05-12T11:20:46.99412Z","end":"2026-05-12T11:20:47.242439Z","steps":["trace[1900614759] 'process raft request' (duration: 247.782929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:47.242497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.561163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:20:47.242274Z","caller":"traceutil/trace.go:171","msg":"trace[927954694] linearizableReadLoop","detail":"{readStateIndex:32; appliedIndex:24; }","duration":"250.690482ms","start":"2026-05-12T11:20:46.991563Z","end":"2026-05-12T11:20:47.242253Z","steps":["trace[927954694] 'read index received' (duration: 26.498439ms)","trace[927954694] 'applied index is now lower than readState.Index' (duration: 224.190853ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:47.242565Z","caller":"traceutil/trace.go:171","msg":"trace[912848366] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:30; }","duration":"600.660116ms","start":"2026-05-12T11:20:46.641887Z","end":"2026-05-12T11:20:47.242547Z","steps":["trace[912848366] 'agreement among raft nodes before linearized reading' (duration: 600.481761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:47.242543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.891155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:20:47.243495Z","caller":"traceutil/trace.go:171","msg":"trace[969155355] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:30; }","duration":"635.858606ms","start":"2026-05-12T11:20:46.607607Z","end":"2026-05-12T11:20:47.243465Z","steps":["trace[969155355] 'agreement among raft nodes before linearized reading' (duration: 634.703399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:47.243595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.607567Z","time spent":"635.99566ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-05-12T11:20:47.242702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.756627Z","time spent":"485.72782ms","remote":"127.0.0.1:53766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:20:47.243619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:46.641877Z","time spent":"601.475583ms","remote":"127.0.0.1:53926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-12T11:20:47.247218Z","caller":"traceutil/trace.go:171","msg":"trace[536373465] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"225.474314ms","start":"2026-05-12T11:20:47.021713Z","end":"2026-05-12T11:20:47.247187Z","steps":["trace[536373465] 'process raft request' (duration: 225.052791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:47.247375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.963936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-12T11:20:47.24741Z","caller":"traceutil/trace.go:171","msg":"trace[601587757] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"224.962947ms","start":"2026-05-12T11:20:47.022281Z","end":"2026-05-12T11:20:47.247244Z","steps":["trace[601587757] 'process raft request' (duration: 224.909926ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:47.247445Z","caller":"traceutil/trace.go:171","msg":"trace[1138454361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:35; }","duration":"253.10027ms","start":"2026-05-12T11:20:46.994326Z","end":"2026-05-12T11:20:47.247426Z","steps":["trace[1138454361] 'agreement among raft nodes before linearized reading' (duration: 252.908394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:47.247673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.351396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T11:20:47.247708Z","caller":"traceutil/trace.go:171","msg":"trace[592904555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:35; }","duration":"252.440289ms","start":"2026-05-12T11:20:46.995259Z","end":"2026-05-12T11:20:47.2477Z","steps":["trace[592904555] 'agreement among raft nodes before linearized reading' (duration: 252.357146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:47.247686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.082138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:20:47.247731Z","caller":"traceutil/trace.go:171","msg":"trace[1988655915] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"225.871416ms","start":"2026-05-12T11:20:47.021845Z","end":"2026-05-12T11:20:47.247716Z","steps":["trace[1988655915] 'process raft request' (duration: 225.294258ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:47.247804Z","caller":"traceutil/trace.go:171","msg":"trace[80897580] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"226.047883ms","start":"2026-05-12T11:20:47.021725Z","end":"2026-05-12T11:20:47.247773Z","steps":["trace[80897580] 'process raft request' (duration: 225.343961ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:47.24788Z","caller":"traceutil/trace.go:171","msg":"trace[65009309] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:35; }","duration":"252.302524ms","start":"2026-05-12T11:20:46.99544Z","end":"2026-05-12T11:20:47.247743Z","steps":["trace[65009309] 'agreement among raft nodes before linearized reading' (duration: 251.836719ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:47.248002Z","caller":"traceutil/trace.go:171","msg":"trace[1509725034] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"226.134824ms","start":"2026-05-12T11:20:47.021856Z","end":"2026-05-12T11:20:47.247991Z","steps":["trace[1509725034] 'process raft request' (duration: 225.309348ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:48.217735Z","caller":"traceutil/trace.go:171","msg":"trace[1713141542] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"128.222435ms","start":"2026-05-12T11:20:48.089459Z","end":"2026-05-12T11:20:48.217681Z","steps":["trace[1713141542] 'process raft request' (duration: 57.763244ms)","trace[1713141542] 'compare' (duration: 70.294826ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:48.565976Z","caller":"traceutil/trace.go:171","msg":"trace[775918586] linearizableReadLoop","detail":"{readStateIndex:150; appliedIndex:149; }","duration":"133.474214ms","start":"2026-05-12T11:20:48.432472Z","end":"2026-05-12T11:20:48.565946Z","steps":["trace[775918586] 'read index received' (duration: 106.614612ms)","trace[775918586] 'applied index is now lower than readState.Index' (duration: 26.858642ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:48.566025Z","caller":"traceutil/trace.go:171","msg":"trace[258580475] transaction","detail":"{read_only:false; response_revision:146; number_of_response:1; }","duration":"141.346277ms","start":"2026-05-12T11:20:48.424647Z","end":"2026-05-12T11:20:48.565993Z","steps":["trace[258580475] 'process raft request' (duration: 114.408122ms)","trace[258580475] 'compare' (duration: 26.735068ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:48.566319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.720352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:20:48.566603Z","caller":"traceutil/trace.go:171","msg":"trace[1034384994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:146; }","duration":"134.143756ms","start":"2026-05-12T11:20:48.432427Z","end":"2026-05-12T11:20:48.566571Z","steps":["trace[1034384994] 'agreement among raft nodes before linearized reading' (duration: 133.63825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:49.172843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.639433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:20:49.17296Z","caller":"traceutil/trace.go:171","msg":"trace[1985012042] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"343.219197ms","start":"2026-05-12T11:20:48.82972Z","end":"2026-05-12T11:20:49.172939Z","steps":["trace[1985012042] 'process raft request' (duration: 41.407299ms)","trace[1985012042] 'compare' (duration: 301.447397ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:49.173094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:48.829679Z","time spent":"343.362492ms","remote":"127.0.0.1:53884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":792,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:20:49.509659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.606303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:20:49.509761Z","caller":"traceutil/trace.go:171","msg":"trace[1095586392] linearizableReadLoop","detail":"{readStateIndex:167; appliedIndex:166; }","duration":"233.56662ms","start":"2026-05-12T11:20:49.276183Z","end":"2026-05-12T11:20:49.509749Z","steps":["trace[1095586392] 'read index received' (duration: 41.809383ms)","trace[1095586392] 'applied index is now lower than readState.Index' (duration: 191.756377ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:49.509829Z","caller":"traceutil/trace.go:171","msg":"trace[2062827017] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"303.063611ms","start":"2026-05-12T11:20:49.206754Z","end":"2026-05-12T11:20:49.509817Z","steps":["trace[2062827017] 'process raft request' (duration: 111.252812ms)","trace[2062827017] 'compare' (duration: 191.465828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:49.509869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:49.20674Z","time spent":"303.112933ms","remote":"127.0.0.1:53884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":760,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:20:49.510073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.867279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-12T11:20:49.510128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.939309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-12T11:20:49.510176Z","caller":"traceutil/trace.go:171","msg":"trace[1872799503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:163; }","duration":"234.018424ms","start":"2026-05-12T11:20:49.276133Z","end":"2026-05-12T11:20:49.510151Z","steps":["trace[1872799503] 'agreement among raft nodes before linearized reading' (duration: 233.813268ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:49.510198Z","caller":"traceutil/trace.go:171","msg":"trace[926099875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:163; }","duration":"233.076793ms","start":"2026-05-12T11:20:49.277101Z","end":"2026-05-12T11:20:49.510178Z","steps":["trace[926099875] 'agreement among raft nodes before linearized reading' (duration: 232.766503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:49.510476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.047897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:20:49.510683Z","caller":"traceutil/trace.go:171","msg":"trace[733045085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:163; }","duration":"178.17021ms","start":"2026-05-12T11:20:49.332342Z","end":"2026-05-12T11:20:49.510512Z","steps":["trace[733045085] 'agreement among raft nodes before linearized reading' (duration: 177.704596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:49.748194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.455103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:20:49.748857Z","caller":"traceutil/trace.go:171","msg":"trace[1072878052] transaction","detail":"{read_only:false; response_revision:167; number_of_response:1; }","duration":"150.451581ms","start":"2026-05-12T11:20:49.598375Z","end":"2026-05-12T11:20:49.748827Z","steps":["trace[1072878052] 'process raft request' (duration: 17.295555ms)","trace[1072878052] 'compare' (duration: 132.254237ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:49.884771Z","caller":"traceutil/trace.go:171","msg":"trace[302588312] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"130.987335ms","start":"2026-05-12T11:20:49.753757Z","end":"2026-05-12T11:20:49.884744Z","steps":["trace[302588312] 'process raft request' (duration: 115.197639ms)","trace[302588312] 'compare' (duration: 15.690523ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:50.158116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.637445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:20:50.158255Z","caller":"traceutil/trace.go:171","msg":"trace[1455218054] transaction","detail":"{read_only:false; response_revision:171; number_of_response:1; }","duration":"205.419137ms","start":"2026-05-12T11:20:49.952821Z","end":"2026-05-12T11:20:50.158241Z","steps":["trace[1455218054] 'process raft request' (duration: 41.605906ms)","trace[1455218054] 'compare' (duration: 163.526792ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:50.833901Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400674232202189979,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T11:20:50.994591Z","caller":"traceutil/trace.go:171","msg":"trace[1731488511] linearizableReadLoop","detail":"{readStateIndex:177; appliedIndex:176; }","duration":"661.182036ms","start":"2026-05-12T11:20:50.333365Z","end":"2026-05-12T11:20:50.994547Z","steps":["trace[1731488511] 'read index received' (duration: 563.446166ms)","trace[1731488511] 'applied index is now lower than readState.Index' (duration: 97.73416ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:50.994619Z","caller":"traceutil/trace.go:171","msg":"trace[2092920208] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"817.047681ms","start":"2026-05-12T11:20:50.177525Z","end":"2026-05-12T11:20:50.994572Z","steps":["trace[2092920208] 'process raft request' (duration: 719.324293ms)","trace[2092920208] 'compare' (duration: 97.367967ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:50.994779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:50.177515Z","time spent":"817.184876ms","remote":"127.0.0.1:53884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:20:50.994822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.446534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:20:50.99487Z","caller":"traceutil/trace.go:171","msg":"trace[716217412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:173; }","duration":"661.538576ms","start":"2026-05-12T11:20:50.333315Z","end":"2026-05-12T11:20:50.994854Z","steps":["trace[716217412] 'agreement among raft nodes before linearized reading' (duration: 661.410252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:50.994908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:50.333293Z","time spent":"661.604889ms","remote":"127.0.0.1:53470","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-05-12T11:20:50.99492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.055835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T11:20:50.994954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.520907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:20:50.994971Z","caller":"traceutil/trace.go:171","msg":"trace[747999713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:173; }","duration":"483.148928ms","start":"2026-05-12T11:20:50.511807Z","end":"2026-05-12T11:20:50.994956Z","steps":["trace[747999713] 'agreement among raft nodes before linearized reading' (duration: 483.057635ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:50.994996Z","caller":"traceutil/trace.go:171","msg":"trace[1341945668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:173; }","duration":"274.62382ms","start":"2026-05-12T11:20:50.720359Z","end":"2026-05-12T11:20:50.994983Z","steps":["trace[1341945668] 'agreement among raft nodes before linearized reading' (duration: 274.550138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:50.995005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:50.511781Z","time spent":"483.21704ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T11:20:51.174263Z","caller":"traceutil/trace.go:171","msg":"trace[874303623] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"174.559579ms","start":"2026-05-12T11:20:50.999683Z","end":"2026-05-12T11:20:51.174242Z","steps":["trace[874303623] 'process raft request' (duration: 174.427204ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:51.174266Z","caller":"traceutil/trace.go:171","msg":"trace[1097358500] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"174.59523ms","start":"2026-05-12T11:20:50.999634Z","end":"2026-05-12T11:20:51.174229Z","steps":["trace[1097358500] 'process raft request' (duration: 163.615027ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:51.174623Z","caller":"traceutil/trace.go:171","msg":"trace[1190986681] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"172.838603ms","start":"2026-05-12T11:20:51.001759Z","end":"2026-05-12T11:20:51.174598Z","steps":["trace[1190986681] 'process raft request' (duration: 172.387838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:51.984601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.679473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:20:51.984875Z","caller":"traceutil/trace.go:171","msg":"trace[903253855] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"351.820932ms","start":"2026-05-12T11:20:51.633034Z","end":"2026-05-12T11:20:51.984855Z","steps":["trace[903253855] 'process raft request' (duration: 100.826069ms)","trace[903253855] 'compare' (duration: 250.543529ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:51.985075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:20:51.633008Z","time spent":"352.001008ms","remote":"127.0.0.1:53608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1069,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T11:20:52.1382Z","caller":"traceutil/trace.go:171","msg":"trace[2021314954] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"124.797951ms","start":"2026-05-12T11:20:52.013365Z","end":"2026-05-12T11:20:52.138163Z","steps":["trace[2021314954] 'process raft request' (duration: 82.200882ms)","trace[2021314954] 'compare' (duration: 42.425524ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:52.462873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.188868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:20:52.463014Z","caller":"traceutil/trace.go:171","msg":"trace[1132986245] transaction","detail":"{read_only:false; response_revision:220; number_of_response:1; }","duration":"250.47127ms","start":"2026-05-12T11:20:52.212511Z","end":"2026-05-12T11:20:52.462982Z","steps":["trace[1132986245] 'process raft request' (duration: 137.111807ms)","trace[1132986245] 'compare' (duration: 113.015042ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:52.463048Z","caller":"traceutil/trace.go:171","msg":"trace[290679780] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"234.067263ms","start":"2026-05-12T11:20:52.228971Z","end":"2026-05-12T11:20:52.463038Z","steps":["trace[290679780] 'process raft request' (duration: 233.99069ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:52.463143Z","caller":"traceutil/trace.go:171","msg":"trace[438692091] linearizableReadLoop","detail":"{readStateIndex:226; appliedIndex:225; }","duration":"234.853187ms","start":"2026-05-12T11:20:52.228258Z","end":"2026-05-12T11:20:52.463111Z","steps":["trace[438692091] 'read index received' (duration: 121.38621ms)","trace[438692091] 'applied index is now lower than readState.Index' (duration: 113.463397ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:52.463347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.067705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-12T11:20:52.463385Z","caller":"traceutil/trace.go:171","msg":"trace[950426722] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:221; }","duration":"235.160007ms","start":"2026-05-12T11:20:52.228213Z","end":"2026-05-12T11:20:52.463373Z","steps":["trace[950426722] 'agreement among raft nodes before linearized reading' (duration: 235.009993ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:52.663403Z","caller":"traceutil/trace.go:171","msg":"trace[826689803] linearizableReadLoop","detail":"{readStateIndex:230; appliedIndex:228; }","duration":"152.040076ms","start":"2026-05-12T11:20:52.511345Z","end":"2026-05-12T11:20:52.663385Z","steps":["trace[826689803] 'read index received' (duration: 66.649162ms)","trace[826689803] 'applied index is now lower than readState.Index' (duration: 85.389944ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:52.663496Z","caller":"traceutil/trace.go:171","msg":"trace[9964480] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"195.752931ms","start":"2026-05-12T11:20:52.467706Z","end":"2026-05-12T11:20:52.663458Z","steps":["trace[9964480] 'process raft request' (duration: 195.629367ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:52.663537Z","caller":"traceutil/trace.go:171","msg":"trace[1288571787] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"196.192175ms","start":"2026-05-12T11:20:52.467322Z","end":"2026-05-12T11:20:52.663514Z","steps":["trace[1288571787] 'process raft request' (duration: 110.673887ms)","trace[1288571787] 'compare' (duration: 85.212538ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:52.663554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.190771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:20:52.663618Z","caller":"traceutil/trace.go:171","msg":"trace[36989863] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:224; }","duration":"152.306605ms","start":"2026-05-12T11:20:52.511292Z","end":"2026-05-12T11:20:52.663598Z","steps":["trace[36989863] 'agreement among raft nodes before linearized reading' (duration: 152.16935ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:52.935295Z","caller":"traceutil/trace.go:171","msg":"trace[2058186999] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"199.77296ms","start":"2026-05-12T11:20:52.735489Z","end":"2026-05-12T11:20:52.935262Z","steps":["trace[2058186999] 'process raft request' (duration: 131.263098ms)","trace[2058186999] 'compare' (duration: 68.268574ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:52.935472Z","caller":"traceutil/trace.go:171","msg":"trace[2087965659] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"199.13481ms","start":"2026-05-12T11:20:52.736094Z","end":"2026-05-12T11:20:52.935229Z","steps":["trace[2087965659] 'process raft request' (duration: 199.075878ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:53.266789Z","caller":"traceutil/trace.go:171","msg":"trace[599886522] linearizableReadLoop","detail":"{readStateIndex:245; appliedIndex:244; }","duration":"131.91196ms","start":"2026-05-12T11:20:53.134856Z","end":"2026-05-12T11:20:53.266768Z","steps":["trace[599886522] 'read index received' (duration: 80.684613ms)","trace[599886522] 'applied index is now lower than readState.Index' (duration: 51.226387ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:53.2669Z","caller":"traceutil/trace.go:171","msg":"trace[785628281] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"133.664237ms","start":"2026-05-12T11:20:53.133187Z","end":"2026-05-12T11:20:53.266851Z","steps":["trace[785628281] 'process raft request' (duration: 82.390238ms)","trace[785628281] 'compare' (duration: 51.109494ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:53.267037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.151378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:20:53.267398Z","caller":"traceutil/trace.go:171","msg":"trace[91236486] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:0; response_revision:239; }","duration":"132.549951ms","start":"2026-05-12T11:20:53.134824Z","end":"2026-05-12T11:20:53.267374Z","steps":["trace[91236486] 'agreement among raft nodes before linearized reading' (duration: 132.036134ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:53.421962Z","caller":"traceutil/trace.go:171","msg":"trace[1169460360] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"124.952447ms","start":"2026-05-12T11:20:53.296991Z","end":"2026-05-12T11:20:53.421944Z","steps":["trace[1169460360] 'process raft request' (duration: 124.908006ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:53.422261Z","caller":"traceutil/trace.go:171","msg":"trace[1020003126] linearizableReadLoop","detail":"{readStateIndex:248; appliedIndex:246; }","duration":"127.695615ms","start":"2026-05-12T11:20:53.294555Z","end":"2026-05-12T11:20:53.42225Z","steps":["trace[1020003126] 'read index received' (duration: 82.450261ms)","trace[1020003126] 'applied index is now lower than readState.Index' (duration: 45.244554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:53.422331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.986565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-12T11:20:53.422358Z","caller":"traceutil/trace.go:171","msg":"trace[1512797640] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:243; }","duration":"151.032376ms","start":"2026-05-12T11:20:53.271318Z","end":"2026-05-12T11:20:53.42235Z","steps":["trace[1512797640] 'agreement among raft nodes before linearized reading' (duration: 150.968394ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:53.422327Z","caller":"traceutil/trace.go:171","msg":"trace[1962844390] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"150.378573ms","start":"2026-05-12T11:20:53.271893Z","end":"2026-05-12T11:20:53.422272Z","steps":["trace[1962844390] 'process raft request' (duration: 105.134109ms)","trace[1962844390] 'compare' (duration: 44.711177ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:53.422443Z","caller":"traceutil/trace.go:171","msg":"trace[2128588507] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"147.352027ms","start":"2026-05-12T11:20:53.2749Z","end":"2026-05-12T11:20:53.422252Z","steps":["trace[2128588507] 'process raft request' (duration: 146.944004ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:53.70883Z","caller":"traceutil/trace.go:171","msg":"trace[1112362176] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:254; }","duration":"203.141761ms","start":"2026-05-12T11:20:53.50565Z","end":"2026-05-12T11:20:53.708792Z","steps":["trace[1112362176] 'read index received' (duration: 136.365754ms)","trace[1112362176] 'applied index is now lower than readState.Index' (duration: 66.774457ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:53.708908Z","caller":"traceutil/trace.go:171","msg":"trace[1857440091] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"212.883004ms","start":"2026-05-12T11:20:53.495989Z","end":"2026-05-12T11:20:53.708872Z","steps":["trace[1857440091] 'process raft request' (duration: 146.042105ms)","trace[1857440091] 'compare' (duration: 66.486577ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:53.709067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.431809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T11:20:53.709116Z","caller":"traceutil/trace.go:171","msg":"trace[1740730520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:250; }","duration":"128.556683ms","start":"2026-05-12T11:20:53.580546Z","end":"2026-05-12T11:20:53.709102Z","steps":["trace[1740730520] 'agreement among raft nodes before linearized reading' (duration: 128.428639ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:53.709045Z","caller":"traceutil/trace.go:171","msg":"trace[841765254] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"203.933656ms","start":"2026-05-12T11:20:53.505069Z","end":"2026-05-12T11:20:53.709003Z","steps":["trace[841765254] 'process raft request' (duration: 203.649246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:53.709254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.529693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:20:53.70943Z","caller":"traceutil/trace.go:171","msg":"trace[1285696234] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:250; }","duration":"203.781031ms","start":"2026-05-12T11:20:53.505598Z","end":"2026-05-12T11:20:53.709379Z","steps":["trace[1285696234] 'agreement among raft nodes before linearized reading' (duration: 203.322596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:53.960429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.763424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:20:53.96071Z","caller":"traceutil/trace.go:171","msg":"trace[1456611970] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"246.928279ms","start":"2026-05-12T11:20:53.713729Z","end":"2026-05-12T11:20:53.960658Z","steps":["trace[1456611970] 'process raft request' (duration: 96.441792ms)","trace[1456611970] 'compare' (duration: 149.609809ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:53.960819Z","caller":"traceutil/trace.go:171","msg":"trace[536894362] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"246.018249ms","start":"2026-05-12T11:20:53.714768Z","end":"2026-05-12T11:20:53.960786Z","steps":["trace[536894362] 'process raft request' (duration: 245.813792ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:53.960729Z","caller":"traceutil/trace.go:171","msg":"trace[1203476726] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"246.912378ms","start":"2026-05-12T11:20:53.713781Z","end":"2026-05-12T11:20:53.960693Z","steps":["trace[1203476726] 'process raft request' (duration: 246.750372ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:53.961035Z","caller":"traceutil/trace.go:171","msg":"trace[791231474] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"245.08948ms","start":"2026-05-12T11:20:53.715832Z","end":"2026-05-12T11:20:53.960921Z","steps":["trace[791231474] 'process raft request' (duration: 244.789489ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:53.961216Z","caller":"traceutil/trace.go:171","msg":"trace[953183509] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"216.078498ms","start":"2026-05-12T11:20:53.74512Z","end":"2026-05-12T11:20:53.961199Z","steps":["trace[953183509] 'process raft request' (duration: 215.54251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:54.1967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.487899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:20:54.196818Z","caller":"traceutil/trace.go:171","msg":"trace[981583725] linearizableReadLoop","detail":"{readStateIndex:265; appliedIndex:264; }","duration":"164.503791ms","start":"2026-05-12T11:20:54.032298Z","end":"2026-05-12T11:20:54.196802Z","steps":["trace[981583725] 'read index received' (duration: 32.601559ms)","trace[981583725] 'applied index is now lower than readState.Index' (duration: 131.900842ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:54.196898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.609044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:20:54.196934Z","caller":"traceutil/trace.go:171","msg":"trace[1098045776] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:259; }","duration":"164.683937ms","start":"2026-05-12T11:20:54.03224Z","end":"2026-05-12T11:20:54.196924Z","steps":["trace[1098045776] 'agreement among raft nodes before linearized reading' (duration: 164.606874ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:54.197167Z","caller":"traceutil/trace.go:171","msg":"trace[1580983696] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"193.029068ms","start":"2026-05-12T11:20:54.004076Z","end":"2026-05-12T11:20:54.197105Z","steps":["trace[1580983696] 'process raft request' (duration: 60.897458ms)","trace[1580983696] 'compare' (duration: 131.355145ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:54.331172Z","caller":"traceutil/trace.go:171","msg":"trace[769017141] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"127.438689ms","start":"2026-05-12T11:20:54.203675Z","end":"2026-05-12T11:20:54.331113Z","steps":["trace[769017141] 'process raft request' (duration: 114.566265ms)","trace[769017141] 'compare' (duration: 12.698608ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:57.485892Z","caller":"traceutil/trace.go:171","msg":"trace[154079251] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"162.785219ms","start":"2026-05-12T11:20:57.323079Z","end":"2026-05-12T11:20:57.485864Z","steps":["trace[154079251] 'process raft request' (duration: 130.52942ms)","trace[154079251] 'compare' (duration: 32.010111ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:57.486008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.952973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-12T11:20:57.485885Z","caller":"traceutil/trace.go:171","msg":"trace[1443956363] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:371; }","duration":"134.815009ms","start":"2026-05-12T11:20:57.351045Z","end":"2026-05-12T11:20:57.48586Z","steps":["trace[1443956363] 'read index received' (duration: 102.484438ms)","trace[1443956363] 'applied index is now lower than readState.Index' (duration: 32.329091ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:57.486345Z","caller":"traceutil/trace.go:171","msg":"trace[617145836] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"161.422705ms","start":"2026-05-12T11:20:57.324528Z","end":"2026-05-12T11:20:57.485951Z","steps":["trace[617145836] 'process raft request' (duration: 161.24703ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:57.486799Z","caller":"traceutil/trace.go:171","msg":"trace[1966075072] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:363; }","duration":"135.776449ms","start":"2026-05-12T11:20:57.35098Z","end":"2026-05-12T11:20:57.486757Z","steps":["trace[1966075072] 'agreement among raft nodes before linearized reading' (duration: 134.929822ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:57.716774Z","caller":"traceutil/trace.go:171","msg":"trace[77325814] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"136.927507ms","start":"2026-05-12T11:20:57.57982Z","end":"2026-05-12T11:20:57.716747Z","steps":["trace[77325814] 'process raft request' (duration: 103.977186ms)","trace[77325814] 'compare' (duration: 32.826327ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:57.784297Z","caller":"traceutil/trace.go:171","msg":"trace[550052402] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"199.412228ms","start":"2026-05-12T11:20:57.584864Z","end":"2026-05-12T11:20:57.784277Z","steps":["trace[550052402] 'process raft request' (duration: 199.286594ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:57.784674Z","caller":"traceutil/trace.go:171","msg":"trace[1142708391] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"191.270806ms","start":"2026-05-12T11:20:57.593387Z","end":"2026-05-12T11:20:57.784658Z","steps":["trace[1142708391] 'process raft request' (duration: 191.113191ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:57.784734Z","caller":"traceutil/trace.go:171","msg":"trace[1271913677] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"191.419301ms","start":"2026-05-12T11:20:57.593297Z","end":"2026-05-12T11:20:57.784716Z","steps":["trace[1271913677] 'process raft request' (duration: 190.931435ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:57.784724Z","caller":"traceutil/trace.go:171","msg":"trace[110763808] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"189.994524ms","start":"2026-05-12T11:20:57.59468Z","end":"2026-05-12T11:20:57.784675Z","steps":["trace[110763808] 'process raft request' (duration: 189.909391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:57.784801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.402004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-12T11:20:57.78486Z","caller":"traceutil/trace.go:171","msg":"trace[204034794] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:379; }","duration":"184.511529ms","start":"2026-05-12T11:20:57.600331Z","end":"2026-05-12T11:20:57.784843Z","steps":["trace[204034794] 'agreement among raft nodes before linearized reading' (duration: 184.378273ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:57.784665Z","caller":"traceutil/trace.go:171","msg":"trace[87304424] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:384; }","duration":"184.26909ms","start":"2026-05-12T11:20:57.600374Z","end":"2026-05-12T11:20:57.784643Z","steps":["trace[87304424] 'read index received' (duration: 83.383093ms)","trace[87304424] 'applied index is now lower than readState.Index' (duration: 100.884437ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:20:57.785002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.379535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-12T11:20:57.785036Z","caller":"traceutil/trace.go:171","msg":"trace[1840868761] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:379; }","duration":"134.475298ms","start":"2026-05-12T11:20:57.65055Z","end":"2026-05-12T11:20:57.785025Z","steps":["trace[1840868761] 'agreement among raft nodes before linearized reading' (duration: 134.406416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:58.064255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.675624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:20:58.064363Z","caller":"traceutil/trace.go:171","msg":"trace[1346823262] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"272.569883ms","start":"2026-05-12T11:20:57.791773Z","end":"2026-05-12T11:20:58.064343Z","steps":["trace[1346823262] 'process raft request' (duration: 64.730023ms)","trace[1346823262] 'compare' (duration: 207.507289ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:58.065816Z","caller":"traceutil/trace.go:171","msg":"trace[1018723546] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"272.74888ms","start":"2026-05-12T11:20:57.79303Z","end":"2026-05-12T11:20:58.065779Z","steps":["trace[1018723546] 'process raft request' (duration: 272.551153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:58.065905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.600059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-12T11:20:58.065954Z","caller":"traceutil/trace.go:171","msg":"trace[855662529] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:385; }","duration":"265.680982ms","start":"2026-05-12T11:20:57.80026Z","end":"2026-05-12T11:20:58.065941Z","steps":["trace[855662529] 'agreement among raft nodes before linearized reading' (duration: 265.524347ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:58.066029Z","caller":"traceutil/trace.go:171","msg":"trace[1693528945] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:389; }","duration":"265.492296ms","start":"2026-05-12T11:20:57.800288Z","end":"2026-05-12T11:20:58.06578Z","steps":["trace[1693528945] 'read index received' (duration: 56.22824ms)","trace[1693528945] 'applied index is now lower than readState.Index' (duration: 209.262696ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:58.065835Z","caller":"traceutil/trace.go:171","msg":"trace[1367624846] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"273.936898ms","start":"2026-05-12T11:20:57.791876Z","end":"2026-05-12T11:20:58.065813Z","steps":["trace[1367624846] 'process raft request' (duration: 273.636588ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:58.066141Z","caller":"traceutil/trace.go:171","msg":"trace[571524030] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"272.898943ms","start":"2026-05-12T11:20:57.79322Z","end":"2026-05-12T11:20:58.066119Z","steps":["trace[571524030] 'process raft request' (duration: 272.409088ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:58.066194Z","caller":"traceutil/trace.go:171","msg":"trace[320258790] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"270.699153ms","start":"2026-05-12T11:20:57.795484Z","end":"2026-05-12T11:20:58.066183Z","steps":["trace[320258790] 'process raft request' (duration: 270.259269ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:58.066148Z","caller":"traceutil/trace.go:171","msg":"trace[1868757426] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"271.946853ms","start":"2026-05-12T11:20:57.794186Z","end":"2026-05-12T11:20:58.066133Z","steps":["trace[1868757426] 'process raft request' (duration: 271.482938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:20:58.066339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.25376ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:20:58.066442Z","caller":"traceutil/trace.go:171","msg":"trace[1193356671] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:385; }","duration":"133.359333ms","start":"2026-05-12T11:20:57.933067Z","end":"2026-05-12T11:20:58.066427Z","steps":["trace[1193356671] 'agreement among raft nodes before linearized reading' (duration: 133.237949ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:58.251642Z","caller":"traceutil/trace.go:171","msg":"trace[1906456066] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"179.087326ms","start":"2026-05-12T11:20:58.072528Z","end":"2026-05-12T11:20:58.251615Z","steps":["trace[1906456066] 'process raft request' (duration: 92.53821ms)","trace[1906456066] 'compare' (duration: 86.337629ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:20:58.252015Z","caller":"traceutil/trace.go:171","msg":"trace[334545880] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"179.171569ms","start":"2026-05-12T11:20:58.072808Z","end":"2026-05-12T11:20:58.25198Z","steps":["trace[334545880] 'process raft request' (duration: 178.766486ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:58.252083Z","caller":"traceutil/trace.go:171","msg":"trace[120555014] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"176.364298ms","start":"2026-05-12T11:20:58.075689Z","end":"2026-05-12T11:20:58.252053Z","steps":["trace[120555014] 'process raft request' (duration: 176.303106ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:58.252165Z","caller":"traceutil/trace.go:171","msg":"trace[1488885783] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"177.516535ms","start":"2026-05-12T11:20:58.074636Z","end":"2026-05-12T11:20:58.252152Z","steps":["trace[1488885783] 'process raft request' (duration: 177.172134ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:58.252345Z","caller":"traceutil/trace.go:171","msg":"trace[1189188588] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"177.435482ms","start":"2026-05-12T11:20:58.074896Z","end":"2026-05-12T11:20:58.252331Z","steps":["trace[1189188588] 'process raft request' (duration: 177.04449ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:20:59.280548Z","caller":"traceutil/trace.go:171","msg":"trace[1802659761] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"298.063037ms","start":"2026-05-12T11:20:58.982459Z","end":"2026-05-12T11:20:59.280522Z","steps":["trace[1802659761] 'process raft request' (duration: 297.84388ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:21:00.222998Z","caller":"traceutil/trace.go:171","msg":"trace[1388053703] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"167.101213ms","start":"2026-05-12T11:21:00.055876Z","end":"2026-05-12T11:21:00.222977Z","steps":["trace[1388053703] 'process raft request' (duration: 166.921567ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:21:00.343522Z","caller":"traceutil/trace.go:171","msg":"trace[2086770944] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"117.701661ms","start":"2026-05-12T11:21:00.225797Z","end":"2026-05-12T11:21:00.343498Z","steps":["trace[2086770944] 'process raft request' (duration: 117.558586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:21:00.343696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.20018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-05-12T11:21:00.343736Z","caller":"traceutil/trace.go:171","msg":"trace[901540325] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:406; }","duration":"115.255832ms","start":"2026-05-12T11:21:00.228471Z","end":"2026-05-12T11:21:00.343727Z","steps":["trace[901540325] 'agreement among raft nodes before linearized reading' (duration: 115.112388ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:21:00.343539Z","caller":"traceutil/trace.go:171","msg":"trace[1952596194] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:415; }","duration":"115.037825ms","start":"2026-05-12T11:21:00.228475Z","end":"2026-05-12T11:21:00.343513Z","steps":["trace[1952596194] 'read index received' (duration: 114.87742ms)","trace[1952596194] 'applied index is now lower than readState.Index' (duration: 158.895µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:21:51.913762Z","caller":"traceutil/trace.go:171","msg":"trace[749346602] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"118.076852ms","start":"2026-05-12T11:21:51.795664Z","end":"2026-05-12T11:21:51.913741Z","steps":["trace[749346602] 'read index received' (duration: 117.906249ms)","trace[749346602] 'applied index is now lower than readState.Index' (duration: 169.743µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:21:51.913824Z","caller":"traceutil/trace.go:171","msg":"trace[1381509160] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"152.920789ms","start":"2026-05-12T11:21:51.760884Z","end":"2026-05-12T11:21:51.913804Z","steps":["trace[1381509160] 'process raft request' (duration: 152.725804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:21:51.913885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.214056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T11:21:51.913917Z","caller":"traceutil/trace.go:171","msg":"trace[1206118986] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"118.301158ms","start":"2026-05-12T11:21:51.795606Z","end":"2026-05-12T11:21:51.913907Z","steps":["trace[1206118986] 'agreement among raft nodes before linearized reading' (duration: 118.228966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:11.555414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415931678s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:22:11.555516Z","caller":"traceutil/trace.go:171","msg":"trace[1272794695] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"1.436562494s","start":"2026-05-12T11:22:10.118937Z","end":"2026-05-12T11:22:11.5555Z","steps":["trace[1272794695] 'read index received' (duration: 20.388861ms)","trace[1272794695] 'applied index is now lower than readState.Index' (duration: 1.416172473s)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:22:11.555608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.436672918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:22:11.555592Z","caller":"traceutil/trace.go:171","msg":"trace[1369355676] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.503498988s","start":"2026-05-12T11:22:10.052074Z","end":"2026-05-12T11:22:11.555573Z","steps":["trace[1369355676] 'process raft request' (duration: 87.282973ms)","trace[1369355676] 'compare' (duration: 1.415657252s)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:22:11.555635Z","caller":"traceutil/trace.go:171","msg":"trace[331831680] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:642; }","duration":"1.436728728s","start":"2026-05-12T11:22:10.118897Z","end":"2026-05-12T11:22:11.555625Z","steps":["trace[331831680] 'agreement among raft nodes before linearized reading' (duration: 1.436679937s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:11.555664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:10.11888Z","time spent":"1.43677649s","remote":"127.0.0.1:53606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-12T11:22:11.555678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:10.05204Z","time spent":"1.50359807s","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:22:11.555828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379850028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:22:11.555854Z","caller":"traceutil/trace.go:171","msg":"trace[2117858020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"1.37990008s","start":"2026-05-12T11:22:10.175945Z","end":"2026-05-12T11:22:11.555845Z","steps":["trace[2117858020] 'agreement among raft nodes before linearized reading' (duration: 1.379864449s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:11.555881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:10.175928Z","time spent":"1.379948441s","remote":"127.0.0.1:53470","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-05-12T11:22:11.555945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.845821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.215\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-12T11:22:11.555999Z","caller":"traceutil/trace.go:171","msg":"trace[1235021923] range","detail":"{range_begin:/registry/masterleases/199.19.213.215; range_end:; response_count:1; response_revision:642; }","duration":"117.897523ms","start":"2026-05-12T11:22:11.438085Z","end":"2026-05-12T11:22:11.555982Z","steps":["trace[1235021923] 'agreement among raft nodes before linearized reading' (duration: 117.80775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:11.556165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.203274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-12T11:22:11.5562Z","caller":"traceutil/trace.go:171","msg":"trace[2016278204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"771.261906ms","start":"2026-05-12T11:22:10.784927Z","end":"2026-05-12T11:22:11.556189Z","steps":["trace[2016278204] 'agreement among raft nodes before linearized reading' (duration: 771.187034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:11.556226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:10.784898Z","time spent":"771.321986ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T11:22:11.556308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.8986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T11:22:11.556389Z","caller":"traceutil/trace.go:171","msg":"trace[1135793005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"828.033503ms","start":"2026-05-12T11:22:10.728336Z","end":"2026-05-12T11:22:11.55637Z","steps":["trace[1135793005] 'agreement among raft nodes before linearized reading' (duration: 827.862189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:11.556448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:10.728317Z","time spent":"828.121436ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T11:22:13.690594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.812205ms","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-05-12T11:22:13.690723Z","caller":"traceutil/trace.go:171","msg":"trace[1483191012] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:712; }","duration":"184.958069ms","start":"2026-05-12T11:22:13.505747Z","end":"2026-05-12T11:22:13.690705Z","steps":["trace[1483191012] 'range keys from in-memory index tree' (duration: 184.794873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:13.692855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.593002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:22:13.692955Z","caller":"traceutil/trace.go:171","msg":"trace[1903033040] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"517.274049ms","start":"2026-05-12T11:22:13.175666Z","end":"2026-05-12T11:22:13.69294Z","steps":["trace[1903033040] 'read index received' (duration: 65.491µs)","trace[1903033040] 'applied index is now lower than readState.Index' (duration: 517.207308ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:22:13.693507Z","caller":"traceutil/trace.go:171","msg":"trace[289430719] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"985.012085ms","start":"2026-05-12T11:22:12.708482Z","end":"2026-05-12T11:22:13.693494Z","steps":["trace[289430719] 'process raft request' (duration: 401.711895ms)","trace[289430719] 'compare' (duration: 581.035135ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:22:13.693569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:12.708445Z","time spent":"985.098126ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:22:13.693848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.180811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:22:13.693888Z","caller":"traceutil/trace.go:171","msg":"trace[837952423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:713; }","duration":"518.259243ms","start":"2026-05-12T11:22:13.175617Z","end":"2026-05-12T11:22:13.693876Z","steps":["trace[837952423] 'agreement among raft nodes before linearized reading' (duration: 518.165781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:13.693916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:13.175598Z","time spent":"518.311315ms","remote":"127.0.0.1:53470","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-05-12T11:22:13.694079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.179353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:22:13.694112Z","caller":"traceutil/trace.go:171","msg":"trace[796697392] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:713; }","duration":"187.217984ms","start":"2026-05-12T11:22:13.506886Z","end":"2026-05-12T11:22:13.694104Z","steps":["trace[796697392] 'agreement among raft nodes before linearized reading' (duration: 187.166132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:13.695219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.937123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-12T11:22:13.695258Z","caller":"traceutil/trace.go:171","msg":"trace[1457623517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:713; }","duration":"113.014535ms","start":"2026-05-12T11:22:13.582234Z","end":"2026-05-12T11:22:13.695249Z","steps":["trace[1457623517] 'agreement among raft nodes before linearized reading' (duration: 112.894532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:13.695436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.538402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T11:22:13.69547Z","caller":"traceutil/trace.go:171","msg":"trace[1826658419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:713; }","duration":"114.614214ms","start":"2026-05-12T11:22:13.580847Z","end":"2026-05-12T11:22:13.695462Z","steps":["trace[1826658419] 'agreement among raft nodes before linearized reading' (duration: 114.532332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:13.69563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.24078ms","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-05-12T11:22:13.695665Z","caller":"traceutil/trace.go:171","msg":"trace[2000534631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:713; }","duration":"136.322553ms","start":"2026-05-12T11:22:13.559335Z","end":"2026-05-12T11:22:13.695658Z","steps":["trace[2000534631] 'agreement among raft nodes before linearized reading' (duration: 136.238361ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:13.829329Z","caller":"traceutil/trace.go:171","msg":"trace[1055611750] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"129.794314ms","start":"2026-05-12T11:22:13.699506Z","end":"2026-05-12T11:22:13.829301Z","steps":["trace[1055611750] 'process raft request' (duration: 106.972878ms)","trace[1055611750] 'compare' (duration: 22.711993ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:22:13.830258Z","caller":"traceutil/trace.go:171","msg":"trace[1852751612] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"129.115308ms","start":"2026-05-12T11:22:13.701098Z","end":"2026-05-12T11:22:13.830213Z","steps":["trace[1852751612] 'process raft request' (duration: 128.914783ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:13.830335Z","caller":"traceutil/trace.go:171","msg":"trace[2092509077] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"129.003565ms","start":"2026-05-12T11:22:13.70132Z","end":"2026-05-12T11:22:13.830323Z","steps":["trace[2092509077] 'process raft request' (duration: 128.762919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:18.326312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.865302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:22:18.326649Z","caller":"traceutil/trace.go:171","msg":"trace[1308029638] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"449.124796ms","start":"2026-05-12T11:22:17.87749Z","end":"2026-05-12T11:22:18.326615Z","steps":["trace[1308029638] 'process raft request' (duration: 152.866364ms)","trace[1308029638] 'compare' (duration: 295.7654ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:22:18.326735Z","caller":"traceutil/trace.go:171","msg":"trace[910876057] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:777; }","duration":"443.080427ms","start":"2026-05-12T11:22:17.883638Z","end":"2026-05-12T11:22:18.326718Z","steps":["trace[910876057] 'read index received' (duration: 146.735732ms)","trace[910876057] 'applied index is now lower than readState.Index' (duration: 296.343725ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:22:18.326823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:17.877476Z","time spent":"449.277239ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:22:18.326715Z","caller":"traceutil/trace.go:171","msg":"trace[2075937828] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"448.238594ms","start":"2026-05-12T11:22:17.878448Z","end":"2026-05-12T11:22:18.326686Z","steps":["trace[2075937828] 'process raft request' (duration: 448.013708ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:18.327024Z","caller":"traceutil/trace.go:171","msg":"trace[1811111622] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"447.408384ms","start":"2026-05-12T11:22:17.87956Z","end":"2026-05-12T11:22:18.326968Z","steps":["trace[1811111622] 'process raft request' (duration: 447.073765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:18.327109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.840593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-12T11:22:18.327184Z","caller":"traceutil/trace.go:171","msg":"trace[1652012131] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:748; }","duration":"237.932206ms","start":"2026-05-12T11:22:18.089238Z","end":"2026-05-12T11:22:18.327171Z","steps":["trace[1652012131] 'agreement among raft nodes before linearized reading' (duration: 237.749871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:18.327195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:17.879553Z","time spent":"447.508807ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:22:18.327335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.650832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:22:18.327467Z","caller":"traceutil/trace.go:171","msg":"trace[2011064695] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:748; }","duration":"443.831006ms","start":"2026-05-12T11:22:17.883614Z","end":"2026-05-12T11:22:18.327445Z","steps":["trace[2011064695] 'agreement among raft nodes before linearized reading' (duration: 443.634432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:18.32758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:17.883604Z","time spent":"443.963569ms","remote":"127.0.0.1:53884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"warn","ts":"2026-05-12T11:22:18.327646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.234937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mcvgg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-12T11:22:18.327767Z","caller":"traceutil/trace.go:171","msg":"trace[1240688178] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mcvgg; range_end:; response_count:1; response_revision:748; }","duration":"162.305109ms","start":"2026-05-12T11:22:18.165368Z","end":"2026-05-12T11:22:18.327673Z","steps":["trace[1240688178] 'agreement among raft nodes before linearized reading' (duration: 161.985901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:18.32772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:17.878433Z","time spent":"448.606385ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:22:18.472759Z","caller":"traceutil/trace.go:171","msg":"trace[44094892] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"132.370637ms","start":"2026-05-12T11:22:18.340363Z","end":"2026-05-12T11:22:18.472733Z","steps":["trace[44094892] 'process raft request' (duration: 95.731656ms)","trace[44094892] 'compare' (duration: 36.545678ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:22:20.056936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.584562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:22:20.057006Z","caller":"traceutil/trace.go:171","msg":"trace[795210488] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"1.335282774s","start":"2026-05-12T11:22:18.721712Z","end":"2026-05-12T11:22:20.056995Z","steps":["trace[795210488] 'process raft request' (duration: 744.577849ms)","trace[795210488] 'compare' (duration: 590.470549ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:22:20.057038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:18.721668Z","time spent":"1.335356166s","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:22:20.082096Z","caller":"traceutil/trace.go:171","msg":"trace[698013830] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:782; }","duration":"904.959094ms","start":"2026-05-12T11:22:19.177106Z","end":"2026-05-12T11:22:20.082065Z","steps":["trace[698013830] 'read index received' (duration: 289.250493ms)","trace[698013830] 'applied index is now lower than readState.Index' (duration: 615.707351ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:22:20.082139Z","caller":"traceutil/trace.go:171","msg":"trace[115660087] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"1.357444167s","start":"2026-05-12T11:22:18.724675Z","end":"2026-05-12T11:22:20.08212Z","steps":["trace[115660087] 'process raft request' (duration: 1.356711979s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:20.082234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:18.724652Z","time spent":"1.357535331s","remote":"127.0.0.1:53606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:22:20.082249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.103137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:22:20.082315Z","caller":"traceutil/trace.go:171","msg":"trace[1884586614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"905.23673ms","start":"2026-05-12T11:22:19.177062Z","end":"2026-05-12T11:22:20.082299Z","steps":["trace[1884586614] 'agreement among raft nodes before linearized reading' (duration: 905.101507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:20.08236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:19.177042Z","time spent":"905.308863ms","remote":"127.0.0.1:53470","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-05-12T11:22:20.082488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.903351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T11:22:20.082576Z","caller":"traceutil/trace.go:171","msg":"trace[1281465075] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:752; }","duration":"811.081815ms","start":"2026-05-12T11:22:19.271475Z","end":"2026-05-12T11:22:20.082557Z","steps":["trace[1281465075] 'agreement among raft nodes before linearized reading' (duration: 810.921081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:20.082631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:19.27144Z","time spent":"811.179267ms","remote":"127.0.0.1:54008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-05-12T11:22:20.082815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.655028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:22:20.082868Z","caller":"traceutil/trace.go:171","msg":"trace[578308096] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:752; }","duration":"883.276333ms","start":"2026-05-12T11:22:19.199578Z","end":"2026-05-12T11:22:20.082854Z","steps":["trace[578308096] 'agreement among raft nodes before linearized reading' (duration: 882.650408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:20.082942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:19.199569Z","time spent":"883.358505ms","remote":"127.0.0.1:53742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-12T11:22:21.656795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.853821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.215\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-12T11:22:21.656869Z","caller":"traceutil/trace.go:171","msg":"trace[650655640] range","detail":"{range_begin:/registry/masterleases/199.19.213.215; range_end:; response_count:1; response_revision:777; }","duration":"217.977804ms","start":"2026-05-12T11:22:21.438874Z","end":"2026-05-12T11:22:21.656852Z","steps":["trace[650655640] 'range keys from in-memory index tree' (duration: 217.702607ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:24.517122Z","caller":"traceutil/trace.go:171","msg":"trace[651245768] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"162.947793ms","start":"2026-05-12T11:22:24.354153Z","end":"2026-05-12T11:22:24.517101Z","steps":["trace[651245768] 'process raft request' (duration: 162.80091ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:24.523508Z","caller":"traceutil/trace.go:171","msg":"trace[185624631] linearizableReadLoop","detail":"{readStateIndex:863; appliedIndex:860; }","duration":"131.627111ms","start":"2026-05-12T11:22:24.391863Z","end":"2026-05-12T11:22:24.52349Z","steps":["trace[185624631] 'read index received' (duration: 125.098277ms)","trace[185624631] 'applied index is now lower than readState.Index' (duration: 6.527854ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:22:24.52354Z","caller":"traceutil/trace.go:171","msg":"trace[365771042] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"167.97415ms","start":"2026-05-12T11:22:24.355547Z","end":"2026-05-12T11:22:24.523521Z","steps":["trace[365771042] 'process raft request' (duration: 167.838567ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:24.523553Z","caller":"traceutil/trace.go:171","msg":"trace[1135818665] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"167.084109ms","start":"2026-05-12T11:22:24.356455Z","end":"2026-05-12T11:22:24.523539Z","steps":["trace[1135818665] 'process raft request' (duration: 167.008306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:24.523712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.833997ms","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-05-12T11:22:24.523755Z","caller":"traceutil/trace.go:171","msg":"trace[743189226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:830; }","duration":"131.916329ms","start":"2026-05-12T11:22:24.391829Z","end":"2026-05-12T11:22:24.523745Z","steps":["trace[743189226] 'agreement among raft nodes before linearized reading' (duration: 131.761815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:36.268188Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400674232202191613,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:22:36.314142Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.045624038s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T11:22:36.314571Z","caller":"traceutil/trace.go:171","msg":"trace[925957349] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"546.97699ms","start":"2026-05-12T11:22:35.767568Z","end":"2026-05-12T11:22:36.314545Z","steps":["trace[925957349] 'read index received' (duration: 546.728973ms)","trace[925957349] 'applied index is now lower than readState.Index' (duration: 247.106µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:22:36.314711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.071901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:22:36.31477Z","caller":"traceutil/trace.go:171","msg":"trace[247007600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"138.189465ms","start":"2026-05-12T11:22:36.176553Z","end":"2026-05-12T11:22:36.314743Z","steps":["trace[247007600] 'agreement among raft nodes before linearized reading' (duration: 138.107142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:36.314833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.247017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-12T11:22:36.314917Z","caller":"traceutil/trace.go:171","msg":"trace[951645273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:918; }","duration":"547.39216ms","start":"2026-05-12T11:22:35.767504Z","end":"2026-05-12T11:22:36.314896Z","steps":["trace[951645273] 'agreement among raft nodes before linearized reading' (duration: 547.109133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:36.314965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:35.767478Z","time spent":"547.477583ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-12T11:22:36.314656Z","caller":"traceutil/trace.go:171","msg":"trace[698722631] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"1.046195104s","start":"2026-05-12T11:22:35.26841Z","end":"2026-05-12T11:22:36.314606Z","steps":["trace[698722631] 'process raft request' (duration: 1.045950467s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:36.315314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:35.268384Z","time spent":"1.046743997s","remote":"127.0.0.1:53692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:22:40.657852Z","caller":"traceutil/trace.go:171","msg":"trace[1274317886] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"148.455739ms","start":"2026-05-12T11:22:40.509361Z","end":"2026-05-12T11:22:40.657816Z","steps":["trace[1274317886] 'process raft request' (duration: 113.350432ms)","trace[1274317886] 'compare' (duration: 34.887281ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:22:40.658093Z","caller":"traceutil/trace.go:171","msg":"trace[1617526147] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"147.509695ms","start":"2026-05-12T11:22:40.51056Z","end":"2026-05-12T11:22:40.658069Z","steps":["trace[1617526147] 'process raft request' (duration: 147.170796ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:40.658158Z","caller":"traceutil/trace.go:171","msg":"trace[620448844] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"138.451774ms","start":"2026-05-12T11:22:40.519685Z","end":"2026-05-12T11:22:40.658136Z","steps":["trace[620448844] 'process raft request' (duration: 138.093985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:40.658344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.782999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5266"} {"level":"info","ts":"2026-05-12T11:22:40.658409Z","caller":"traceutil/trace.go:171","msg":"trace[1033123102] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:958; }","duration":"145.877301ms","start":"2026-05-12T11:22:40.51251Z","end":"2026-05-12T11:22:40.658387Z","steps":["trace[1033123102] 'agreement among raft nodes before linearized reading' (duration: 145.693506ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:40.658945Z","caller":"traceutil/trace.go:171","msg":"trace[1168388920] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:991; }","duration":"145.519942ms","start":"2026-05-12T11:22:40.512546Z","end":"2026-05-12T11:22:40.658066Z","steps":["trace[1168388920] 'read index received' (duration: 16.821444ms)","trace[1168388920] 'applied index is now lower than readState.Index' (duration: 128.697748ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:22:40.850211Z","caller":"traceutil/trace.go:171","msg":"trace[1374712872] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"178.500472ms","start":"2026-05-12T11:22:40.671689Z","end":"2026-05-12T11:22:40.85019Z","steps":["trace[1374712872] 'process raft request' (duration: 178.463391ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:40.85068Z","caller":"traceutil/trace.go:171","msg":"trace[1103991575] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"179.373736ms","start":"2026-05-12T11:22:40.671296Z","end":"2026-05-12T11:22:40.85067Z","steps":["trace[1103991575] 'process raft request' (duration: 170.781339ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:40.850941Z","caller":"traceutil/trace.go:171","msg":"trace[209321813] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"179.627432ms","start":"2026-05-12T11:22:40.671304Z","end":"2026-05-12T11:22:40.850931Z","steps":["trace[209321813] 'process raft request' (duration: 178.663926ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:40.85107Z","caller":"traceutil/trace.go:171","msg":"trace[760518164] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"179.497409ms","start":"2026-05-12T11:22:40.671564Z","end":"2026-05-12T11:22:40.851062Z","steps":["trace[760518164] 'process raft request' (duration: 178.557075ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:40.851434Z","caller":"traceutil/trace.go:171","msg":"trace[1372385604] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"180.050133ms","start":"2026-05-12T11:22:40.671363Z","end":"2026-05-12T11:22:40.851413Z","steps":["trace[1372385604] 'process raft request' (duration: 178.653747ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:40.851753Z","caller":"traceutil/trace.go:171","msg":"trace[20434175] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"180.361772ms","start":"2026-05-12T11:22:40.671375Z","end":"2026-05-12T11:22:40.851737Z","steps":["trace[20434175] 'process raft request' (duration: 178.682658ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:40.851794Z","caller":"traceutil/trace.go:171","msg":"trace[2139671590] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"180.252469ms","start":"2026-05-12T11:22:40.671526Z","end":"2026-05-12T11:22:40.851778Z","steps":["trace[2139671590] 'process raft request' (duration: 178.568345ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:41.002113Z","caller":"traceutil/trace.go:171","msg":"trace[347231564] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"128.154954ms","start":"2026-05-12T11:22:40.873917Z","end":"2026-05-12T11:22:41.002071Z","steps":["trace[347231564] 'process raft request' (duration: 110.779156ms)","trace[347231564] 'compare' (duration: 17.123492ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:22:52.089601Z","caller":"traceutil/trace.go:171","msg":"trace[999117738] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"186.936913ms","start":"2026-05-12T11:22:51.902644Z","end":"2026-05-12T11:22:52.089581Z","steps":["trace[999117738] 'process raft request' (duration: 186.789149ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:23:27.365516Z","caller":"traceutil/trace.go:171","msg":"trace[1787342422] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1263; }","duration":"150.151355ms","start":"2026-05-12T11:23:27.215337Z","end":"2026-05-12T11:23:27.365488Z","steps":["trace[1787342422] 'read index received' (duration: 120.902965ms)","trace[1787342422] 'applied index is now lower than readState.Index' (duration: 29.24719ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:23:27.365603Z","caller":"traceutil/trace.go:171","msg":"trace[547911264] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"165.821099ms","start":"2026-05-12T11:23:27.199745Z","end":"2026-05-12T11:23:27.365566Z","steps":["trace[547911264] 'process raft request' (duration: 136.427824ms)","trace[547911264] 'compare' (duration: 29.20143ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:23:27.36585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.489824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-12T11:23:27.365855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.508297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-12T11:23:27.365953Z","caller":"traceutil/trace.go:171","msg":"trace[1425851792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1215; }","duration":"150.634218ms","start":"2026-05-12T11:23:27.215294Z","end":"2026-05-12T11:23:27.365928Z","steps":["trace[1425851792] 'agreement among raft nodes before linearized reading' (duration: 150.32828ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:23:27.36597Z","caller":"traceutil/trace.go:171","msg":"trace[46712267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1215; }","duration":"111.684562ms","start":"2026-05-12T11:23:27.254264Z","end":"2026-05-12T11:23:27.365949Z","steps":["trace[46712267] 'agreement among raft nodes before linearized reading' (duration: 111.399324ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:23:28.467219Z","caller":"traceutil/trace.go:171","msg":"trace[41165568] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"147.292126ms","start":"2026-05-12T11:23:28.319902Z","end":"2026-05-12T11:23:28.467194Z","steps":["trace[41165568] 'process raft request' (duration: 147.138642ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:23:33.561471Z","caller":"traceutil/trace.go:171","msg":"trace[445507963] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"134.195703ms","start":"2026-05-12T11:23:33.427244Z","end":"2026-05-12T11:23:33.56144Z","steps":["trace[445507963] 'process raft request' (duration: 133.971997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:23:33.747478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.057785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:23:33.747896Z","caller":"traceutil/trace.go:171","msg":"trace[868085224] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"319.357392ms","start":"2026-05-12T11:23:33.428502Z","end":"2026-05-12T11:23:33.747859Z","steps":["trace[868085224] 'process raft request' (duration: 195.815973ms)","trace[868085224] 'compare' (duration: 122.921941ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:23:33.748015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:23:33.428493Z","time spent":"319.475365ms","remote":"127.0.0.1:53770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:23:33.748274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.15402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T11:23:33.748388Z","caller":"traceutil/trace.go:171","msg":"trace[1382006745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1237; }","duration":"184.274383ms","start":"2026-05-12T11:23:33.564086Z","end":"2026-05-12T11:23:33.74836Z","steps":["trace[1382006745] 'agreement among raft nodes before linearized reading' (duration: 183.917003ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:23:33.748658Z","caller":"traceutil/trace.go:171","msg":"trace[654765745] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"183.807839ms","start":"2026-05-12T11:23:33.564103Z","end":"2026-05-12T11:23:33.747911Z","steps":["trace[654765745] 'read index received' (duration: 60.153577ms)","trace[654765745] 'applied index is now lower than readState.Index' (duration: 123.651982ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:24:08.843566Z","caller":"traceutil/trace.go:171","msg":"trace[1403537405] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"148.410843ms","start":"2026-05-12T11:24:08.695123Z","end":"2026-05-12T11:24:08.843534Z","steps":["trace[1403537405] 'process raft request' (duration: 147.505467ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:24:22.228827Z","caller":"traceutil/trace.go:171","msg":"trace[815223911] linearizableReadLoop","detail":"{readStateIndex:1488; appliedIndex:1487; }","duration":"155.972273ms","start":"2026-05-12T11:24:22.072831Z","end":"2026-05-12T11:24:22.228803Z","steps":["trace[815223911] 'read index received' (duration: 155.755817ms)","trace[815223911] 'applied index is now lower than readState.Index' (duration: 215.476µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:24:22.228913Z","caller":"traceutil/trace.go:171","msg":"trace[1797681646] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"221.118534ms","start":"2026-05-12T11:24:22.007738Z","end":"2026-05-12T11:24:22.228856Z","steps":["trace[1797681646] 'process raft request' (duration: 220.912758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:24:22.229159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.316894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-12T11:24:22.229213Z","caller":"traceutil/trace.go:171","msg":"trace[1834178022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1427; }","duration":"156.435327ms","start":"2026-05-12T11:24:22.072763Z","end":"2026-05-12T11:24:22.229198Z","steps":["trace[1834178022] 'agreement among raft nodes before linearized reading' (duration: 156.172929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:24:22.229194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.432118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:24:22.229301Z","caller":"traceutil/trace.go:171","msg":"trace[1294362897] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1427; }","duration":"118.647245ms","start":"2026-05-12T11:24:22.110629Z","end":"2026-05-12T11:24:22.229276Z","steps":["trace[1294362897] 'agreement among raft nodes before linearized reading' (duration: 118.373767ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:24:22.348501Z","caller":"traceutil/trace.go:171","msg":"trace[665585428] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"115.011357ms","start":"2026-05-12T11:24:22.23346Z","end":"2026-05-12T11:24:22.348471Z","steps":["trace[665585428] 'process raft request' (duration: 113.215874ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:24:31.522483Z","caller":"traceutil/trace.go:171","msg":"trace[407727939] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"292.091692ms","start":"2026-05-12T11:24:31.230355Z","end":"2026-05-12T11:24:31.522446Z","steps":["trace[407727939] 'process raft request' (duration: 291.264317ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:24:39.580284Z","caller":"traceutil/trace.go:171","msg":"trace[1683340316] linearizableReadLoop","detail":"{readStateIndex:1557; appliedIndex:1556; }","duration":"132.161616ms","start":"2026-05-12T11:24:39.448101Z","end":"2026-05-12T11:24:39.580262Z","steps":["trace[1683340316] 'read index received' (duration: 131.987071ms)","trace[1683340316] 'applied index is now lower than readState.Index' (duration: 173.645µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:24:39.580322Z","caller":"traceutil/trace.go:171","msg":"trace[149855849] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"135.407282ms","start":"2026-05-12T11:24:39.444888Z","end":"2026-05-12T11:24:39.580296Z","steps":["trace[149855849] 'process raft request' (duration: 135.227617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:24:39.580602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.493846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" limit:500 ","response":"range_response_count:12 size:74470"} {"level":"info","ts":"2026-05-12T11:24:39.580646Z","caller":"traceutil/trace.go:171","msg":"trace[266434624] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:12; response_revision:1493; }","duration":"132.613009ms","start":"2026-05-12T11:24:39.448023Z","end":"2026-05-12T11:24:39.580636Z","steps":["trace[266434624] 'agreement among raft nodes before linearized reading' (duration: 132.365102ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:24:39.698266Z","caller":"traceutil/trace.go:171","msg":"trace[427425276] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"111.435742ms","start":"2026-05-12T11:24:39.586792Z","end":"2026-05-12T11:24:39.698227Z","steps":["trace[427425276] 'process raft request' (duration: 59.442172ms)","trace[427425276] 'compare' (duration: 51.798484ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:24:39.699559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.338993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" limit:500 ","response":"range_response_count:135 size:101074"} {"level":"info","ts":"2026-05-12T11:24:39.699771Z","caller":"traceutil/trace.go:171","msg":"trace[2017828622] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:135; response_revision:1494; }","duration":"101.630852ms","start":"2026-05-12T11:24:39.598119Z","end":"2026-05-12T11:24:39.69975Z","steps":["trace[2017828622] 'agreement among raft nodes before linearized reading' (duration: 100.340083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:24:45.304233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.308631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:811"} {"level":"warn","ts":"2026-05-12T11:24:45.304275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.66229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:24:45.304353Z","caller":"traceutil/trace.go:171","msg":"trace[825337010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1513; }","duration":"127.801473ms","start":"2026-05-12T11:24:45.176534Z","end":"2026-05-12T11:24:45.304335Z","steps":["trace[825337010] 'range keys from in-memory index tree' (duration: 127.561636ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:24:45.304355Z","caller":"traceutil/trace.go:171","msg":"trace[1530793176] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1513; }","duration":"154.484386ms","start":"2026-05-12T11:24:45.149843Z","end":"2026-05-12T11:24:45.304327Z","steps":["trace[1530793176] 'range keys from in-memory index tree' (duration: 154.185518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:24:45.304298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.669654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-account-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-05-12T11:24:45.304587Z","caller":"traceutil/trace.go:171","msg":"trace[2118253159] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-account-controller; range_end:; response_count:1; response_revision:1513; }","duration":"146.053557ms","start":"2026-05-12T11:24:45.158506Z","end":"2026-05-12T11:24:45.304559Z","steps":["trace[2118253159] 'range keys from in-memory index tree' (duration: 145.50445ms)"],"step_count":1}