{"level":"warn","ts":"2026-05-06T14:14:51.143018Z","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-06T14:14:51.143113Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244: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-06T14:14:51.143208Z","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-06T14:14:51.143223Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-05-06T14:14:51.143246Z","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-06T14:14:51.143866Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-05-06T14:14:51.14397Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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-06T14:14:51.161999Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"17.753915ms"} {"level":"info","ts":"2026-05-06T14:14:51.175067Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-05-06T14:14:51.175151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T14:14:51.175174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-05-06T14:14:51.175186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T14:14:51.175194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-05-06T14:14:51.175228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-05-06T14:14:51.192292Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T14:14:51.195811Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T14:14:51.201002Z","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-06T14:14:51.204966Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T14:14:51.205373Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T14:14:51.205365Z","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-06T14:14:51.205452Z","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-06T14:14:51.205469Z","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-06T14:14:51.206672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-05-06T14:14:51.206859Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-05-06T14:14:51.210957Z","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-06T14:14:51.211251Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-05-06T14:14:51.211416Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-05-06T14:14:51.211591Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T14:14:51.211721Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T14:14:52.076179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T14:14:52.076231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T14:14:52.076273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-05-06T14:14:52.076288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-05-06T14:14:52.076295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-05-06T14:14:52.076305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-05-06T14:14:52.07632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-05-06T14:14:52.239607Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T14:14:52.241796Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T14:14:52.241829Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T14:14:52.241794Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T14:14:52.242297Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T14:14:52.242323Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T14:14:52.24262Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T14:14:52.243318Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T14:14:52.243491Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T14:14:52.244124Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T14:14:52.244485Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"info","ts":"2026-05-06T14:14:53.846942Z","caller":"traceutil/trace.go:171","msg":"trace[1038457066] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:15; }","duration":"144.322508ms","start":"2026-05-06T14:14:53.702595Z","end":"2026-05-06T14:14:53.846917Z","steps":["trace[1038457066] 'read index received' (duration: 77.524744ms)","trace[1038457066] 'applied index is now lower than readState.Index' (duration: 66.796784ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:14:53.847489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.847524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-05-06T14:14:53.84695Z","caller":"traceutil/trace.go:171","msg":"trace[1168986809] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"154.525093ms","start":"2026-05-06T14:14:53.692398Z","end":"2026-05-06T14:14:53.846923Z","steps":["trace[1168986809] 'process raft request' (duration: 154.40427ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:53.847601Z","caller":"traceutil/trace.go:171","msg":"trace[850133032] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18; }","duration":"145.056249ms","start":"2026-05-06T14:14:53.702524Z","end":"2026-05-06T14:14:53.84758Z","steps":["trace[850133032] 'agreement among raft nodes before linearized reading' (duration: 144.651858ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:53.84704Z","caller":"traceutil/trace.go:171","msg":"trace[1941360733] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"155.85232ms","start":"2026-05-06T14:14:53.691176Z","end":"2026-05-06T14:14:53.847028Z","steps":["trace[1941360733] 'process raft request' (duration: 88.799959ms)","trace[1941360733] 'compare' (duration: 66.610329ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:14:53.847068Z","caller":"traceutil/trace.go:171","msg":"trace[898476932] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13; }","duration":"155.817859ms","start":"2026-05-06T14:14:53.691243Z","end":"2026-05-06T14:14:53.847061Z","steps":["trace[898476932] 'process raft request' (duration: 155.473799ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:53.847136Z","caller":"traceutil/trace.go:171","msg":"trace[1943837603] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"154.770571ms","start":"2026-05-06T14:14:53.69235Z","end":"2026-05-06T14:14:53.847121Z","steps":["trace[1943837603] 'process raft request' (duration: 154.39062ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:53.847185Z","caller":"traceutil/trace.go:171","msg":"trace[1526329358] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"154.688639ms","start":"2026-05-06T14:14:53.69249Z","end":"2026-05-06T14:14:53.847178Z","steps":["trace[1526329358] 'process raft request' (duration: 154.337178ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:53.848282Z","caller":"traceutil/trace.go:171","msg":"trace[2087867261] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"144.664388ms","start":"2026-05-06T14:14:53.703538Z","end":"2026-05-06T14:14:53.848202Z","steps":["trace[2087867261] 'process raft request' (duration: 144.325989ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:53.847222Z","caller":"traceutil/trace.go:171","msg":"trace[53337451] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"154.431122ms","start":"2026-05-06T14:14:53.692784Z","end":"2026-05-06T14:14:53.847215Z","steps":["trace[53337451] 'process raft request' (duration: 154.096342ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:53.847243Z","caller":"traceutil/trace.go:171","msg":"trace[647793106] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"154.545025ms","start":"2026-05-06T14:14:53.692679Z","end":"2026-05-06T14:14:53.847224Z","steps":["trace[647793106] 'process raft request' (duration: 154.171864ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:53.848354Z","caller":"traceutil/trace.go:171","msg":"trace[1650853844] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"138.246379ms","start":"2026-05-06T14:14:53.71009Z","end":"2026-05-06T14:14:53.848336Z","steps":["trace[1650853844] 'process raft request' (duration: 138.034793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:57.320481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.839599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T14:14:57.321399Z","caller":"traceutil/trace.go:171","msg":"trace[1003861] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"255.367458ms","start":"2026-05-06T14:14:57.065262Z","end":"2026-05-06T14:14:57.320629Z","steps":["trace[1003861] 'process raft request' (duration: 100.863381ms)","trace[1003861] 'compare' (duration: 153.610733ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:14:57.321408Z","caller":"traceutil/trace.go:171","msg":"trace[287511792] linearizableReadLoop","detail":"{readStateIndex:246; appliedIndex:245; }","duration":"230.322987ms","start":"2026-05-06T14:14:57.090403Z","end":"2026-05-06T14:14:57.320726Z","steps":["trace[287511792] 'read index received' (duration: 75.727418ms)","trace[287511792] 'applied index is now lower than readState.Index' (duration: 154.591349ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:14:57.321769Z","caller":"traceutil/trace.go:171","msg":"trace[428608153] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"230.459081ms","start":"2026-05-06T14:14:57.091108Z","end":"2026-05-06T14:14:57.321567Z","steps":["trace[428608153] 'process raft request' (duration: 229.484484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:57.323783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.272917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:14:57.323969Z","caller":"traceutil/trace.go:171","msg":"trace[429885430] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:241; }","duration":"233.519544ms","start":"2026-05-06T14:14:57.090374Z","end":"2026-05-06T14:14:57.323893Z","steps":["trace[429885430] 'agreement among raft nodes before linearized reading' (duration: 231.283954ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:57.325163Z","caller":"traceutil/trace.go:171","msg":"trace[1586004372] transaction","detail":"{read_only:false; number_of_response:0; response_revision:243; }","duration":"117.880596ms","start":"2026-05-06T14:14:57.207262Z","end":"2026-05-06T14:14:57.325142Z","steps":["trace[1586004372] 'process raft request' (duration: 117.383203ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:57.325205Z","caller":"traceutil/trace.go:171","msg":"trace[726731688] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"233.989616ms","start":"2026-05-06T14:14:57.091173Z","end":"2026-05-06T14:14:57.325162Z","steps":["trace[726731688] 'process raft request' (duration: 229.926927ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:57.325196Z","caller":"traceutil/trace.go:171","msg":"trace[1033548569] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"117.760713ms","start":"2026-05-06T14:14:57.207397Z","end":"2026-05-06T14:14:57.325158Z","steps":["trace[1033548569] 'process raft request' (duration: 117.275201ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:57.325355Z","caller":"traceutil/trace.go:171","msg":"trace[1205186953] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"119.004846ms","start":"2026-05-06T14:14:57.206333Z","end":"2026-05-06T14:14:57.325338Z","steps":["trace[1205186953] 'process raft request' (duration: 118.145974ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:57.325228Z","caller":"traceutil/trace.go:171","msg":"trace[401078739] transaction","detail":"{read_only:false; number_of_response:0; response_revision:242; }","duration":"138.57805ms","start":"2026-05-06T14:14:57.186606Z","end":"2026-05-06T14:14:57.325184Z","steps":["trace[401078739] 'process raft request' (duration: 137.765859ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:57.325833Z","caller":"traceutil/trace.go:171","msg":"trace[1831653697] transaction","detail":"{read_only:false; number_of_response:0; response_revision:243; }","duration":"119.315265ms","start":"2026-05-06T14:14:57.206492Z","end":"2026-05-06T14:14:57.325807Z","steps":["trace[1831653697] 'process raft request' (duration: 118.104993ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:57.326006Z","caller":"traceutil/trace.go:171","msg":"trace[288619699] transaction","detail":"{read_only:false; number_of_response:0; response_revision:242; }","duration":"119.478149ms","start":"2026-05-06T14:14:57.206304Z","end":"2026-05-06T14:14:57.325782Z","steps":["trace[288619699] 'process raft request' (duration: 118.139634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:57.326821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.297798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-06T14:14:57.326911Z","caller":"traceutil/trace.go:171","msg":"trace[734512210] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:244; }","duration":"236.401351ms","start":"2026-05-06T14:14:57.090472Z","end":"2026-05-06T14:14:57.326874Z","steps":["trace[734512210] 'agreement among raft nodes before linearized reading' (duration: 234.272024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:57.772336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.393963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T14:14:57.772573Z","caller":"traceutil/trace.go:171","msg":"trace[544328332] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"362.713654ms","start":"2026-05-06T14:14:57.409849Z","end":"2026-05-06T14:14:57.772562Z","steps":["trace[544328332] 'process raft request' (duration: 362.685744ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:57.772526Z","caller":"traceutil/trace.go:171","msg":"trace[517291479] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"441.021111ms","start":"2026-05-06T14:14:57.331467Z","end":"2026-05-06T14:14:57.772488Z","steps":["trace[517291479] 'process raft request' (duration: 316.415964ms)","trace[517291479] 'compare' (duration: 124.169086ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:14:57.772673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:57.409817Z","time spent":"362.793207ms","remote":"127.0.0.1:54398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:14:57.772763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:57.331454Z","time spent":"441.190665ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T14:14:57.772931Z","caller":"traceutil/trace.go:171","msg":"trace[1215356270] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"439.49086ms","start":"2026-05-06T14:14:57.33343Z","end":"2026-05-06T14:14:57.772921Z","steps":["trace[1215356270] 'process raft request' (duration: 439.060708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:57.772986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:57.333421Z","time spent":"439.542621ms","remote":"127.0.0.1:54796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3200,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T14:14:57.773126Z","caller":"traceutil/trace.go:171","msg":"trace[706824114] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:254; }","duration":"441.463343ms","start":"2026-05-06T14:14:57.331651Z","end":"2026-05-06T14:14:57.773114Z","steps":["trace[706824114] 'read index received' (duration: 73.807537ms)","trace[706824114] 'applied index is now lower than readState.Index' (duration: 367.654296ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:14:57.773127Z","caller":"traceutil/trace.go:171","msg":"trace[507483059] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"439.513181ms","start":"2026-05-06T14:14:57.333589Z","end":"2026-05-06T14:14:57.773102Z","steps":["trace[507483059] 'process raft request' (duration: 438.871934ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:57.773363Z","caller":"traceutil/trace.go:171","msg":"trace[547244980] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"440.329812ms","start":"2026-05-06T14:14:57.332864Z","end":"2026-05-06T14:14:57.773194Z","steps":["trace[547244980] 'process raft request' (duration: 439.555921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:57.773423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.757401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-06T14:14:57.773469Z","caller":"traceutil/trace.go:171","msg":"trace[441044244] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:250; }","duration":"441.817722ms","start":"2026-05-06T14:14:57.331636Z","end":"2026-05-06T14:14:57.773454Z","steps":["trace[441044244] 'agreement among raft nodes before linearized reading' (duration: 441.567765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:57.773541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:57.331628Z","time spent":"441.902485ms","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-06T14:14:57.773493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:57.333576Z","time spent":"439.797108ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:14:57.773647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:57.332855Z","time spent":"440.583098ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:14:58.504728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.50387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T14:14:58.505052Z","caller":"traceutil/trace.go:171","msg":"trace[1110727597] linearizableReadLoop","detail":"{readStateIndex:265; appliedIndex:262; }","duration":"661.840587ms","start":"2026-05-06T14:14:57.843194Z","end":"2026-05-06T14:14:58.505034Z","steps":["trace[1110727597] 'read index received' (duration: 136.947577ms)","trace[1110727597] 'applied index is now lower than readState.Index' (duration: 524.89168ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:14:58.505128Z","caller":"traceutil/trace.go:171","msg":"trace[1932800356] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"717.551789ms","start":"2026-05-06T14:14:57.787565Z","end":"2026-05-06T14:14:58.505117Z","steps":["trace[1932800356] 'process raft request' (duration: 192.561236ms)","trace[1932800356] 'compare' (duration: 524.081399ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:14:58.505246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:57.787541Z","time spent":"717.613021ms","remote":"127.0.0.1:54398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:14:58.505576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.368361ms","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-06T14:14:58.505604Z","caller":"traceutil/trace.go:171","msg":"trace[2144158458] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:256; }","duration":"662.437812ms","start":"2026-05-06T14:14:57.843156Z","end":"2026-05-06T14:14:58.505594Z","steps":["trace[2144158458] 'agreement among raft nodes before linearized reading' (duration: 662.3342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:58.505623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:57.843146Z","time spent":"662.473434ms","remote":"127.0.0.1:54440","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-06T14:14:58.50552Z","caller":"traceutil/trace.go:171","msg":"trace[2128879923] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"713.426468ms","start":"2026-05-06T14:14:57.792036Z","end":"2026-05-06T14:14:58.505463Z","steps":["trace[2128879923] 'process raft request' (duration: 712.810172ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:58.505724Z","caller":"traceutil/trace.go:171","msg":"trace[1474649860] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"660.665805ms","start":"2026-05-06T14:14:57.844796Z","end":"2026-05-06T14:14:58.505462Z","steps":["trace[1474649860] 'process raft request' (duration: 660.202693ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:58.50528Z","caller":"traceutil/trace.go:171","msg":"trace[689711130] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"663.280225ms","start":"2026-05-06T14:14:57.841946Z","end":"2026-05-06T14:14:58.505226Z","steps":["trace[689711130] 'process raft request' (duration: 663.017838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:58.506888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:57.844784Z","time spent":"661.97917ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:14:58.50616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:57.792026Z","time spent":"713.721515ms","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4183,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:14:58.506989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:57.841922Z","time spent":"664.846527ms","remote":"127.0.0.1:54378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T14:14:58.568768Z","caller":"traceutil/trace.go:171","msg":"trace[1504006511] transaction","detail":"{read_only:false; number_of_response:0; response_revision:256; }","duration":"450.148274ms","start":"2026-05-06T14:14:58.118595Z","end":"2026-05-06T14:14:58.568743Z","steps":["trace[1504006511] 'process raft request' (duration: 449.814146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:58.568799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.624974ms","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-06T14:14:58.568915Z","caller":"traceutil/trace.go:171","msg":"trace[850710277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:256; }","duration":"491.817619ms","start":"2026-05-06T14:14:58.077067Z","end":"2026-05-06T14:14:58.568885Z","steps":["trace[850710277] 'agreement among raft nodes before linearized reading' (duration: 491.584533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:58.568942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.282174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"warn","ts":"2026-05-06T14:14:58.56898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:58.077036Z","time spent":"491.933462ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T14:14:58.569031Z","caller":"traceutil/trace.go:171","msg":"trace[1344403604] transaction","detail":"{read_only:false; number_of_response:0; response_revision:256; }","duration":"449.471135ms","start":"2026-05-06T14:14:58.119521Z","end":"2026-05-06T14:14:58.568992Z","steps":["trace[1344403604] 'process raft request' (duration: 449.073655ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:58.569034Z","caller":"traceutil/trace.go:171","msg":"trace[1980213277] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:256; }","duration":"451.447088ms","start":"2026-05-06T14:14:58.117561Z","end":"2026-05-06T14:14:58.569008Z","steps":["trace[1980213277] 'agreement among raft nodes before linearized reading' (duration: 451.268664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:58.569053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:58.118569Z","time spent":"450.287797ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:14:58.569114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:58.117531Z","time spent":"451.569861ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3488,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-05-06T14:14:58.569168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.969869ms","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-06T14:14:58.56933Z","caller":"traceutil/trace.go:171","msg":"trace[355500103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:256; }","duration":"244.41697ms","start":"2026-05-06T14:14:58.324855Z","end":"2026-05-06T14:14:58.569272Z","steps":["trace[355500103] 'agreement among raft nodes before linearized reading' (duration: 243.947768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:58.569587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:58.119512Z","time spent":"449.567538ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T14:14:58.568754Z","caller":"traceutil/trace.go:171","msg":"trace[1179144607] transaction","detail":"{read_only:false; number_of_response:0; response_revision:256; }","duration":"449.486295ms","start":"2026-05-06T14:14:58.119212Z","end":"2026-05-06T14:14:58.568699Z","steps":["trace[1179144607] 'process raft request' (duration: 449.342781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:58.569973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:58.119192Z","time spent":"450.701978ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:14:58.980396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.210087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T14:14:58.980644Z","caller":"traceutil/trace.go:171","msg":"trace[1524191485] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:269; }","duration":"409.35746ms","start":"2026-05-06T14:14:58.571263Z","end":"2026-05-06T14:14:58.98062Z","steps":["trace[1524191485] 'read index received' (duration: 231.843185ms)","trace[1524191485] 'applied index is now lower than readState.Index' (duration: 177.512705ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:14:58.980773Z","caller":"traceutil/trace.go:171","msg":"trace[852668683] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"469.215077ms","start":"2026-05-06T14:14:58.511513Z","end":"2026-05-06T14:14:58.980728Z","steps":["trace[852668683] 'process raft request' (duration: 469.031742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:58.980826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.550515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-05-06T14:14:58.980858Z","caller":"traceutil/trace.go:171","msg":"trace[1095966804] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:258; }","duration":"409.632077ms","start":"2026-05-06T14:14:58.571214Z","end":"2026-05-06T14:14:58.980846Z","steps":["trace[1095966804] 'agreement among raft nodes before linearized reading' (duration: 409.509724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:58.980898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:58.571194Z","time spent":"409.695659ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3488,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-05-06T14:14:58.980909Z","caller":"traceutil/trace.go:171","msg":"trace[2016583066] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"471.096476ms","start":"2026-05-06T14:14:58.509771Z","end":"2026-05-06T14:14:58.980867Z","steps":["trace[2016583066] 'process raft request' (duration: 293.193912ms)","trace[2016583066] 'compare' (duration: 176.909048ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:14:58.980924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:58.511502Z","time spent":"469.34626ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:14:58.981002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:58.509738Z","time spent":"471.2252ms","remote":"127.0.0.1:54510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1196,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:14:58.98344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.027621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-05-06T14:14:58.983522Z","caller":"traceutil/trace.go:171","msg":"trace[583460999] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:261; }","duration":"412.139663ms","start":"2026-05-06T14:14:58.571365Z","end":"2026-05-06T14:14:58.983504Z","steps":["trace[583460999] 'agreement among raft nodes before linearized reading' (duration: 411.990479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:58.983564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:58.571356Z","time spent":"412.199725ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5919,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-05-06T14:14:58.983603Z","caller":"traceutil/trace.go:171","msg":"trace[1033075060] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"408.61055ms","start":"2026-05-06T14:14:58.574963Z","end":"2026-05-06T14:14:58.983573Z","steps":["trace[1033075060] 'process raft request' (duration: 407.866511ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:58.983792Z","caller":"traceutil/trace.go:171","msg":"trace[1675766888] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"393.721945ms","start":"2026-05-06T14:14:58.590058Z","end":"2026-05-06T14:14:58.98378Z","steps":["trace[1675766888] 'process raft request' (duration: 393.216412ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:14:58.98384Z","caller":"traceutil/trace.go:171","msg":"trace[198706978] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"408.679422ms","start":"2026-05-06T14:14:58.575096Z","end":"2026-05-06T14:14:58.983775Z","steps":["trace[198706978] 'process raft request' (duration: 408.088967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:58.983903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:58.590017Z","time spent":"393.818128ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:14:58.984003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:58.575063Z","time spent":"408.824086ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:14:58.98407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.904388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3978"} {"level":"info","ts":"2026-05-06T14:14:58.984118Z","caller":"traceutil/trace.go:171","msg":"trace[330369494] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:261; }","duration":"412.053841ms","start":"2026-05-06T14:14:58.572049Z","end":"2026-05-06T14:14:58.984103Z","steps":["trace[330369494] 'agreement among raft nodes before linearized reading' (duration: 411.950888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:14:58.984152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:58.571987Z","time spent":"412.157474ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4000,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-05-06T14:14:58.984356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:14:58.574943Z","time spent":"408.798615ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:15:07.398051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.151851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6964"} {"level":"info","ts":"2026-05-06T14:15:07.398238Z","caller":"traceutil/trace.go:171","msg":"trace[967785949] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:362; }","duration":"256.393287ms","start":"2026-05-06T14:15:07.141824Z","end":"2026-05-06T14:15:07.398217Z","steps":["trace[967785949] 'range keys from in-memory index tree' (duration: 256.037949ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:07.854293Z","caller":"traceutil/trace.go:171","msg":"trace[712584474] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"448.216528ms","start":"2026-05-06T14:15:07.406051Z","end":"2026-05-06T14:15:07.854267Z","steps":["trace[712584474] 'process raft request' (duration: 448.082775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:07.85444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:07.406021Z","time spent":"448.34466ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T14:15:07.96871Z","caller":"traceutil/trace.go:171","msg":"trace[172275287] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"106.990634ms","start":"2026-05-06T14:15:07.861697Z","end":"2026-05-06T14:15:07.968687Z","steps":["trace[172275287] 'process raft request' (duration: 75.854269ms)","trace[172275287] 'compare' (duration: 30.904529ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:08.727176Z","caller":"traceutil/trace.go:171","msg":"trace[1709150617] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"169.506959ms","start":"2026-05-06T14:15:08.557645Z","end":"2026-05-06T14:15:08.727152Z","steps":["trace[1709150617] 'process raft request' (duration: 169.380676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:09.231247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.113362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T14:15:09.231346Z","caller":"traceutil/trace.go:171","msg":"trace[823755646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:369; }","duration":"127.287467ms","start":"2026-05-06T14:15:09.104034Z","end":"2026-05-06T14:15:09.231322Z","steps":["trace[823755646] 'range keys from in-memory index tree' (duration: 126.936818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:09.606887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.105964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:09.606994Z","caller":"traceutil/trace.go:171","msg":"trace[646461857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"220.261758ms","start":"2026-05-06T14:15:09.386708Z","end":"2026-05-06T14:15:09.60697Z","steps":["trace[646461857] 'range keys from in-memory index tree' (duration: 220.022443ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:10.028368Z","caller":"traceutil/trace.go:171","msg":"trace[177650134] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"266.621795ms","start":"2026-05-06T14:15:09.761727Z","end":"2026-05-06T14:15:10.028349Z","steps":["trace[177650134] 'read index received' (duration: 266.460102ms)","trace[177650134] 'applied index is now lower than readState.Index' (duration: 160.933µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:10.028457Z","caller":"traceutil/trace.go:171","msg":"trace[1196547925] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"408.724184ms","start":"2026-05-06T14:15:09.619724Z","end":"2026-05-06T14:15:10.028448Z","steps":["trace[1196547925] 'process raft request' (duration: 408.484118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:10.028542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:09.619711Z","time spent":"408.765705ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:15:10.0286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.857931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:10.028676Z","caller":"traceutil/trace.go:171","msg":"trace[1821936180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"266.961544ms","start":"2026-05-06T14:15:09.761694Z","end":"2026-05-06T14:15:10.028655Z","steps":["trace[1821936180] 'agreement among raft nodes before linearized reading' (duration: 266.833391ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:10.278408Z","caller":"traceutil/trace.go:171","msg":"trace[1803883097] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"246.078666ms","start":"2026-05-06T14:15:10.032302Z","end":"2026-05-06T14:15:10.278381Z","steps":["trace[1803883097] 'process raft request' (duration: 183.741778ms)","trace[1803883097] 'compare' (duration: 62.215165ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:10.354918Z","caller":"traceutil/trace.go:171","msg":"trace[1145837184] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"315.975162ms","start":"2026-05-06T14:15:10.038913Z","end":"2026-05-06T14:15:10.354888Z","steps":["trace[1145837184] 'process raft request' (duration: 315.823128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:10.355083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:10.038904Z","time spent":"316.096295ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T14:15:11.892582Z","caller":"traceutil/trace.go:171","msg":"trace[916019657] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"330.531333ms","start":"2026-05-06T14:15:11.562028Z","end":"2026-05-06T14:15:11.892559Z","steps":["trace[916019657] 'process raft request' (duration: 330.41561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:11.892844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:11.56199Z","time spent":"330.670286ms","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4361,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T14:15:13.230042Z","caller":"traceutil/trace.go:171","msg":"trace[143401665] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"362.533213ms","start":"2026-05-06T14:15:12.867479Z","end":"2026-05-06T14:15:13.230012Z","steps":["trace[143401665] 'process raft request' (duration: 362.366779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:13.230184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:12.867459Z","time spent":"362.657695ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:15:18.848564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199481292827321,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T14:15:19.110944Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.03445569s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T14:15:19.111376Z","caller":"traceutil/trace.go:171","msg":"trace[306590200] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"1.03493508s","start":"2026-05-06T14:15:18.076417Z","end":"2026-05-06T14:15:19.111352Z","steps":["trace[306590200] 'process raft request' (duration: 1.034728046s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:19.111478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:18.076396Z","time spent":"1.035013821s","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T14:15:19.111494Z","caller":"traceutil/trace.go:171","msg":"trace[1425520962] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"763.111093ms","start":"2026-05-06T14:15:18.348166Z","end":"2026-05-06T14:15:19.111277Z","steps":["trace[1425520962] 'read index received' (duration: 762.93697ms)","trace[1425520962] 'applied index is now lower than readState.Index' (duration: 173.003µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:19.111546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.365639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-06T14:15:19.1116Z","caller":"traceutil/trace.go:171","msg":"trace[1377421590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:421; }","duration":"763.459091ms","start":"2026-05-06T14:15:18.348126Z","end":"2026-05-06T14:15:19.111585Z","steps":["trace[1377421590] 'agreement among raft nodes before linearized reading' (duration: 763.353789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:19.111632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:18.348109Z","time spent":"763.514622ms","remote":"127.0.0.1:54576","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-06T14:15:19.111646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.54957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:19.111674Z","caller":"traceutil/trace.go:171","msg":"trace[2060081611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"335.605862ms","start":"2026-05-06T14:15:18.776058Z","end":"2026-05-06T14:15:19.111664Z","steps":["trace[2060081611] 'agreement among raft nodes before linearized reading' (duration: 335.563781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:19.111696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:18.776039Z","time spent":"335.651062ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T14:15:23.288287Z","caller":"traceutil/trace.go:171","msg":"trace[496250931] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"132.484442ms","start":"2026-05-06T14:15:23.155786Z","end":"2026-05-06T14:15:23.28827Z","steps":["trace[496250931] 'process raft request' (duration: 132.38287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:27.802499Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199481292827437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T14:15:27.937595Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.12920994s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T14:15:27.937974Z","caller":"traceutil/trace.go:171","msg":"trace[1384840212] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"1.129662629s","start":"2026-05-06T14:15:26.808284Z","end":"2026-05-06T14:15:27.937947Z","steps":["trace[1384840212] 'process raft request' (duration: 1.129552297s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:27.938125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:26.808265Z","time spent":"1.129783562s","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T14:15:27.946978Z","caller":"traceutil/trace.go:171","msg":"trace[606240808] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"1.136235342s","start":"2026-05-06T14:15:26.810714Z","end":"2026-05-06T14:15:27.946949Z","steps":["trace[606240808] 'process raft request' (duration: 1.136010938s)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:27.947038Z","caller":"traceutil/trace.go:171","msg":"trace[118870042] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"1.134922494s","start":"2026-05-06T14:15:26.812078Z","end":"2026-05-06T14:15:27.947Z","steps":["trace[118870042] 'process raft request' (duration: 1.134733801s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:27.947128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:26.810693Z","time spent":"1.136348994s","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:15:27.94719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:26.812068Z","time spent":"1.135045317s","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T14:15:27.947282Z","caller":"traceutil/trace.go:171","msg":"trace[1719550003] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"1.128702468s","start":"2026-05-06T14:15:26.818566Z","end":"2026-05-06T14:15:27.947269Z","steps":["trace[1719550003] 'process raft request' (duration: 1.128419412s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:27.947342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:26.818554Z","time spent":"1.12875045s","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T14:15:27.947411Z","caller":"traceutil/trace.go:171","msg":"trace[2068292564] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.118149423s","start":"2026-05-06T14:15:26.829238Z","end":"2026-05-06T14:15:27.947387Z","steps":["trace[2068292564] 'process raft request' (duration: 1.117877658s)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:27.947422Z","caller":"traceutil/trace.go:171","msg":"trace[1970267112] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"1.129383962s","start":"2026-05-06T14:15:26.81802Z","end":"2026-05-06T14:15:27.947404Z","steps":["trace[1970267112] 'process raft request' (duration: 1.128871921s)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:27.947406Z","caller":"traceutil/trace.go:171","msg":"trace[1650108690] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:493; }","duration":"645.30667ms","start":"2026-05-06T14:15:27.302083Z","end":"2026-05-06T14:15:27.94739Z","steps":["trace[1650108690] 'read index received' (duration: 635.828619ms)","trace[1650108690] 'applied index is now lower than readState.Index' (duration: 9.477201ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:27.94751Z","caller":"traceutil/trace.go:171","msg":"trace[1303419915] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"1.129426642s","start":"2026-05-06T14:15:26.818074Z","end":"2026-05-06T14:15:27.9475Z","steps":["trace[1303419915] 'process raft request' (duration: 1.128874601s)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:27.947516Z","caller":"traceutil/trace.go:171","msg":"trace[955831019] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"1.118042341s","start":"2026-05-06T14:15:26.829464Z","end":"2026-05-06T14:15:27.947507Z","steps":["trace[955831019] 'process raft request' (duration: 1.117695844s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:27.947521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:26.818004Z","time spent":"1.129478204s","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:15:27.947555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:26.818069Z","time spent":"1.129463563s","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T14:15:27.947572Z","caller":"traceutil/trace.go:171","msg":"trace[618151555] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.127895161s","start":"2026-05-06T14:15:26.819664Z","end":"2026-05-06T14:15:27.947559Z","steps":["trace[618151555] 'process raft request' (duration: 1.127393511s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:27.947521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:26.829217Z","time spent":"1.118256035s","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:15:27.947633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:26.819649Z","time spent":"1.127954112s","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T14:15:27.947665Z","caller":"traceutil/trace.go:171","msg":"trace[193160539] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"1.105386812s","start":"2026-05-06T14:15:26.842268Z","end":"2026-05-06T14:15:27.947655Z","steps":["trace[193160539] 'process raft request' (duration: 1.104946714s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:27.947569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:26.829451Z","time spent":"1.118087141s","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:15:27.94778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:26.842252Z","time spent":"1.105470174s","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T14:15:27.947785Z","caller":"traceutil/trace.go:171","msg":"trace[1958675338] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"967.842047ms","start":"2026-05-06T14:15:26.979926Z","end":"2026-05-06T14:15:27.947768Z","steps":["trace[1958675338] 'process raft request' (duration: 967.406668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:27.947869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:26.979904Z","time spent":"967.933958ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T14:15:27.947966Z","caller":"traceutil/trace.go:171","msg":"trace[1470876977] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.095055061s","start":"2026-05-06T14:15:26.8529Z","end":"2026-05-06T14:15:27.947955Z","steps":["trace[1470876977] 'process raft request' (duration: 1.094369838s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:27.948034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:26.85288Z","time spent":"1.095112043s","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:15:27.9481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.012624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-06T14:15:27.948161Z","caller":"traceutil/trace.go:171","msg":"trace[891219542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:485; }","duration":"646.113476ms","start":"2026-05-06T14:15:27.302032Z","end":"2026-05-06T14:15:27.948146Z","steps":["trace[891219542] 'agreement among raft nodes before linearized reading' (duration: 645.863491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:27.948211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:27.302012Z","time spent":"646.186717ms","remote":"127.0.0.1:54576","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-06T14:15:27.95349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.313151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-06T14:15:27.953565Z","caller":"traceutil/trace.go:171","msg":"trace[1583808294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"604.443244ms","start":"2026-05-06T14:15:27.349105Z","end":"2026-05-06T14:15:27.953548Z","steps":["trace[1583808294] 'agreement among raft nodes before linearized reading' (duration: 604.23654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:27.953615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:27.349082Z","time spent":"604.520465ms","remote":"127.0.0.1:54576","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-06T14:15:27.953814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.049428ms","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-06T14:15:27.953871Z","caller":"traceutil/trace.go:171","msg":"trace[904402405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:487; }","duration":"567.13245ms","start":"2026-05-06T14:15:27.386725Z","end":"2026-05-06T14:15:27.953858Z","steps":["trace[904402405] 'agreement among raft nodes before linearized reading' (duration: 566.950895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:27.953858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.328418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T14:15:27.953904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:27.386712Z","time spent":"567.184702ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T14:15:27.953918Z","caller":"traceutil/trace.go:171","msg":"trace[304632625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"190.414901ms","start":"2026-05-06T14:15:27.763485Z","end":"2026-05-06T14:15:27.9539Z","steps":["trace[304632625] 'agreement among raft nodes before linearized reading' (duration: 190.307797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:27.953898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.071474ms","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-06T14:15:27.954056Z","caller":"traceutil/trace.go:171","msg":"trace[15157320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:487; }","duration":"592.250888ms","start":"2026-05-06T14:15:27.36179Z","end":"2026-05-06T14:15:27.95404Z","steps":["trace[15157320] 'agreement among raft nodes before linearized reading' (duration: 592.018383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:27.954111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:27.361777Z","time spent":"592.321029ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T14:15:29.545321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.41145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T14:15:29.545431Z","caller":"traceutil/trace.go:171","msg":"trace[1902976027] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"629.109666ms","start":"2026-05-06T14:15:28.916302Z","end":"2026-05-06T14:15:29.545412Z","steps":["trace[1902976027] 'process raft request' (duration: 377.535743ms)","trace[1902976027] 'compare' (duration: 250.97279ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:29.54549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:28.916281Z","time spent":"629.180428ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T14:15:29.551727Z","caller":"traceutil/trace.go:171","msg":"trace[260440103] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"281.796224ms","start":"2026-05-06T14:15:29.269896Z","end":"2026-05-06T14:15:29.551693Z","steps":["trace[260440103] 'process raft request' (duration: 281.735393ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:29.552248Z","caller":"traceutil/trace.go:171","msg":"trace[318133624] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"560.420157ms","start":"2026-05-06T14:15:28.991805Z","end":"2026-05-06T14:15:29.552226Z","steps":["trace[318133624] 'process raft request' (duration: 559.689843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:29.55235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:28.991778Z","time spent":"560.505419ms","remote":"127.0.0.1:54364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T14:15:32.266111Z","caller":"traceutil/trace.go:171","msg":"trace[1078187036] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"110.325752ms","start":"2026-05-06T14:15:32.155763Z","end":"2026-05-06T14:15:32.266088Z","steps":["trace[1078187036] 'process raft request' (duration: 109.664309ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:32.470575Z","caller":"traceutil/trace.go:171","msg":"trace[86641472] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"148.186395ms","start":"2026-05-06T14:15:32.322364Z","end":"2026-05-06T14:15:32.470551Z","steps":["trace[86641472] 'process raft request' (duration: 143.236769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:33.347974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.288133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:33.348086Z","caller":"traceutil/trace.go:171","msg":"trace[1471725823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"584.462117ms","start":"2026-05-06T14:15:32.763598Z","end":"2026-05-06T14:15:33.34806Z","steps":["trace[1471725823] 'range keys from in-memory index tree' (duration: 584.154761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:33.348136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:32.76358Z","time spent":"584.541228ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T14:15:33.348568Z","caller":"traceutil/trace.go:171","msg":"trace[2112181379] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"469.808531ms","start":"2026-05-06T14:15:32.878733Z","end":"2026-05-06T14:15:33.348541Z","steps":["trace[2112181379] 'process raft request' (duration: 468.847973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:33.348728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:32.878694Z","time spent":"469.952634ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036165,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:15:33.748084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.669928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:33.748169Z","caller":"traceutil/trace.go:171","msg":"trace[2096415679] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:569; }","duration":"297.806501ms","start":"2026-05-06T14:15:33.450343Z","end":"2026-05-06T14:15:33.748149Z","steps":["trace[2096415679] 'range keys from in-memory index tree' (duration: 297.553627ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:34.419409Z","caller":"traceutil/trace.go:171","msg":"trace[1192421640] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"394.108273ms","start":"2026-05-06T14:15:34.025281Z","end":"2026-05-06T14:15:34.41939Z","steps":["trace[1192421640] 'process raft request' (duration: 380.26098ms)","trace[1192421640] 'compare' (duration: 13.604458ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:34.419438Z","caller":"traceutil/trace.go:171","msg":"trace[1589347251] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"393.308349ms","start":"2026-05-06T14:15:34.026103Z","end":"2026-05-06T14:15:34.419411Z","steps":["trace[1589347251] 'process raft request' (duration: 393.195496ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:34.419463Z","caller":"traceutil/trace.go:171","msg":"trace[53335111] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"392.81657ms","start":"2026-05-06T14:15:34.026628Z","end":"2026-05-06T14:15:34.419444Z","steps":["trace[53335111] 'process raft request' (duration: 392.739787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:34.419503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:34.025265Z","time spent":"394.191915ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:15:34.419547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:34.026094Z","time spent":"393.396051ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:15:34.419574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:34.02662Z","time spent":"392.897151ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T14:15:34.632835Z","caller":"traceutil/trace.go:171","msg":"trace[940799206] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"500.937933ms","start":"2026-05-06T14:15:34.131871Z","end":"2026-05-06T14:15:34.632809Z","steps":["trace[940799206] 'process raft request' (duration: 489.76995ms)","trace[940799206] 'compare' (duration: 10.5219ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:34.632978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:34.131845Z","time spent":"501.058336ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036201,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:15:34.883476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.720888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T14:15:34.883654Z","caller":"traceutil/trace.go:171","msg":"trace[1391600696] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"119.587032ms","start":"2026-05-06T14:15:34.764048Z","end":"2026-05-06T14:15:34.883635Z","steps":["trace[1391600696] 'read index received' (duration: 73.031µs)","trace[1391600696] 'applied index is now lower than readState.Index' (duration: 119.512481ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:34.883734Z","caller":"traceutil/trace.go:171","msg":"trace[1472232903] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"181.799021ms","start":"2026-05-06T14:15:34.701897Z","end":"2026-05-06T14:15:34.883696Z","steps":["trace[1472232903] 'process raft request' (duration: 30.757467ms)","trace[1472232903] 'compare' (duration: 150.518474ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:34.883771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.714165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:34.883829Z","caller":"traceutil/trace.go:171","msg":"trace[268277251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"119.826857ms","start":"2026-05-06T14:15:34.763987Z","end":"2026-05-06T14:15:34.883814Z","steps":["trace[268277251] 'agreement among raft nodes before linearized reading' (duration: 119.694454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:36.925549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199481292827689,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T14:15:37.133128Z","caller":"traceutil/trace.go:171","msg":"trace[1110438394] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"708.600156ms","start":"2026-05-06T14:15:36.42449Z","end":"2026-05-06T14:15:37.133091Z","steps":["trace[1110438394] 'read index received' (duration: 707.788321ms)","trace[1110438394] 'applied index is now lower than readState.Index' (duration: 810.685µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:37.133206Z","caller":"traceutil/trace.go:171","msg":"trace[889911001] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"766.423531ms","start":"2026-05-06T14:15:36.366752Z","end":"2026-05-06T14:15:37.133175Z","steps":["trace[889911001] 'process raft request' (duration: 765.687998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:37.133348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:36.366729Z","time spent":"766.539204ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:15:37.133399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.889421ms","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-06T14:15:37.13344Z","caller":"traceutil/trace.go:171","msg":"trace[1981800705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"708.998063ms","start":"2026-05-06T14:15:36.424429Z","end":"2026-05-06T14:15:37.133427Z","steps":["trace[1981800705] 'agreement among raft nodes before linearized reading' (duration: 708.82321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:37.133477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:36.424401Z","time spent":"709.068065ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T14:15:37.133479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.063915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:37.133582Z","caller":"traceutil/trace.go:171","msg":"trace[514179053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"369.218998ms","start":"2026-05-06T14:15:36.764338Z","end":"2026-05-06T14:15:37.133557Z","steps":["trace[514179053] 'agreement among raft nodes before linearized reading' (duration: 369.044305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:37.133655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:36.764323Z","time spent":"369.31774ms","remote":"127.0.0.1:54328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T14:15:37.133593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.708698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T14:15:37.133723Z","caller":"traceutil/trace.go:171","msg":"trace[55787858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"708.889001ms","start":"2026-05-06T14:15:36.424811Z","end":"2026-05-06T14:15:37.1337Z","steps":["trace[55787858] 'agreement among raft nodes before linearized reading' (duration: 708.537474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:37.133774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:36.424776Z","time spent":"708.984693ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T14:15:37.133747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.770099ms","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-06T14:15:37.133852Z","caller":"traceutil/trace.go:171","msg":"trace[501499816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"708.927852ms","start":"2026-05-06T14:15:36.424895Z","end":"2026-05-06T14:15:37.133823Z","steps":["trace[501499816] 'agreement among raft nodes before linearized reading' (duration: 708.571346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:37.133901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:36.424887Z","time spent":"709.005383ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T14:15:39.764142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000295328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-06T14:15:39.764208Z","caller":"traceutil/trace.go:171","msg":"trace[398322882] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00041235s","start":"2026-05-06T14:15:37.76378Z","end":"2026-05-06T14:15:39.764193Z","steps":["trace[398322882] 'agreement among raft nodes before linearized reading' (duration: 2.000327309s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:39.764245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:37.76376Z","time spent":"2.000479081s","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/06 14:15:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T14:15:40.114177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.475725115s","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-06T14:15:40.114267Z","caller":"traceutil/trace.go:171","msg":"trace[1088587536] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:586; }","duration":"2.475834847s","start":"2026-05-06T14:15:37.638418Z","end":"2026-05-06T14:15:40.114253Z","steps":["trace[1088587536] 'range keys from in-memory index tree' (duration: 2.475697125s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:40.11461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.802013778s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T14:15:40.114715Z","caller":"traceutil/trace.go:171","msg":"trace[1150969690] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"2.973281252s","start":"2026-05-06T14:15:37.141416Z","end":"2026-05-06T14:15:40.114698Z","steps":["trace[1150969690] 'process raft request' (duration: 171.10148ms)","trace[1150969690] 'compare' (duration: 2.801912336s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:40.114773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:37.141379Z","time spent":"2.973366484s","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:15:40.75809Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.445236565s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T14:15:41.764358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999731813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-06T14:15:41.764496Z","caller":"traceutil/trace.go:171","msg":"trace[18461210] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999893486s","start":"2026-05-06T14:15:39.764583Z","end":"2026-05-06T14:15:41.764477Z","steps":["trace[18461210] 'agreement among raft nodes before linearized reading' (duration: 1.999728783s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:41.764553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:39.764499Z","time spent":"2.000037848s","remote":"127.0.0.1:54328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/06 14:15:41 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T14:15:43.08802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999110182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-06T14:15:43.088117Z","caller":"traceutil/trace.go:171","msg":"trace[129513726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999270826s","start":"2026-05-06T14:15:38.088825Z","end":"2026-05-06T14:15:43.088095Z","steps":["trace[129513726] 'agreement among raft nodes before linearized reading' (duration: 4.999146593s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:43.088172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:38.088803Z","time spent":"4.999354727s","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/05/06 14:15:43 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T14:15:43.771734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999934599s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-06T14:15:43.771817Z","caller":"traceutil/trace.go:171","msg":"trace[1928056415] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000076631s","start":"2026-05-06T14:15:41.771722Z","end":"2026-05-06T14:15:43.771799Z","steps":["trace[1928056415] 'agreement among raft nodes before linearized reading' (duration: 1.999957949s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:43.771856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:41.771688Z","time spent":"2.000160093s","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T14:15:44.142893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:37.142407Z","time spent":"7.000478471s","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-06T14:15:44.142938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:37.142367Z","time spent":"7.000565574s","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-06T14:15:44.205456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:37.204459Z","time spent":"7.00099013s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-06T14:15:45.772831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000571621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-06T14:15:45.772924Z","caller":"traceutil/trace.go:171","msg":"trace[475548987] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000717254s","start":"2026-05-06T14:15:43.772184Z","end":"2026-05-06T14:15:45.772902Z","steps":["trace[475548987] 'agreement among raft nodes before linearized reading' (duration: 2.000604392s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:45.772993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:43.772168Z","time spent":"2.000792995s","remote":"127.0.0.1:54328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/06 14:15:45 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T14:15:46.301583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.543105421s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T14:15:46.302874Z","caller":"traceutil/trace.go:171","msg":"trace[1091953460] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:611; }","duration":"9.079544205s","start":"2026-05-06T14:15:37.223277Z","end":"2026-05-06T14:15:46.302821Z","steps":["trace[1091953460] 'read index received' (duration: 89.442587ms)","trace[1091953460] 'applied index is now lower than readState.Index' (duration: 8.990097628s)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:46.303064Z","caller":"traceutil/trace.go:171","msg":"trace[688436732] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"6.623086348s","start":"2026-05-06T14:15:39.679965Z","end":"2026-05-06T14:15:46.303051Z","steps":["trace[688436732] 'process raft request' (duration: 6.622043319s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:46.303139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:39.679917Z","time spent":"6.62317419s","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:15:46.423427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.188501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-06T14:15:46.423518Z","caller":"traceutil/trace.go:171","msg":"trace[983676927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"276.333593ms","start":"2026-05-06T14:15:46.147168Z","end":"2026-05-06T14:15:46.423501Z","steps":["trace[983676927] 'agreement among raft nodes before linearized reading' (duration: 276.213231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:46.423493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.243642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-06T14:15:46.423636Z","caller":"traceutil/trace.go:171","msg":"trace[518448539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"276.429745ms","start":"2026-05-06T14:15:46.147186Z","end":"2026-05-06T14:15:46.423616Z","steps":["trace[518448539] 'agreement among raft nodes before linearized reading' (duration: 276.252112ms)"],"step_count":1} 2026/05/06 14:15:46 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" 2026/05/06 14:15:46 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T14:15:46.803754Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199481292827702,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T14:15:47.117954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999163306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-06T14:15:47.118015Z","caller":"traceutil/trace.go:171","msg":"trace[1183922770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999245509s","start":"2026-05-06T14:15:42.118752Z","end":"2026-05-06T14:15:47.117998Z","steps":["trace[1183922770] 'agreement among raft nodes before linearized reading' (duration: 4.999160197s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:47.118048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:42.118698Z","time spent":"4.99934049s","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/05/06 14:15:47 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T14:15:47.30432Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199481292827702,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T14:15:47.755107Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"7edf9dfda4382b20","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-05-06T14:15:47.779589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0001884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-06T14:15:47.77966Z","caller":"traceutil/trace.go:171","msg":"trace[985272090] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000303692s","start":"2026-05-06T14:15:45.779336Z","end":"2026-05-06T14:15:47.77964Z","steps":["trace[985272090] 'agreement among raft nodes before linearized reading' (duration: 2.000208931s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:47.779722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:45.779321Z","time spent":"2.000386384s","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/06 14:15:47 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T14:15:47.805003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199481292827702,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T14:15:48.089026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998451076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-06T14:15:48.08913Z","caller":"traceutil/trace.go:171","msg":"trace[2065971969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.998611649s","start":"2026-05-06T14:15:45.090494Z","end":"2026-05-06T14:15:48.089105Z","steps":["trace[2065971969] 'agreement among raft nodes before linearized reading' (duration: 2.998485236s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:48.0892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:45.090468Z","time spent":"2.99871306s","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/05/06 14:15:48 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T14:15:48.131754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.829675951s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-05-06T14:15:48.132039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.908749247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j9ws4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-06T14:15:48.132103Z","caller":"traceutil/trace.go:171","msg":"trace[283717215] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j9ws4; range_end:; response_count:1; response_revision:591; }","duration":"10.90887325s","start":"2026-05-06T14:15:37.223214Z","end":"2026-05-06T14:15:48.132087Z","steps":["trace[283717215] 'agreement among raft nodes before linearized reading' (duration: 9.07981167s)","trace[283717215] 'range keys from in-memory index tree' (duration: 1.828882646s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:48.132131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:37.223189Z","time spent":"10.908935162s","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-j9ws4\" "} {"level":"warn","ts":"2026-05-06T14:15:48.132157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.658061ms","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-06T14:15:48.132236Z","caller":"traceutil/trace.go:171","msg":"trace[359644722] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:591; }","duration":"493.738762ms","start":"2026-05-06T14:15:47.638483Z","end":"2026-05-06T14:15:48.132222Z","steps":["trace[359644722] 'range keys from in-memory index tree' (duration: 493.649101ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:48.132714Z","caller":"traceutil/trace.go:171","msg":"trace[933111491] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"6.364606172s","start":"2026-05-06T14:15:41.768058Z","end":"2026-05-06T14:15:48.132664Z","steps":["trace[933111491] 'process raft request' (duration: 6.363780197s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:48.13296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:41.768035Z","time spent":"6.364800626s","remote":"127.0.0.1:54398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T14:15:48.15291Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.547809462s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T14:15:48.286548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.163846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T14:15:48.286672Z","caller":"traceutil/trace.go:171","msg":"trace[1996864714] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"3.688443241s","start":"2026-05-06T14:15:44.598211Z","end":"2026-05-06T14:15:48.286654Z","steps":["trace[1996864714] 'process raft request' (duration: 3.555092401s)","trace[1996864714] 'compare' (duration: 132.786129ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:48.286734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:44.598185Z","time spent":"3.688519201s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036424,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T14:15:48.312087Z","caller":"traceutil/trace.go:171","msg":"trace[639353850] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"2.009139282s","start":"2026-05-06T14:15:46.302921Z","end":"2026-05-06T14:15:48.31206Z","steps":["trace[639353850] 'read index received' (duration: 1.850144572s)","trace[639353850] 'applied index is now lower than readState.Index' (duration: 158.99373ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:48.312182Z","caller":"traceutil/trace.go:171","msg":"trace[792063398] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"164.915552ms","start":"2026-05-06T14:15:48.147242Z","end":"2026-05-06T14:15:48.312158Z","steps":["trace[792063398] 'process raft request' (duration: 164.767569ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:48.312284Z","caller":"traceutil/trace.go:171","msg":"trace[1207644743] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"3.253108749s","start":"2026-05-06T14:15:45.059156Z","end":"2026-05-06T14:15:48.312265Z","steps":["trace[1207644743] 'process raft request' (duration: 3.252707323s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:48.312384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.887064456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-06T14:15:48.312413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:45.059132Z","time spent":"3.253205262s","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:15:48.312304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.906407008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-06T14:15:48.312468Z","caller":"traceutil/trace.go:171","msg":"trace[2119967483] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:595; }","duration":"2.906609331s","start":"2026-05-06T14:15:45.40585Z","end":"2026-05-06T14:15:48.31246Z","steps":["trace[2119967483] 'agreement among raft nodes before linearized reading' (duration: 2.906333576s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:48.312495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.987507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18acff8ed13c4781\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-05-06T14:15:48.312512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:45.405837Z","time spent":"2.906653321s","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"info","ts":"2026-05-06T14:15:48.31242Z","caller":"traceutil/trace.go:171","msg":"trace[599578893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"1.887129958s","start":"2026-05-06T14:15:46.425279Z","end":"2026-05-06T14:15:48.312409Z","steps":["trace[599578893] 'agreement among raft nodes before linearized reading' (duration: 1.887055317s)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:48.312536Z","caller":"traceutil/trace.go:171","msg":"trace[1517271287] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18acff8ed13c4781; range_end:; response_count:1; response_revision:595; }","duration":"174.043458ms","start":"2026-05-06T14:15:48.138471Z","end":"2026-05-06T14:15:48.312514Z","steps":["trace[1517271287] 'agreement among raft nodes before linearized reading' (duration: 173.971466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:48.312563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:46.425259Z","time spent":"1.88729723s","remote":"127.0.0.1:54576","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-06T14:15:48.312742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.592621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T14:15:48.312759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.887415363s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-06T14:15:48.312773Z","caller":"traceutil/trace.go:171","msg":"trace[1235073901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"532.655283ms","start":"2026-05-06T14:15:47.78011Z","end":"2026-05-06T14:15:48.312765Z","steps":["trace[1235073901] 'agreement among raft nodes before linearized reading' (duration: 532.608742ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:48.312779Z","caller":"traceutil/trace.go:171","msg":"trace[916031908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"1.887470103s","start":"2026-05-06T14:15:46.425304Z","end":"2026-05-06T14:15:48.312774Z","steps":["trace[916031908] 'agreement among raft nodes before linearized reading' (duration: 1.887386612s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:48.312796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:46.425287Z","time spent":"1.887505304s","remote":"127.0.0.1:54576","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-06T14:15:48.312798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:47.780094Z","time spent":"532.697083ms","remote":"127.0.0.1:54328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T14:15:48.517812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.076422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T14:15:48.517976Z","caller":"traceutil/trace.go:171","msg":"trace[325826618] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"132.666547ms","start":"2026-05-06T14:15:48.385286Z","end":"2026-05-06T14:15:48.517952Z","steps":["trace[325826618] 'read index received' (duration: 12.091197ms)","trace[325826618] 'applied index is now lower than readState.Index' (duration: 120.5734ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:48.5182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.571479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:48.518232Z","caller":"traceutil/trace.go:171","msg":"trace[86365238] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"174.963993ms","start":"2026-05-06T14:15:48.343243Z","end":"2026-05-06T14:15:48.518207Z","steps":["trace[86365238] 'process raft request' (duration: 54.120129ms)","trace[86365238] 'compare' (duration: 119.842538ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:48.518277Z","caller":"traceutil/trace.go:171","msg":"trace[1049955286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"198.688461ms","start":"2026-05-06T14:15:48.319577Z","end":"2026-05-06T14:15:48.518265Z","steps":["trace[1049955286] 'agreement among raft nodes before linearized reading' (duration: 198.432877ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:48.591594Z","caller":"traceutil/trace.go:171","msg":"trace[675239698] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"205.442927ms","start":"2026-05-06T14:15:48.386112Z","end":"2026-05-06T14:15:48.591555Z","steps":["trace[675239698] 'process raft request' (duration: 205.021939ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:48.5916Z","caller":"traceutil/trace.go:171","msg":"trace[1228613564] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"199.087199ms","start":"2026-05-06T14:15:48.392481Z","end":"2026-05-06T14:15:48.591569Z","steps":["trace[1228613564] 'process raft request' (duration: 198.962705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:49.112954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.240082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T14:15:49.113099Z","caller":"traceutil/trace.go:171","msg":"trace[2039158657] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"494.451586ms","start":"2026-05-06T14:15:48.618602Z","end":"2026-05-06T14:15:49.113053Z","steps":["trace[2039158657] 'process raft request' (duration: 353.014901ms)","trace[2039158657] 'compare' (duration: 141.12416ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:49.113197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:48.618587Z","time spent":"494.565598ms","remote":"127.0.0.1:54398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T14:15:49.238935Z","caller":"traceutil/trace.go:171","msg":"trace[290819851] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"118.997569ms","start":"2026-05-06T14:15:49.119911Z","end":"2026-05-06T14:15:49.238909Z","steps":["trace[290819851] 'read index received' (duration: 113.522955ms)","trace[290819851] 'applied index is now lower than readState.Index' (duration: 5.473514ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:49.239168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.233602ms","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-06T14:15:49.239201Z","caller":"traceutil/trace.go:171","msg":"trace[121770256] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"119.306714ms","start":"2026-05-06T14:15:49.119883Z","end":"2026-05-06T14:15:49.23919Z","steps":["trace[121770256] 'agreement among raft nodes before linearized reading' (duration: 119.120951ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:49.239244Z","caller":"traceutil/trace.go:171","msg":"trace[1428323028] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"618.345248ms","start":"2026-05-06T14:15:48.620863Z","end":"2026-05-06T14:15:49.239209Z","steps":["trace[1428323028] 'process raft request' (duration: 612.655391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:49.239421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:48.620822Z","time spent":"618.5061ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T14:15:49.372582Z","caller":"traceutil/trace.go:171","msg":"trace[1147220543] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"129.184302ms","start":"2026-05-06T14:15:49.243362Z","end":"2026-05-06T14:15:49.372546Z","steps":["trace[1147220543] 'process raft request' (duration: 127.795189ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:49.510991Z","caller":"traceutil/trace.go:171","msg":"trace[583353302] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"105.502639ms","start":"2026-05-06T14:15:49.405444Z","end":"2026-05-06T14:15:49.510947Z","steps":["trace[583353302] 'process raft request' (duration: 101.713164ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:49.72122Z","caller":"traceutil/trace.go:171","msg":"trace[417737394] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"199.010602ms","start":"2026-05-06T14:15:49.522177Z","end":"2026-05-06T14:15:49.721188Z","steps":["trace[417737394] 'process raft request' (duration: 112.394145ms)","trace[417737394] 'compare' (duration: 86.465794ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:49.978598Z","caller":"traceutil/trace.go:171","msg":"trace[1666577000] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"214.903513ms","start":"2026-05-06T14:15:49.763667Z","end":"2026-05-06T14:15:49.978571Z","steps":["trace[1666577000] 'read index received' (duration: 175.845427ms)","trace[1666577000] 'applied index is now lower than readState.Index' (duration: 39.056296ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:49.978925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.027865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:49.979358Z","caller":"traceutil/trace.go:171","msg":"trace[987752058] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"247.300676ms","start":"2026-05-06T14:15:49.732003Z","end":"2026-05-06T14:15:49.979304Z","steps":["trace[987752058] 'process raft request' (duration: 207.625589ms)","trace[987752058] 'compare' (duration: 38.799802ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:49.982997Z","caller":"traceutil/trace.go:171","msg":"trace[2108890286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"215.362302ms","start":"2026-05-06T14:15:49.763639Z","end":"2026-05-06T14:15:49.979001Z","steps":["trace[2108890286] 'agreement among raft nodes before linearized reading' (duration: 215.027356ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:50.504009Z","caller":"traceutil/trace.go:171","msg":"trace[1543299924] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"178.206756ms","start":"2026-05-06T14:15:50.325779Z","end":"2026-05-06T14:15:50.503986Z","steps":["trace[1543299924] 'process raft request' (duration: 175.315937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:50.504293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.113958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-06T14:15:50.504412Z","caller":"traceutil/trace.go:171","msg":"trace[660634582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"102.265391ms","start":"2026-05-06T14:15:50.402124Z","end":"2026-05-06T14:15:50.50439Z","steps":["trace[660634582] 'agreement among raft nodes before linearized reading' (duration: 102.027547ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:50.504011Z","caller":"traceutil/trace.go:171","msg":"trace[2134187119] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"101.805712ms","start":"2026-05-06T14:15:50.402154Z","end":"2026-05-06T14:15:50.50396Z","steps":["trace[2134187119] 'read index received' (duration: 98.890763ms)","trace[2134187119] 'applied index is now lower than readState.Index' (duration: 2.913849ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:50.504985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.542236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-06T14:15:50.505031Z","caller":"traceutil/trace.go:171","msg":"trace[1540978321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"102.612227ms","start":"2026-05-06T14:15:50.402409Z","end":"2026-05-06T14:15:50.505022Z","steps":["trace[1540978321] 'agreement among raft nodes before linearized reading' (duration: 102.280971ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:50.721229Z","caller":"traceutil/trace.go:171","msg":"trace[2045384805] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"137.815842ms","start":"2026-05-06T14:15:50.583392Z","end":"2026-05-06T14:15:50.721208Z","steps":["trace[2045384805] 'process raft request' (duration: 137.464936ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:50.80593Z","caller":"traceutil/trace.go:171","msg":"trace[450048425] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"222.472746ms","start":"2026-05-06T14:15:50.583436Z","end":"2026-05-06T14:15:50.805908Z","steps":["trace[450048425] 'read index received' (duration: 67.686726ms)","trace[450048425] 'applied index is now lower than readState.Index' (duration: 154.78388ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:50.806158Z","caller":"traceutil/trace.go:171","msg":"trace[824816144] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"137.234183ms","start":"2026-05-06T14:15:50.668916Z","end":"2026-05-06T14:15:50.80615Z","steps":["trace[824816144] 'process raft request' (duration: 137.127101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:50.806198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.73583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-06T14:15:50.806273Z","caller":"traceutil/trace.go:171","msg":"trace[1357370476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:617; }","duration":"222.825331ms","start":"2026-05-06T14:15:50.583431Z","end":"2026-05-06T14:15:50.806256Z","steps":["trace[1357370476] 'agreement among raft nodes before linearized reading' (duration: 222.608138ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:50.910367Z","caller":"traceutil/trace.go:171","msg":"trace[1277807233] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"100.922828ms","start":"2026-05-06T14:15:50.809419Z","end":"2026-05-06T14:15:50.910342Z","steps":["trace[1277807233] 'process raft request' (duration: 99.011356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:51.574412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.491491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T14:15:51.574554Z","caller":"traceutil/trace.go:171","msg":"trace[770586350] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"197.50624ms","start":"2026-05-06T14:15:51.377025Z","end":"2026-05-06T14:15:51.574532Z","steps":["trace[770586350] 'read index received' (duration: 62.717664ms)","trace[770586350] 'applied index is now lower than readState.Index' (duration: 134.786796ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:51.57468Z","caller":"traceutil/trace.go:171","msg":"trace[1035190602] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"239.799581ms","start":"2026-05-06T14:15:51.334857Z","end":"2026-05-06T14:15:51.574656Z","steps":["trace[1035190602] 'process raft request' (duration: 104.977244ms)","trace[1035190602] 'compare' (duration: 134.359529ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:51.574723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.695033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T14:15:51.574767Z","caller":"traceutil/trace.go:171","msg":"trace[65006184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"197.768544ms","start":"2026-05-06T14:15:51.376983Z","end":"2026-05-06T14:15:51.574751Z","steps":["trace[65006184] 'agreement among raft nodes before linearized reading' (duration: 197.609152ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:51.712464Z","caller":"traceutil/trace.go:171","msg":"trace[1669524020] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"126.561058ms","start":"2026-05-06T14:15:51.585873Z","end":"2026-05-06T14:15:51.712434Z","steps":["trace[1669524020] 'process raft request' (duration: 108.908381ms)","trace[1669524020] 'compare' (duration: 17.522165ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:53.225551Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199481292827769,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T14:15:53.726146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199481292827769,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T14:15:54.226654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199481292827769,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T14:15:54.249316Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.858611594s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T14:15:54.249848Z","caller":"traceutil/trace.go:171","msg":"trace[1986854460] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"1.52533165s","start":"2026-05-06T14:15:52.724483Z","end":"2026-05-06T14:15:54.249815Z","steps":["trace[1986854460] 'read index received' (duration: 1.524957794s)","trace[1986854460] 'applied index is now lower than readState.Index' (duration: 371.986µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:54.249902Z","caller":"traceutil/trace.go:171","msg":"trace[1795608226] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.859291135s","start":"2026-05-06T14:15:52.390577Z","end":"2026-05-06T14:15:54.249868Z","steps":["trace[1795608226] 'process raft request' (duration: 1.85896612s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:54.250036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:52.390562Z","time spent":"1.859395447s","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17693,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:15:54.250042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.485963404s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:54.250084Z","caller":"traceutil/trace.go:171","msg":"trace[1033544243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"1.486018865s","start":"2026-05-06T14:15:52.764049Z","end":"2026-05-06T14:15:54.250068Z","steps":["trace[1033544243] 'agreement among raft nodes before linearized reading' (duration: 1.485935743s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:54.250121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:52.764002Z","time spent":"1.486110586s","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T14:15:54.250116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525616025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-06T14:15:54.250189Z","caller":"traceutil/trace.go:171","msg":"trace[1303098036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"1.525728107s","start":"2026-05-06T14:15:52.724439Z","end":"2026-05-06T14:15:54.250167Z","steps":["trace[1303098036] 'agreement among raft nodes before linearized reading' (duration: 1.525498503s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:54.250226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:52.724417Z","time spent":"1.525800569s","remote":"127.0.0.1:54576","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-06T14:15:54.250281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.166741ms","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-06T14:15:54.250315Z","caller":"traceutil/trace.go:171","msg":"trace[1004710667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"647.226662ms","start":"2026-05-06T14:15:53.603077Z","end":"2026-05-06T14:15:54.250304Z","steps":["trace[1004710667] 'agreement among raft nodes before linearized reading' (duration: 647.072569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:54.25036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:53.60306Z","time spent":"647.294454ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T14:15:54.250552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.434297ms","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-06T14:15:54.250585Z","caller":"traceutil/trace.go:171","msg":"trace[1525920055] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"404.468328ms","start":"2026-05-06T14:15:53.846107Z","end":"2026-05-06T14:15:54.250575Z","steps":["trace[1525920055] 'agreement among raft nodes before linearized reading' (duration: 404.382246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:54.250619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:53.846069Z","time spent":"404.539789ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T14:15:54.250662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031102616s","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:1 size:1036439"} {"level":"info","ts":"2026-05-06T14:15:54.250704Z","caller":"traceutil/trace.go:171","msg":"trace[1563734275] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:1; response_revision:627; }","duration":"1.031194518s","start":"2026-05-06T14:15:53.219495Z","end":"2026-05-06T14:15:54.25069Z","steps":["trace[1563734275] 'agreement among raft nodes before linearized reading' (duration: 1.030831512s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:54.250736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:53.219479Z","time spent":"1.031249538s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1036461,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-06T14:15:54.25079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33596293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-06T14:15:54.250859Z","caller":"traceutil/trace.go:171","msg":"trace[33455802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"1.336063101s","start":"2026-05-06T14:15:52.914775Z","end":"2026-05-06T14:15:54.250838Z","steps":["trace[33455802] 'agreement among raft nodes before linearized reading' (duration: 1.335563603s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:54.25091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:52.914757Z","time spent":"1.336141823s","remote":"127.0.0.1:54576","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":"info","ts":"2026-05-06T14:15:54.428341Z","caller":"traceutil/trace.go:171","msg":"trace[1003059741] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"171.602425ms","start":"2026-05-06T14:15:54.256713Z","end":"2026-05-06T14:15:54.428315Z","steps":["trace[1003059741] 'process raft request' (duration: 165.309842ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:54.428535Z","caller":"traceutil/trace.go:171","msg":"trace[710023221] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"171.718407ms","start":"2026-05-06T14:15:54.256797Z","end":"2026-05-06T14:15:54.428515Z","steps":["trace[710023221] 'process raft request' (duration: 171.469133ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:54.428632Z","caller":"traceutil/trace.go:171","msg":"trace[496216935] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:655; }","duration":"168.487764ms","start":"2026-05-06T14:15:54.260132Z","end":"2026-05-06T14:15:54.42862Z","steps":["trace[496216935] 'read index received' (duration: 161.900216ms)","trace[496216935] 'applied index is now lower than readState.Index' (duration: 6.586858ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:15:54.428748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.596176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:54.428809Z","caller":"traceutil/trace.go:171","msg":"trace[1042502385] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"171.471083ms","start":"2026-05-06T14:15:54.25733Z","end":"2026-05-06T14:15:54.428801Z","steps":["trace[1042502385] 'process raft request' (duration: 171.115787ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:54.428816Z","caller":"traceutil/trace.go:171","msg":"trace[339692139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"168.684717ms","start":"2026-05-06T14:15:54.260113Z","end":"2026-05-06T14:15:54.428797Z","steps":["trace[339692139] 'agreement among raft nodes before linearized reading' (duration: 168.584255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:54.428772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.639568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T14:15:54.428883Z","caller":"traceutil/trace.go:171","msg":"trace[1432024607] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:630; }","duration":"114.7954ms","start":"2026-05-06T14:15:54.314079Z","end":"2026-05-06T14:15:54.428874Z","steps":["trace[1432024607] 'agreement among raft nodes before linearized reading' (duration: 114.652288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:54.717972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.087478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-05-06T14:15:54.718106Z","caller":"traceutil/trace.go:171","msg":"trace[10418364] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:630; }","duration":"145.264772ms","start":"2026-05-06T14:15:54.572798Z","end":"2026-05-06T14:15:54.718063Z","steps":["trace[10418364] 'range keys from in-memory index tree' (duration: 144.62815ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:55.469577Z","caller":"traceutil/trace.go:171","msg":"trace[1000420055] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"109.581242ms","start":"2026-05-06T14:15:55.359972Z","end":"2026-05-06T14:15:55.469553Z","steps":["trace[1000420055] 'process raft request' (duration: 109.370679ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:56.701444Z","caller":"traceutil/trace.go:171","msg":"trace[1873917904] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"262.372463ms","start":"2026-05-06T14:15:56.439048Z","end":"2026-05-06T14:15:56.70142Z","steps":["trace[1873917904] 'process raft request' (duration: 262.204749ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:56.70167Z","caller":"traceutil/trace.go:171","msg":"trace[151120357] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:665; }","duration":"181.451838ms","start":"2026-05-06T14:15:56.520179Z","end":"2026-05-06T14:15:56.70163Z","steps":["trace[151120357] 'read index received' (duration: 181.130552ms)","trace[151120357] 'applied index is now lower than readState.Index' (duration: 319.906µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:56.701713Z","caller":"traceutil/trace.go:171","msg":"trace[1435592166] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"252.153986ms","start":"2026-05-06T14:15:56.449536Z","end":"2026-05-06T14:15:56.70169Z","steps":["trace[1435592166] 'process raft request' (duration: 251.84667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:56.701811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.616981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-05-06T14:15:56.701878Z","caller":"traceutil/trace.go:171","msg":"trace[907598143] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:638; }","duration":"181.716142ms","start":"2026-05-06T14:15:56.520145Z","end":"2026-05-06T14:15:56.701861Z","steps":["trace[907598143] 'agreement among raft nodes before linearized reading' (duration: 181.59427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:56.743477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.613966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:56.743554Z","caller":"traceutil/trace.go:171","msg":"trace[903014050] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:638; }","duration":"137.731208ms","start":"2026-05-06T14:15:56.605805Z","end":"2026-05-06T14:15:56.743537Z","steps":["trace[903014050] 'agreement among raft nodes before linearized reading' (duration: 137.585324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:56.743581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.239036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:5 size:1788"} {"level":"info","ts":"2026-05-06T14:15:56.743655Z","caller":"traceutil/trace.go:171","msg":"trace[498484000] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:638; }","duration":"146.325947ms","start":"2026-05-06T14:15:56.597313Z","end":"2026-05-06T14:15:56.743639Z","steps":["trace[498484000] 'agreement among raft nodes before linearized reading' (duration: 146.110004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:56.74396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.820491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3548"} {"level":"info","ts":"2026-05-06T14:15:56.743998Z","caller":"traceutil/trace.go:171","msg":"trace[1373483386] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:638; }","duration":"187.911903ms","start":"2026-05-06T14:15:56.556075Z","end":"2026-05-06T14:15:56.743987Z","steps":["trace[1373483386] 'agreement among raft nodes before linearized reading' (duration: 187.818711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:57.598278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.32627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:1 size:2379"} {"level":"info","ts":"2026-05-06T14:15:57.598399Z","caller":"traceutil/trace.go:171","msg":"trace[577064497] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:1; response_revision:640; }","duration":"501.496113ms","start":"2026-05-06T14:15:57.096873Z","end":"2026-05-06T14:15:57.598369Z","steps":["trace[577064497] 'range keys from in-memory index tree' (duration: 501.207638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:57.598459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:57.096855Z","time spent":"501.590024ms","remote":"127.0.0.1:54680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":2401,"request content":"key:\"/registry/clusterroles/cilium-operator\" "} {"level":"warn","ts":"2026-05-06T14:15:57.598499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.826904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j9ws4\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-05-06T14:15:57.598588Z","caller":"traceutil/trace.go:171","msg":"trace[287077367] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j9ws4; range_end:; response_count:1; response_revision:640; }","duration":"243.954877ms","start":"2026-05-06T14:15:57.354602Z","end":"2026-05-06T14:15:57.598557Z","steps":["trace[287077367] 'range keys from in-memory index tree' (duration: 243.49499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:57.59849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.436801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium\" ","response":"range_response_count:1 size:1654"} {"level":"info","ts":"2026-05-06T14:15:57.598826Z","caller":"traceutil/trace.go:171","msg":"trace[456092637] range","detail":"{range_begin:/registry/clusterroles/cilium; range_end:; response_count:1; response_revision:640; }","duration":"478.767546ms","start":"2026-05-06T14:15:57.119981Z","end":"2026-05-06T14:15:57.598749Z","steps":["trace[456092637] 'range keys from in-memory index tree' (duration: 478.304179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:57.599018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:57.119959Z","time spent":"479.041741ms","remote":"127.0.0.1:54680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":31,"response count":1,"response size":1676,"request content":"key:\"/registry/clusterroles/cilium\" "} {"level":"info","ts":"2026-05-06T14:15:57.914292Z","caller":"traceutil/trace.go:171","msg":"trace[2026370322] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"239.773129ms","start":"2026-05-06T14:15:57.674494Z","end":"2026-05-06T14:15:57.914267Z","steps":["trace[2026370322] 'read index received' (duration: 239.602626ms)","trace[2026370322] 'applied index is now lower than readState.Index' (duration: 169.423µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:57.914391Z","caller":"traceutil/trace.go:171","msg":"trace[1369004199] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"254.485807ms","start":"2026-05-06T14:15:57.659895Z","end":"2026-05-06T14:15:57.914381Z","steps":["trace[1369004199] 'process raft request' (duration: 254.197052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:57.91452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.010083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:1 size:2379"} {"level":"info","ts":"2026-05-06T14:15:57.914621Z","caller":"traceutil/trace.go:171","msg":"trace[253907474] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:1; response_revision:641; }","duration":"240.152996ms","start":"2026-05-06T14:15:57.674448Z","end":"2026-05-06T14:15:57.914601Z","steps":["trace[253907474] 'agreement among raft nodes before linearized reading' (duration: 239.942933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:57.918992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.396008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:1 size:553"} {"level":"warn","ts":"2026-05-06T14:15:57.919032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.093711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:57.919114Z","caller":"traceutil/trace.go:171","msg":"trace[769543728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"158.217563ms","start":"2026-05-06T14:15:57.760874Z","end":"2026-05-06T14:15:57.919091Z","steps":["trace[769543728] 'agreement among raft nodes before linearized reading' (duration: 158.01501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:57.919183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.463718ms","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-06T14:15:57.919218Z","caller":"traceutil/trace.go:171","msg":"trace[1634256361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"238.518939ms","start":"2026-05-06T14:15:57.680691Z","end":"2026-05-06T14:15:57.91921Z","steps":["trace[1634256361] 'agreement among raft nodes before linearized reading' (duration: 238.388197ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:57.919055Z","caller":"traceutil/trace.go:171","msg":"trace[270451893] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:1; response_revision:642; }","duration":"160.51377ms","start":"2026-05-06T14:15:57.758527Z","end":"2026-05-06T14:15:57.919041Z","steps":["trace[270451893] 'agreement among raft nodes before linearized reading' (duration: 160.376728ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:15:59.979049Z","caller":"traceutil/trace.go:171","msg":"trace[536977077] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"215.152988ms","start":"2026-05-06T14:15:59.763872Z","end":"2026-05-06T14:15:59.979025Z","steps":["trace[536977077] 'read index received' (duration: 214.852032ms)","trace[536977077] 'applied index is now lower than readState.Index' (duration: 299.976µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:15:59.979108Z","caller":"traceutil/trace.go:171","msg":"trace[1823473027] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"320.315423ms","start":"2026-05-06T14:15:59.658763Z","end":"2026-05-06T14:15:59.979078Z","steps":["trace[1823473027] 'process raft request' (duration: 320.052688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:59.979193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.31798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:59.979231Z","caller":"traceutil/trace.go:171","msg":"trace[1538393608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"215.411932ms","start":"2026-05-06T14:15:59.763809Z","end":"2026-05-06T14:15:59.979221Z","steps":["trace[1538393608] 'agreement among raft nodes before linearized reading' (duration: 215.341561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:15:59.97924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:15:59.658744Z","time spent":"320.423465ms","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4737,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:15:59.97937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.37138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:15:59.979454Z","caller":"traceutil/trace.go:171","msg":"trace[2115732921] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:686; }","duration":"149.501342ms","start":"2026-05-06T14:15:59.829933Z","end":"2026-05-06T14:15:59.979435Z","steps":["trace[2115732921] 'agreement among raft nodes before linearized reading' (duration: 149.38045ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:16:00.824768Z","caller":"traceutil/trace.go:171","msg":"trace[18483372] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"106.086618ms","start":"2026-05-06T14:16:00.718651Z","end":"2026-05-06T14:16:00.824737Z","steps":["trace[18483372] 'process raft request' (duration: 105.745922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:16:09.093076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.755229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-05-06T14:16:09.093215Z","caller":"traceutil/trace.go:171","msg":"trace[113608531] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:741; }","duration":"104.942901ms","start":"2026-05-06T14:16:08.988244Z","end":"2026-05-06T14:16:09.093187Z","steps":["trace[113608531] 'range keys from in-memory index tree' (duration: 104.635548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:16:10.911099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.722968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T14:16:10.911253Z","caller":"traceutil/trace.go:171","msg":"trace[743616404] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"332.136284ms","start":"2026-05-06T14:16:10.579087Z","end":"2026-05-06T14:16:10.911223Z","steps":["trace[743616404] 'process raft request' (duration: 47.205753ms)","trace[743616404] 'compare' (duration: 284.601047ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:16:10.911243Z","caller":"traceutil/trace.go:171","msg":"trace[1831277725] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"330.133914ms","start":"2026-05-06T14:16:10.581093Z","end":"2026-05-06T14:16:10.911227Z","steps":["trace[1831277725] 'read index received' (duration: 45.244944ms)","trace[1831277725] 'applied index is now lower than readState.Index' (duration: 284.88788ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:16:10.911547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.445669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-06T14:16:10.911593Z","caller":"traceutil/trace.go:171","msg":"trace[320641776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"330.529251ms","start":"2026-05-06T14:16:10.581054Z","end":"2026-05-06T14:16:10.911583Z","steps":["trace[320641776] 'agreement among raft nodes before linearized reading' (duration: 330.281227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:16:10.911617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:16:10.581037Z","time spent":"330.573881ms","remote":"127.0.0.1:54576","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-06T14:16:10.911759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:16:10.579075Z","time spent":"332.272726ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:16:10.91511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.223695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/envoy-gateway-system/\" range_end:\"/registry/persistentvolumeclaims/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T14:16:10.915129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.154641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-06T14:16:10.915123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.236423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/\" range_end:\"/registry/persistentvolumeclaims/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T14:16:10.915211Z","caller":"traceutil/trace.go:171","msg":"trace[1873365187] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/; range_end:/registry/persistentvolumeclaims/default0; response_count:0; response_revision:755; }","duration":"270.371244ms","start":"2026-05-06T14:16:10.644821Z","end":"2026-05-06T14:16:10.915192Z","steps":["trace[1873365187] 'agreement among raft nodes before linearized reading' (duration: 270.214752ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:16:10.915204Z","caller":"traceutil/trace.go:171","msg":"trace[1662587900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"313.263272ms","start":"2026-05-06T14:16:10.60192Z","end":"2026-05-06T14:16:10.915183Z","steps":["trace[1662587900] 'agreement among raft nodes before linearized reading' (duration: 313.072429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:16:10.915106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.428332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T14:16:10.915274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:16:10.6019Z","time spent":"313.360663ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T14:16:10.9153Z","caller":"traceutil/trace.go:171","msg":"trace[702309530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"153.658496ms","start":"2026-05-06T14:16:10.761623Z","end":"2026-05-06T14:16:10.915282Z","steps":["trace[702309530] 'agreement among raft nodes before linearized reading' (duration: 153.419433ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:16:10.915178Z","caller":"traceutil/trace.go:171","msg":"trace[1576253540] range","detail":"{range_begin:/registry/persistentvolumeclaims/envoy-gateway-system/; range_end:/registry/persistentvolumeclaims/envoy-gateway-system0; response_count:0; response_revision:755; }","duration":"168.329656ms","start":"2026-05-06T14:16:10.746834Z","end":"2026-05-06T14:16:10.915163Z","steps":["trace[1576253540] 'agreement among raft nodes before linearized reading' (duration: 168.188544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:16:18.5043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.463427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-05-06T14:16:18.504298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.634322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-05-06T14:16:18.504381Z","caller":"traceutil/trace.go:171","msg":"trace[1512167900] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:786; }","duration":"116.58906ms","start":"2026-05-06T14:16:18.387777Z","end":"2026-05-06T14:16:18.504366Z","steps":["trace[1512167900] 'range keys from in-memory index tree' (duration: 116.369506ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:16:18.504406Z","caller":"traceutil/trace.go:171","msg":"trace[901984140] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:786; }","duration":"100.779434ms","start":"2026-05-06T14:16:18.403605Z","end":"2026-05-06T14:16:18.504384Z","steps":["trace[901984140] 'range keys from in-memory index tree' (duration: 100.55236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:16:18.504499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.413963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T14:16:18.504605Z","caller":"traceutil/trace.go:171","msg":"trace[82302057] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:786; }","duration":"115.635936ms","start":"2026-05-06T14:16:18.388948Z","end":"2026-05-06T14:16:18.504584Z","steps":["trace[82302057] 'count revisions from in-memory index tree' (duration: 115.332132ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:16:19.091644Z","caller":"traceutil/trace.go:171","msg":"trace[1228369158] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"140.139817ms","start":"2026-05-06T14:16:18.951471Z","end":"2026-05-06T14:16:19.091611Z","steps":["trace[1228369158] 'process raft request' (duration: 139.895624ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:16:19.212559Z","caller":"traceutil/trace.go:171","msg":"trace[1687885384] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"117.313341ms","start":"2026-05-06T14:16:19.095222Z","end":"2026-05-06T14:16:19.212535Z","steps":["trace[1687885384] 'process raft request' (duration: 109.949527ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:16:19.237837Z","caller":"traceutil/trace.go:171","msg":"trace[520724933] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"142.391515ms","start":"2026-05-06T14:16:19.095418Z","end":"2026-05-06T14:16:19.23781Z","steps":["trace[520724933] 'process raft request' (duration: 142.219582ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:16:19.238117Z","caller":"traceutil/trace.go:171","msg":"trace[1684872552] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"141.462682ms","start":"2026-05-06T14:16:19.096635Z","end":"2026-05-06T14:16:19.238098Z","steps":["trace[1684872552] 'process raft request' (duration: 141.128967ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:16:20.703312Z","caller":"traceutil/trace.go:171","msg":"trace[708511009] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"376.530392ms","start":"2026-05-06T14:16:20.326757Z","end":"2026-05-06T14:16:20.703287Z","steps":["trace[708511009] 'read index received' (duration: 376.427051ms)","trace[708511009] 'applied index is now lower than readState.Index' (duration: 101.631µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:16:20.703389Z","caller":"traceutil/trace.go:171","msg":"trace[1041701701] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"391.937828ms","start":"2026-05-06T14:16:20.311426Z","end":"2026-05-06T14:16:20.703364Z","steps":["trace[1041701701] 'process raft request' (duration: 391.727665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:16:20.703616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:16:20.311411Z","time spent":"392.038771ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T14:16:20.704469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.709179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:66 size:49100"} {"level":"info","ts":"2026-05-06T14:16:20.704518Z","caller":"traceutil/trace.go:171","msg":"trace[996919297] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:66; response_revision:792; }","duration":"377.802111ms","start":"2026-05-06T14:16:20.326704Z","end":"2026-05-06T14:16:20.704506Z","steps":["trace[996919297] 'agreement among raft nodes before linearized reading' (duration: 376.690305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:16:20.704559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:16:20.326676Z","time spent":"377.872091ms","remote":"127.0.0.1:54398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":66,"response size":49122,"request content":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-05-06T14:16:20.76405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.300701ms","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":"warn","ts":"2026-05-06T14:16:20.764052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.598431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-05-06T14:16:20.76413Z","caller":"traceutil/trace.go:171","msg":"trace[552356019] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:792; }","duration":"330.437274ms","start":"2026-05-06T14:16:20.433675Z","end":"2026-05-06T14:16:20.764112Z","steps":["trace[552356019] 'agreement among raft nodes before linearized reading' (duration: 330.291232ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:16:20.764154Z","caller":"traceutil/trace.go:171","msg":"trace[1126808764] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:792; }","duration":"422.743313ms","start":"2026-05-06T14:16:20.341387Z","end":"2026-05-06T14:16:20.76413Z","steps":["trace[1126808764] 'agreement among raft nodes before linearized reading' (duration: 422.479349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:16:20.764177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:16:20.433654Z","time spent":"330.513014ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-06T14:16:20.764213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T14:16:20.341373Z","time spent":"422.819984ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"info","ts":"2026-05-06T14:16:25.529893Z","caller":"traceutil/trace.go:171","msg":"trace[728874327] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"221.126245ms","start":"2026-05-06T14:16:25.308721Z","end":"2026-05-06T14:16:25.529847Z","steps":["trace[728874327] 'process raft request' (duration: 220.815101ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:16:25.578227Z","caller":"traceutil/trace.go:171","msg":"trace[311399908] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"266.441726ms","start":"2026-05-06T14:16:25.311743Z","end":"2026-05-06T14:16:25.578185Z","steps":["trace[311399908] 'process raft request' (duration: 266.136322ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:16:25.578496Z","caller":"traceutil/trace.go:171","msg":"trace[759587159] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:841; }","duration":"216.761416ms","start":"2026-05-06T14:16:25.361697Z","end":"2026-05-06T14:16:25.578458Z","steps":["trace[759587159] 'read index received' (duration: 168.748887ms)","trace[759587159] 'applied index is now lower than readState.Index' (duration: 48.010299ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T14:16:25.578657Z","caller":"traceutil/trace.go:171","msg":"trace[1910003622] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"266.376336ms","start":"2026-05-06T14:16:25.312236Z","end":"2026-05-06T14:16:25.578613Z","steps":["trace[1910003622] 'process raft request' (duration: 265.877029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:16:25.57888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.696043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-06T14:16:25.578959Z","caller":"traceutil/trace.go:171","msg":"trace[799105570] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:806; }","duration":"170.863425ms","start":"2026-05-06T14:16:25.408072Z","end":"2026-05-06T14:16:25.578935Z","steps":["trace[799105570] 'agreement among raft nodes before linearized reading' (duration: 170.605272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:16:25.578879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.158511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j9ws4\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-05-06T14:16:25.579062Z","caller":"traceutil/trace.go:171","msg":"trace[2089866308] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j9ws4; range_end:; response_count:1; response_revision:806; }","duration":"217.417064ms","start":"2026-05-06T14:16:25.361621Z","end":"2026-05-06T14:16:25.579038Z","steps":["trace[2089866308] 'agreement among raft nodes before linearized reading' (duration: 217.017749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T14:16:25.812677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.590597ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-05-06T14:16:25.812798Z","caller":"traceutil/trace.go:171","msg":"trace[2000544267] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:843; }","duration":"224.835366ms","start":"2026-05-06T14:16:25.587928Z","end":"2026-05-06T14:16:25.812763Z","steps":["trace[2000544267] 'read index received' (duration: 71.079927ms)","trace[2000544267] 'applied index is now lower than readState.Index' (duration: 153.753729ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T14:16:25.813022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.091419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j9ws4\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-05-06T14:16:25.813059Z","caller":"traceutil/trace.go:171","msg":"trace[603734601] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j9ws4; range_end:; response_count:1; response_revision:806; }","duration":"225.156761ms","start":"2026-05-06T14:16:25.587891Z","end":"2026-05-06T14:16:25.813048Z","steps":["trace[603734601] 'agreement among raft nodes before linearized reading' (duration: 224.974677ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T14:16:27.580288Z","caller":"traceutil/trace.go:171","msg":"trace[1141918368] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"131.0568ms","start":"2026-05-06T14:16:27.449205Z","end":"2026-05-06T14:16:27.580262Z","steps":["trace[1141918368] 'process raft request' (duration: 130.863658ms)"],"step_count":1}