{"level":"warn","ts":"2026-04-07T05:45:28.329657Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T05:45:28.329768Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T05:45:28.329853Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T05:45:28.329868Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-07T05:45:28.329889Z","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-04-07T05:45:28.33071Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-04-07T05:45:28.33084Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T05:45:28.342019Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.850293ms"} {"level":"info","ts":"2026-04-07T05:45:28.36732Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-04-07T05:45:28.367451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T05:45:28.367488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-04-07T05:45:28.367511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T05:45:28.367522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-04-07T05:45:28.367588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-04-07T05:45:28.407612Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T05:45:28.410125Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T05:45:28.411536Z","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-04-07T05:45:28.416481Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T05:45:28.418067Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T05:45:28.41957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-04-07T05:45:28.419784Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-07T05:45:28.419972Z","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-04-07T05:45:28.420014Z","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-04-07T05:45:28.420062Z","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-04-07T05:45:28.426792Z","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-04-07T05:45:28.426998Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-07T05:45:28.427068Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-07T05:45:28.427141Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T05:45:28.427195Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T05:45:28.669472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T05:45:28.669555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T05:45:28.669578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-04-07T05:45:28.669598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-04-07T05:45:28.669611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-07T05:45:28.669628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-04-07T05:45:28.669642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-07T05:45:28.671818Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:45:28.672584Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T05:45:28.672593Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T05:45:28.672624Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T05:45:28.673149Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T05:45:28.673239Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T05:45:28.673371Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:45:28.673579Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:45:28.67363Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:45:28.674991Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-04-07T05:45:28.677014Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-07T05:45:35.777686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.891079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:45:35.777761Z","caller":"traceutil/trace.go:171","msg":"trace[185069258] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:284; }","duration":"194.02984ms","start":"2026-04-07T05:45:35.583716Z","end":"2026-04-07T05:45:35.777746Z","steps":["trace[185069258] 'range keys from in-memory index tree' (duration: 193.808719ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:45:35.935764Z","caller":"traceutil/trace.go:171","msg":"trace[33741268] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"101.710795ms","start":"2026-04-07T05:45:35.834026Z","end":"2026-04-07T05:45:35.935737Z","steps":["trace[33741268] 'process raft request' (duration: 99.187519ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:45:37.880448Z","caller":"traceutil/trace.go:171","msg":"trace[711537434] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:323; }","duration":"147.122822ms","start":"2026-04-07T05:45:37.733302Z","end":"2026-04-07T05:45:37.880425Z","steps":["trace[711537434] 'read index received' (duration: 7.698607ms)","trace[711537434] 'applied index is now lower than readState.Index' (duration: 139.423505ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:45:37.880616Z","caller":"traceutil/trace.go:171","msg":"trace[1721527898] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"167.597157ms","start":"2026-04-07T05:45:37.71301Z","end":"2026-04-07T05:45:37.880607Z","steps":["trace[1721527898] 'process raft request' (duration: 110.93682ms)","trace[1721527898] 'compare' (duration: 56.234434ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:45:37.881124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.794966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-07T05:45:37.881326Z","caller":"traceutil/trace.go:171","msg":"trace[897330987] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:313; }","duration":"147.920336ms","start":"2026-04-07T05:45:37.733267Z","end":"2026-04-07T05:45:37.881187Z","steps":["trace[897330987] 'agreement among raft nodes before linearized reading' (duration: 147.671145ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:45:39.649873Z","caller":"traceutil/trace.go:171","msg":"trace[2076469332] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"168.372115ms","start":"2026-04-07T05:45:39.481475Z","end":"2026-04-07T05:45:39.649848Z","steps":["trace[2076469332] 'process raft request' (duration: 168.234193ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:45:42.462053Z","caller":"traceutil/trace.go:171","msg":"trace[1547756889] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"104.655375ms","start":"2026-04-07T05:45:42.357372Z","end":"2026-04-07T05:45:42.462028Z","steps":["trace[1547756889] 'process raft request' (duration: 51.525663ms)","trace[1547756889] 'compare' (duration: 52.976131ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:45:43.092998Z","caller":"traceutil/trace.go:171","msg":"trace[22710050] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"135.263222ms","start":"2026-04-07T05:45:42.957715Z","end":"2026-04-07T05:45:43.092978Z","steps":["trace[22710050] 'read index received' (duration: 135.180671ms)","trace[22710050] 'applied index is now lower than readState.Index' (duration: 81.751µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:45:43.093121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.397692ms","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-04-07T05:45:43.093124Z","caller":"traceutil/trace.go:171","msg":"trace[1289027963] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"164.657554ms","start":"2026-04-07T05:45:42.928439Z","end":"2026-04-07T05:45:43.093097Z","steps":["trace[1289027963] 'process raft request' (duration: 164.366713ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:45:43.093156Z","caller":"traceutil/trace.go:171","msg":"trace[1874383909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:362; }","duration":"135.482424ms","start":"2026-04-07T05:45:42.957662Z","end":"2026-04-07T05:45:43.093144Z","steps":["trace[1874383909] 'agreement among raft nodes before linearized reading' (duration: 135.406693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:43.333573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.90774ms","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-04-07T05:45:43.333654Z","caller":"traceutil/trace.go:171","msg":"trace[60954021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:363; }","duration":"149.039621ms","start":"2026-04-07T05:45:43.184595Z","end":"2026-04-07T05:45:43.333635Z","steps":["trace[60954021] 'range keys from in-memory index tree' (duration: 148.767729ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:45:43.628327Z","caller":"traceutil/trace.go:171","msg":"trace[1609333110] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:378; }","duration":"100.737048ms","start":"2026-04-07T05:45:43.527567Z","end":"2026-04-07T05:45:43.628305Z","steps":["trace[1609333110] 'read index received' (duration: 100.484317ms)","trace[1609333110] 'applied index is now lower than readState.Index' (duration: 251.591µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:45:43.628382Z","caller":"traceutil/trace.go:171","msg":"trace[425250703] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"226.296432ms","start":"2026-04-07T05:45:43.402056Z","end":"2026-04-07T05:45:43.628352Z","steps":["trace[425250703] 'process raft request' (duration: 226.104651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:43.628443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.853989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:45:43.628473Z","caller":"traceutil/trace.go:171","msg":"trace[493614063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:365; }","duration":"100.938839ms","start":"2026-04-07T05:45:43.527526Z","end":"2026-04-07T05:45:43.628464Z","steps":["trace[493614063] 'agreement among raft nodes before linearized reading' (duration: 100.866009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:44.868547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.695526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:45:44.868659Z","caller":"traceutil/trace.go:171","msg":"trace[1751871765] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"145.592894ms","start":"2026-04-07T05:45:44.723049Z","end":"2026-04-07T05:45:44.868642Z","steps":["trace[1751871765] 'read index received' (duration: 8.893811ms)","trace[1751871765] 'applied index is now lower than readState.Index' (duration: 136.697273ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:45:44.868689Z","caller":"traceutil/trace.go:171","msg":"trace[1871808275] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"152.269303ms","start":"2026-04-07T05:45:44.716386Z","end":"2026-04-07T05:45:44.868655Z","steps":["trace[1871808275] 'process raft request' (duration: 15.654521ms)","trace[1871808275] 'compare' (duration: 135.407154ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:45:44.868744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.699945ms","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-04-07T05:45:44.869001Z","caller":"traceutil/trace.go:171","msg":"trace[688885984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:369; }","duration":"145.945866ms","start":"2026-04-07T05:45:44.722997Z","end":"2026-04-07T05:45:44.868943Z","steps":["trace[688885984] 'agreement among raft nodes before linearized reading' (duration: 145.691924ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:45:45.474892Z","caller":"traceutil/trace.go:171","msg":"trace[1968287912] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"119.621058ms","start":"2026-04-07T05:45:45.355211Z","end":"2026-04-07T05:45:45.474832Z","steps":["trace[1968287912] 'process raft request' (duration: 119.352787ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:45:45.763664Z","caller":"traceutil/trace.go:171","msg":"trace[754994413] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"168.53643ms","start":"2026-04-07T05:45:45.595106Z","end":"2026-04-07T05:45:45.763642Z","steps":["trace[754994413] 'process raft request' (duration: 168.407929ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:45:47.127359Z","caller":"traceutil/trace.go:171","msg":"trace[1476597749] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"235.316329ms","start":"2026-04-07T05:45:46.892013Z","end":"2026-04-07T05:45:47.127329Z","steps":["trace[1476597749] 'process raft request' (duration: 228.42133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:49.120822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.592621872s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-07T05:45:49.120927Z","caller":"traceutil/trace.go:171","msg":"trace[791327375] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:391; }","duration":"1.642218183s","start":"2026-04-07T05:45:47.478691Z","end":"2026-04-07T05:45:49.120909Z","steps":["trace[791327375] 'read index received' (duration: 49.386011ms)","trace[791327375] 'applied index is now lower than readState.Index' (duration: 1.592830402s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:45:49.121072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.642381795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T05:45:49.121107Z","caller":"traceutil/trace.go:171","msg":"trace[534058812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"1.642460695s","start":"2026-04-07T05:45:47.478636Z","end":"2026-04-07T05:45:49.121097Z","steps":["trace[534058812] 'agreement among raft nodes before linearized reading' (duration: 1.642354614s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:49.121095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.312683ms","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-04-07T05:45:49.121155Z","caller":"traceutil/trace.go:171","msg":"trace[625598700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"228.404753ms","start":"2026-04-07T05:45:48.892736Z","end":"2026-04-07T05:45:49.12114Z","steps":["trace[625598700] 'agreement among raft nodes before linearized reading' (duration: 228.298933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:49.121141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:47.478565Z","time spent":"1.642568375s","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T05:45:49.291527Z","caller":"traceutil/trace.go:171","msg":"trace[883672673] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"166.562823ms","start":"2026-04-07T05:45:49.12494Z","end":"2026-04-07T05:45:49.291502Z","steps":["trace[883672673] 'process raft request' (duration: 166.420022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:49.551752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.744091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:45:49.551886Z","caller":"traceutil/trace.go:171","msg":"trace[779526080] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:392; }","duration":"421.169569ms","start":"2026-04-07T05:45:49.130699Z","end":"2026-04-07T05:45:49.551869Z","steps":["trace[779526080] 'read index received' (duration: 160.76092ms)","trace[779526080] 'applied index is now lower than readState.Index' (duration: 260.407009ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:45:49.551966Z","caller":"traceutil/trace.go:171","msg":"trace[1913273355] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"426.548098ms","start":"2026-04-07T05:45:49.125395Z","end":"2026-04-07T05:45:49.551943Z","steps":["trace[1913273355] 'process raft request' (duration: 220.497405ms)","trace[1913273355] 'compare' (duration: 205.614061ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:45:49.552308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.597511ms","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-04-07T05:45:49.55349Z","caller":"traceutil/trace.go:171","msg":"trace[988055018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:379; }","duration":"421.691021ms","start":"2026-04-07T05:45:49.130665Z","end":"2026-04-07T05:45:49.552356Z","steps":["trace[988055018] 'agreement among raft nodes before linearized reading' (duration: 421.583851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:49.553524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.443602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:45:49.553614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:49.130647Z","time spent":"422.946339ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T05:45:49.553625Z","caller":"traceutil/trace.go:171","msg":"trace[274068614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:379; }","duration":"151.76803ms","start":"2026-04-07T05:45:49.40183Z","end":"2026-04-07T05:45:49.553598Z","steps":["trace[274068614] 'agreement among raft nodes before linearized reading' (duration: 150.452962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:49.553694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.428763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-04-07T05:45:49.55381Z","caller":"traceutil/trace.go:171","msg":"trace[922464425] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:379; }","duration":"392.376408ms","start":"2026-04-07T05:45:49.161392Z","end":"2026-04-07T05:45:49.553768Z","steps":["trace[922464425] 'agreement among raft nodes before linearized reading' (duration: 390.883499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:49.553806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:49.12537Z","time spent":"426.712119ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:45:49.554268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:49.161378Z","time spent":"392.8576ms","remote":"127.0.0.1:59304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4391,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-07T05:45:49.554388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.904508ms","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-04-07T05:45:49.554764Z","caller":"traceutil/trace.go:171","msg":"trace[1471837157] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:379; }","duration":"389.112379ms","start":"2026-04-07T05:45:49.165365Z","end":"2026-04-07T05:45:49.554477Z","steps":["trace[1471837157] 'agreement among raft nodes before linearized reading' (duration: 386.958337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:49.55486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:49.165355Z","time spent":"389.488451ms","remote":"127.0.0.1:59420","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-04-07T05:45:50.423693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.938017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:45:50.423878Z","caller":"traceutil/trace.go:171","msg":"trace[769149842] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"863.394331ms","start":"2026-04-07T05:45:49.560466Z","end":"2026-04-07T05:45:50.423861Z","steps":["trace[769149842] 'process raft request' (duration: 863.316001ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:45:50.423894Z","caller":"traceutil/trace.go:171","msg":"trace[103673277] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"863.402381ms","start":"2026-04-07T05:45:49.56045Z","end":"2026-04-07T05:45:50.423853Z","steps":["trace[103673277] 'process raft request' (duration: 318.217992ms)","trace[103673277] 'compare' (duration: 544.848136ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:45:50.423947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:49.560454Z","time spent":"863.462181ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:45:50.424047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:49.560433Z","time spent":"863.531362ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:45:50.427613Z","caller":"traceutil/trace.go:171","msg":"trace[1126370755] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"860.412814ms","start":"2026-04-07T05:45:49.567186Z","end":"2026-04-07T05:45:50.427599Z","steps":["trace[1126370755] 'process raft request' (duration: 860.129512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:50.427709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:49.567177Z","time spent":"860.479944ms","remote":"127.0.0.1:59304","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-04-07T05:45:51.667319Z","caller":"traceutil/trace.go:171","msg":"trace[1100892926] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"102.514256ms","start":"2026-04-07T05:45:51.564781Z","end":"2026-04-07T05:45:51.667296Z","steps":["trace[1100892926] 'process raft request' (duration: 102.387656ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:45:52.119047Z","caller":"traceutil/trace.go:171","msg":"trace[1874460824] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"296.539656ms","start":"2026-04-07T05:45:51.822482Z","end":"2026-04-07T05:45:52.119022Z","steps":["trace[1874460824] 'process raft request' (duration: 296.401005ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:45:52.39137Z","caller":"traceutil/trace.go:171","msg":"trace[1639667658] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"265.293419ms","start":"2026-04-07T05:45:52.12605Z","end":"2026-04-07T05:45:52.391343Z","steps":["trace[1639667658] 'process raft request' (duration: 181.299617ms)","trace[1639667658] 'compare' (duration: 83.747561ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:45:52.71705Z","caller":"traceutil/trace.go:171","msg":"trace[520554316] transaction","detail":"{read_only:false; number_of_response:1; response_revision:404; }","duration":"103.299178ms","start":"2026-04-07T05:45:52.613709Z","end":"2026-04-07T05:45:52.717008Z","steps":["trace[520554316] 'process raft request' (duration: 51.248152ms)","trace[520554316] 'compare' (duration: 51.918465ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:45:53.20038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.762088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:45:53.200591Z","caller":"traceutil/trace.go:171","msg":"trace[1279833347] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"569.139202ms","start":"2026-04-07T05:45:52.631439Z","end":"2026-04-07T05:45:53.200578Z","steps":["trace[1279833347] 'process raft request' (duration: 569.102611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:53.200646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:52.631419Z","time spent":"569.207542ms","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:45:53.200653Z","caller":"traceutil/trace.go:171","msg":"trace[1872472809] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"570.41314ms","start":"2026-04-07T05:45:52.630171Z","end":"2026-04-07T05:45:53.200584Z","steps":["trace[1872472809] 'process raft request' (duration: 194.37881ms)","trace[1872472809] 'compare' (duration: 375.651408ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:45:53.200683Z","caller":"traceutil/trace.go:171","msg":"trace[1550901026] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"569.214032ms","start":"2026-04-07T05:45:52.631433Z","end":"2026-04-07T05:45:53.200647Z","steps":["trace[1550901026] 'process raft request' (duration: 569.049651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:53.20085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:52.631415Z","time spent":"569.348083ms","remote":"127.0.0.1:59646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:45:53.201273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:52.630152Z","time spent":"570.655661ms","remote":"127.0.0.1:59220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:45:53.259917Z","caller":"traceutil/trace.go:171","msg":"trace[2145786429] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"536.974034ms","start":"2026-04-07T05:45:52.722923Z","end":"2026-04-07T05:45:53.259897Z","steps":["trace[2145786429] 'process raft request' (duration: 536.893823ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:45:53.259923Z","caller":"traceutil/trace.go:171","msg":"trace[1394993553] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"538.995224ms","start":"2026-04-07T05:45:52.720907Z","end":"2026-04-07T05:45:53.259902Z","steps":["trace[1394993553] 'process raft request' (duration: 538.770783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:53.260043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:52.722914Z","time spent":"537.064764ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:45:53.260031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:52.720885Z","time spent":"539.104314ms","remote":"127.0.0.1:59220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:45:53.736523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.651788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:45:53.736822Z","caller":"traceutil/trace.go:171","msg":"trace[509337508] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"530.882961ms","start":"2026-04-07T05:45:53.205893Z","end":"2026-04-07T05:45:53.736776Z","steps":["trace[509337508] 'process raft request' (duration: 272.865161ms)","trace[509337508] 'compare' (duration: 257.413596ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:45:53.737019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:53.205862Z","time spent":"531.051341ms","remote":"127.0.0.1:59220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:45:53.737289Z","caller":"traceutil/trace.go:171","msg":"trace[1163227261] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"527.918504ms","start":"2026-04-07T05:45:53.209345Z","end":"2026-04-07T05:45:53.737263Z","steps":["trace[1163227261] 'process raft request' (duration: 527.781943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:53.737503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:53.209322Z","time spent":"528.036196ms","remote":"127.0.0.1:59646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:45:53.73775Z","caller":"traceutil/trace.go:171","msg":"trace[236745694] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"531.344934ms","start":"2026-04-07T05:45:53.206385Z","end":"2026-04-07T05:45:53.73773Z","steps":["trace[236745694] 'process raft request' (duration: 530.294728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:53.737861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:53.206374Z","time spent":"531.426935ms","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:45:53.738146Z","caller":"traceutil/trace.go:171","msg":"trace[637043559] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"530.502229ms","start":"2026-04-07T05:45:53.20742Z","end":"2026-04-07T05:45:53.737922Z","steps":["trace[637043559] 'process raft request' (duration: 529.632454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:53.738286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:53.20741Z","time spent":"530.803711ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:45:54.093395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.741696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:45:54.093726Z","caller":"traceutil/trace.go:171","msg":"trace[1582046630] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"827.063738ms","start":"2026-04-07T05:45:53.266608Z","end":"2026-04-07T05:45:54.093672Z","steps":["trace[1582046630] 'process raft request' (duration: 826.917457ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:45:54.093785Z","caller":"traceutil/trace.go:171","msg":"trace[668399697] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"828.087173ms","start":"2026-04-07T05:45:53.265651Z","end":"2026-04-07T05:45:54.093738Z","steps":["trace[668399697] 'process raft request' (duration: 589.931934ms)","trace[668399697] 'compare' (duration: 237.598265ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:45:54.093611Z","caller":"traceutil/trace.go:171","msg":"trace[620465716] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:427; }","duration":"738.868186ms","start":"2026-04-07T05:45:53.354726Z","end":"2026-04-07T05:45:54.093594Z","steps":["trace[620465716] 'read index received' (duration: 124.255179ms)","trace[620465716] 'applied index is now lower than readState.Index' (duration: 614.612067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:45:54.094177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:53.265635Z","time spent":"828.281144ms","remote":"127.0.0.1:59220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:45:54.093815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:53.266591Z","time spent":"827.181508ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:45:54.094264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.511809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T05:45:54.094338Z","caller":"traceutil/trace.go:171","msg":"trace[1611901404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"739.632609ms","start":"2026-04-07T05:45:53.354688Z","end":"2026-04-07T05:45:54.09432Z","steps":["trace[1611901404] 'agreement among raft nodes before linearized reading' (duration: 739.455578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:54.094406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:53.354672Z","time spent":"739.703709ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T05:45:54.129756Z","caller":"traceutil/trace.go:171","msg":"trace[1256450781] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"384.794129ms","start":"2026-04-07T05:45:53.744931Z","end":"2026-04-07T05:45:54.129725Z","steps":["trace[1256450781] 'process raft request' (duration: 384.585898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:54.12977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.112099ms","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-04-07T05:45:54.130109Z","caller":"traceutil/trace.go:171","msg":"trace[923938765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"458.507833ms","start":"2026-04-07T05:45:53.671577Z","end":"2026-04-07T05:45:54.130085Z","steps":["trace[923938765] 'agreement among raft nodes before linearized reading' (duration: 458.09169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:54.130257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:53.671553Z","time spent":"458.643043ms","remote":"127.0.0.1:59420","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-04-07T05:45:54.130443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:53.744904Z","time spent":"385.069701ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:45:54.457888Z","caller":"traceutil/trace.go:171","msg":"trace[1613417386] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"359.278243ms","start":"2026-04-07T05:45:54.098591Z","end":"2026-04-07T05:45:54.457869Z","steps":["trace[1613417386] 'process raft request' (duration: 281.067678ms)","trace[1613417386] 'compare' (duration: 77.950083ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:45:54.457953Z","caller":"traceutil/trace.go:171","msg":"trace[392692697] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"356.001554ms","start":"2026-04-07T05:45:54.10194Z","end":"2026-04-07T05:45:54.457942Z","steps":["trace[392692697] 'process raft request' (duration: 355.752273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:54.457993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:54.098565Z","time spent":"359.371223ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:45:54.458007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:54.101932Z","time spent":"356.047984ms","remote":"127.0.0.1:59646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:45:54.458189Z","caller":"traceutil/trace.go:171","msg":"trace[2141718613] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"324.475533ms","start":"2026-04-07T05:45:54.133706Z","end":"2026-04-07T05:45:54.458181Z","steps":["trace[2141718613] 'process raft request' (duration: 324.200482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:54.458335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:54.133678Z","time spent":"324.531593ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:45:55.868161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235793429s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:45:55.868262Z","caller":"traceutil/trace.go:171","msg":"trace[1995449619] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"1.324897463s","start":"2026-04-07T05:45:54.543347Z","end":"2026-04-07T05:45:55.868244Z","steps":["trace[1995449619] 'read index received' (duration: 88.868373ms)","trace[1995449619] 'applied index is now lower than readState.Index' (duration: 1.23602763s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:45:55.868363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.325022954s","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-04-07T05:45:55.868399Z","caller":"traceutil/trace.go:171","msg":"trace[95314590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"1.325092784s","start":"2026-04-07T05:45:54.543295Z","end":"2026-04-07T05:45:55.868387Z","steps":["trace[95314590] 'agreement among raft nodes before linearized reading' (duration: 1.324995723s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:55.868431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:54.543275Z","time spent":"1.325148315s","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T05:45:55.868603Z","caller":"traceutil/trace.go:171","msg":"trace[869275398] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"1.40155663s","start":"2026-04-07T05:45:54.466997Z","end":"2026-04-07T05:45:55.868553Z","steps":["trace[869275398] 'process raft request' (duration: 165.292318ms)","trace[869275398] 'compare' (duration: 1.235635278s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:45:55.868748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:54.46698Z","time spent":"1.40169409s","remote":"127.0.0.1:59646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:45:56.404013Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.447665551s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T05:45:57.061542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.29926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:45:57.061656Z","caller":"traceutil/trace.go:171","msg":"trace[1279089587] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:439; }","duration":"659.923664ms","start":"2026-04-07T05:45:56.401722Z","end":"2026-04-07T05:45:57.061645Z","steps":["trace[1279089587] 'read index received' (duration: 2.469413ms)","trace[1279089587] 'applied index is now lower than readState.Index' (duration: 657.453621ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:45:57.061768Z","caller":"traceutil/trace.go:171","msg":"trace[1820428656] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"2.105533135s","start":"2026-04-07T05:45:54.956227Z","end":"2026-04-07T05:45:57.06176Z","steps":["trace[1820428656] 'process raft request' (duration: 1.447958763s)","trace[1820428656] 'compare' (duration: 657.054429ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:45:57.061816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:54.956206Z","time spent":"2.105588985s","remote":"127.0.0.1:59420","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-04-07T05:45:57.061961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.756222ms","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-04-07T05:45:57.062023Z","caller":"traceutil/trace.go:171","msg":"trace[1650172792] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"1.188619139s","start":"2026-04-07T05:45:55.873399Z","end":"2026-04-07T05:45:57.062018Z","steps":["trace[1650172792] 'process raft request' (duration: 1.188216127s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:57.06205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:55.873365Z","time spent":"1.188671499s","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:45:57.062049Z","caller":"traceutil/trace.go:171","msg":"trace[1909195320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:423; }","duration":"599.882792ms","start":"2026-04-07T05:45:56.462144Z","end":"2026-04-07T05:45:57.062027Z","steps":["trace[1909195320] 'agreement among raft nodes before linearized reading' (duration: 599.641901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:57.062213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.50299ms","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-04-07T05:45:57.062244Z","caller":"traceutil/trace.go:171","msg":"trace[881921566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"562.563181ms","start":"2026-04-07T05:45:56.499673Z","end":"2026-04-07T05:45:57.062236Z","steps":["trace[881921566] 'agreement among raft nodes before linearized reading' (duration: 562.511521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:57.062274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:56.499658Z","time spent":"562.611331ms","remote":"127.0.0.1:59420","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-04-07T05:45:57.062359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:56.462122Z","time spent":"600.070173ms","remote":"127.0.0.1:59420","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-04-07T05:45:57.062384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.385699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T05:45:57.062403Z","caller":"traceutil/trace.go:171","msg":"trace[1753183074] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:423; }","duration":"599.421019ms","start":"2026-04-07T05:45:56.462978Z","end":"2026-04-07T05:45:57.062399Z","steps":["trace[1753183074] 'agreement among raft nodes before linearized reading' (duration: 599.388769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:57.062419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:56.46297Z","time spent":"599.44517ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T05:45:57.062003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.281636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:45:57.062497Z","caller":"traceutil/trace.go:171","msg":"trace[1997815212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"660.805938ms","start":"2026-04-07T05:45:56.401687Z","end":"2026-04-07T05:45:57.062493Z","steps":["trace[1997815212] 'agreement among raft nodes before linearized reading' (duration: 660.297666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:57.062513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:45:56.401672Z","time spent":"660.837938ms","remote":"127.0.0.1:59136","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-04-07T05:45:59.239971Z","caller":"traceutil/trace.go:171","msg":"trace[1418282919] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:446; }","duration":"109.54687ms","start":"2026-04-07T05:45:59.130401Z","end":"2026-04-07T05:45:59.239948Z","steps":["trace[1418282919] 'read index received' (duration: 106.308652ms)","trace[1418282919] 'applied index is now lower than readState.Index' (duration: 3.237038ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:45:59.240004Z","caller":"traceutil/trace.go:171","msg":"trace[523751821] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"134.905024ms","start":"2026-04-07T05:45:59.105082Z","end":"2026-04-07T05:45:59.239987Z","steps":["trace[523751821] 'process raft request' (duration: 131.648017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:59.241818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.755646ms","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-04-07T05:45:59.241858Z","caller":"traceutil/trace.go:171","msg":"trace[320127895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:428; }","duration":"110.798556ms","start":"2026-04-07T05:45:59.131045Z","end":"2026-04-07T05:45:59.241844Z","steps":["trace[320127895] 'agreement among raft nodes before linearized reading' (duration: 110.024412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:45:59.241801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.373059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T05:45:59.242017Z","caller":"traceutil/trace.go:171","msg":"trace[1348285229] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:428; }","duration":"111.61108ms","start":"2026-04-07T05:45:59.130362Z","end":"2026-04-07T05:45:59.241973Z","steps":["trace[1348285229] 'agreement among raft nodes before linearized reading' (duration: 110.130742ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:04.193461Z","caller":"traceutil/trace.go:171","msg":"trace[409654828] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"108.84052ms","start":"2026-04-07T05:46:04.084603Z","end":"2026-04-07T05:46:04.193443Z","steps":["trace[409654828] 'process raft request' (duration: 42.237398ms)","trace[409654828] 'compare' (duration: 66.485271ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:04.585174Z","caller":"traceutil/trace.go:171","msg":"trace[1575096057] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"103.101937ms","start":"2026-04-07T05:46:04.482049Z","end":"2026-04-07T05:46:04.585151Z","steps":["trace[1575096057] 'process raft request' (duration: 102.677393ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:04.712965Z","caller":"traceutil/trace.go:171","msg":"trace[1633876139] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"127.395618ms","start":"2026-04-07T05:46:04.585542Z","end":"2026-04-07T05:46:04.712937Z","steps":["trace[1633876139] 'read index received' (duration: 61.795366ms)","trace[1633876139] 'applied index is now lower than readState.Index' (duration: 65.598742ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:04.71299Z","caller":"traceutil/trace.go:171","msg":"trace[382227087] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"179.008142ms","start":"2026-04-07T05:46:04.53395Z","end":"2026-04-07T05:46:04.712958Z","steps":["trace[382227087] 'process raft request' (duration: 113.313908ms)","trace[382227087] 'compare' (duration: 65.537432ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:46:04.713158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.589478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"warn","ts":"2026-04-07T05:46:04.71325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.376897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c8msq\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-04-07T05:46:04.713254Z","caller":"traceutil/trace.go:171","msg":"trace[1556682368] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:466; }","duration":"176.724421ms","start":"2026-04-07T05:46:04.536509Z","end":"2026-04-07T05:46:04.713234Z","steps":["trace[1556682368] 'agreement among raft nodes before linearized reading' (duration: 176.485998ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:04.713295Z","caller":"traceutil/trace.go:171","msg":"trace[1404040482] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c8msq; range_end:; response_count:1; response_revision:466; }","duration":"179.469789ms","start":"2026-04-07T05:46:04.533812Z","end":"2026-04-07T05:46:04.713282Z","steps":["trace[1404040482] 'agreement among raft nodes before linearized reading' (duration: 179.254466ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:04.716829Z","caller":"traceutil/trace.go:171","msg":"trace[1526692660] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"122.661782ms","start":"2026-04-07T05:46:04.594148Z","end":"2026-04-07T05:46:04.71681Z","steps":["trace[1526692660] 'process raft request' (duration: 122.50092ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:04.717527Z","caller":"traceutil/trace.go:171","msg":"trace[1016717857] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"116.988355ms","start":"2026-04-07T05:46:04.600523Z","end":"2026-04-07T05:46:04.717511Z","steps":["trace[1016717857] 'process raft request' (duration: 116.238488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:06.29936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.24094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:46:06.299663Z","caller":"traceutil/trace.go:171","msg":"trace[1993352620] transaction","detail":"{read_only:false; number_of_response:1; response_revision:491; }","duration":"163.650467ms","start":"2026-04-07T05:46:06.135995Z","end":"2026-04-07T05:46:06.299645Z","steps":["trace[1993352620] 'process raft request' (duration: 163.597995ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:06.299696Z","caller":"traceutil/trace.go:171","msg":"trace[1392427007] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"165.246582ms","start":"2026-04-07T05:46:06.134423Z","end":"2026-04-07T05:46:06.29967Z","steps":["trace[1392427007] 'process raft request' (duration: 165.038152ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:06.299909Z","caller":"traceutil/trace.go:171","msg":"trace[1395600365] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"165.945445ms","start":"2026-04-07T05:46:06.133952Z","end":"2026-04-07T05:46:06.299897Z","steps":["trace[1395600365] 'process raft request' (duration: 39.086656ms)","trace[1395600365] 'compare' (duration: 126.062652ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:06.299954Z","caller":"traceutil/trace.go:171","msg":"trace[2071854712] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"165.371428ms","start":"2026-04-07T05:46:06.134568Z","end":"2026-04-07T05:46:06.29994Z","steps":["trace[2071854712] 'process raft request' (duration: 164.937007ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:06.300193Z","caller":"traceutil/trace.go:171","msg":"trace[461222925] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"164.233035ms","start":"2026-04-07T05:46:06.135949Z","end":"2026-04-07T05:46:06.300182Z","steps":["trace[461222925] 'process raft request' (duration: 163.591925ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:06.33026Z","caller":"traceutil/trace.go:171","msg":"trace[95739470] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"173.267529ms","start":"2026-04-07T05:46:06.156969Z","end":"2026-04-07T05:46:06.330237Z","steps":["trace[95739470] 'process raft request' (duration: 173.015877ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:06.330382Z","caller":"traceutil/trace.go:171","msg":"trace[1848680136] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"172.113375ms","start":"2026-04-07T05:46:06.158235Z","end":"2026-04-07T05:46:06.330348Z","steps":["trace[1848680136] 'process raft request' (duration: 171.854713ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:06.330462Z","caller":"traceutil/trace.go:171","msg":"trace[655018498] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"172.055892ms","start":"2026-04-07T05:46:06.158361Z","end":"2026-04-07T05:46:06.330417Z","steps":["trace[655018498] 'process raft request' (duration: 171.860123ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:06.330726Z","caller":"traceutil/trace.go:171","msg":"trace[674388336] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"166.774754ms","start":"2026-04-07T05:46:06.163939Z","end":"2026-04-07T05:46:06.330714Z","steps":["trace[674388336] 'process raft request' (duration: 166.595216ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:06.330743Z","caller":"traceutil/trace.go:171","msg":"trace[123580760] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"165.043233ms","start":"2026-04-07T05:46:06.165684Z","end":"2026-04-07T05:46:06.330727Z","steps":["trace[123580760] 'process raft request' (duration: 165.004491ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:06.330788Z","caller":"traceutil/trace.go:171","msg":"trace[1555739104] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"166.115492ms","start":"2026-04-07T05:46:06.164666Z","end":"2026-04-07T05:46:06.330781Z","steps":["trace[1555739104] 'process raft request' (duration: 165.939874ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:06.330646Z","caller":"traceutil/trace.go:171","msg":"trace[1200122544] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"171.428662ms","start":"2026-04-07T05:46:06.15919Z","end":"2026-04-07T05:46:06.330618Z","steps":["trace[1200122544] 'process raft request' (duration: 171.162549ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:06.330808Z","caller":"traceutil/trace.go:171","msg":"trace[1721565899] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"166.05756ms","start":"2026-04-07T05:46:06.164744Z","end":"2026-04-07T05:46:06.330801Z","steps":["trace[1721565899] 'process raft request' (duration: 165.908823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:06.779302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.534568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:46:06.779532Z","caller":"traceutil/trace.go:171","msg":"trace[1435613158] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"507.56529ms","start":"2026-04-07T05:46:06.271926Z","end":"2026-04-07T05:46:06.779491Z","steps":["trace[1435613158] 'process raft request' (duration: 322.604433ms)","trace[1435613158] 'compare' (duration: 184.330518ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:06.779602Z","caller":"traceutil/trace.go:171","msg":"trace[1287651159] transaction","detail":"{read_only:false; number_of_response:0; response_revision:500; }","duration":"476.495731ms","start":"2026-04-07T05:46:06.303036Z","end":"2026-04-07T05:46:06.779532Z","steps":["trace[1287651159] 'process raft request' (duration: 476.347904ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:06.779664Z","caller":"traceutil/trace.go:171","msg":"trace[1595305448] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"464.435775ms","start":"2026-04-07T05:46:06.315206Z","end":"2026-04-07T05:46:06.779642Z","steps":["trace[1595305448] 'process raft request' (duration: 464.339841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:06.779703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:06.271901Z","time spent":"507.728929ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:46:06.779774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:06.315177Z","time spent":"464.54408ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:46:06.779827Z","caller":"traceutil/trace.go:171","msg":"trace[274625454] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"467.600003ms","start":"2026-04-07T05:46:06.312192Z","end":"2026-04-07T05:46:06.779792Z","steps":["trace[274625454] 'process raft request' (duration: 467.249927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:06.779887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:06.303016Z","time spent":"476.709291ms","remote":"127.0.0.1:59724","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-04-07T05:46:06.779939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:06.31218Z","time spent":"467.700188ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:46:07.013612Z","caller":"traceutil/trace.go:171","msg":"trace[591437651] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"651.447412ms","start":"2026-04-07T05:46:06.36214Z","end":"2026-04-07T05:46:07.013587Z","steps":["trace[591437651] 'process raft request' (duration: 609.51327ms)","trace[591437651] 'compare' (duration: 41.577126ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:07.013759Z","caller":"traceutil/trace.go:171","msg":"trace[1258170817] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"649.226678ms","start":"2026-04-07T05:46:06.36452Z","end":"2026-04-07T05:46:07.013747Z","steps":["trace[1258170817] 'process raft request' (duration: 648.922074ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:07.013762Z","caller":"traceutil/trace.go:171","msg":"trace[1324858827] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:524; }","duration":"300.012344ms","start":"2026-04-07T05:46:06.713724Z","end":"2026-04-07T05:46:07.013737Z","steps":["trace[1324858827] 'read index received' (duration: 258.03688ms)","trace[1324858827] 'applied index is now lower than readState.Index' (duration: 41.973544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:46:07.013862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:06.364511Z","time spent":"649.274911ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:46:07.013931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.185382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:46:07.013969Z","caller":"traceutil/trace.go:171","msg":"trace[302547030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:504; }","duration":"300.273856ms","start":"2026-04-07T05:46:06.713684Z","end":"2026-04-07T05:46:07.013958Z","steps":["trace[302547030] 'agreement among raft nodes before linearized reading' (duration: 300.161031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:07.013998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:06.713666Z","time spent":"300.324569ms","remote":"127.0.0.1:59136","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-04-07T05:46:07.014013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:06.362113Z","time spent":"651.602669ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:46:07.029091Z","caller":"traceutil/trace.go:171","msg":"trace[608586522] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"227.669618ms","start":"2026-04-07T05:46:06.801409Z","end":"2026-04-07T05:46:07.029079Z","steps":["trace[608586522] 'process raft request' (duration: 227.566933ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:07.02909Z","caller":"traceutil/trace.go:171","msg":"trace[984613964] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"242.469793ms","start":"2026-04-07T05:46:06.786591Z","end":"2026-04-07T05:46:07.029061Z","steps":["trace[984613964] 'process raft request' (duration: 242.299745ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:07.029465Z","caller":"traceutil/trace.go:171","msg":"trace[1040645605] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"197.234117ms","start":"2026-04-07T05:46:06.832201Z","end":"2026-04-07T05:46:07.029435Z","steps":["trace[1040645605] 'process raft request' (duration: 196.805977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:07.029507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.488585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:995"} {"level":"info","ts":"2026-04-07T05:46:07.029511Z","caller":"traceutil/trace.go:171","msg":"trace[606811646] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"188.375901ms","start":"2026-04-07T05:46:06.84111Z","end":"2026-04-07T05:46:07.029486Z","steps":["trace[606811646] 'process raft request' (duration: 188.185853ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:07.029543Z","caller":"traceutil/trace.go:171","msg":"trace[1818329823] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:508; }","duration":"248.560018ms","start":"2026-04-07T05:46:06.78097Z","end":"2026-04-07T05:46:07.02953Z","steps":["trace[1818329823] 'agreement among raft nodes before linearized reading' (duration: 248.434993ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:07.201679Z","caller":"traceutil/trace.go:171","msg":"trace[1406906497] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"104.908886ms","start":"2026-04-07T05:46:07.096744Z","end":"2026-04-07T05:46:07.201652Z","steps":["trace[1406906497] 'process raft request' (duration: 60.648183ms)","trace[1406906497] 'compare' (duration: 44.043013ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:07.202012Z","caller":"traceutil/trace.go:171","msg":"trace[299826909] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"102.231483ms","start":"2026-04-07T05:46:07.099756Z","end":"2026-04-07T05:46:07.201987Z","steps":["trace[299826909] 'process raft request' (duration: 101.840335ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:07.431661Z","caller":"traceutil/trace.go:171","msg":"trace[949299371] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"177.419159ms","start":"2026-04-07T05:46:07.254196Z","end":"2026-04-07T05:46:07.431615Z","steps":["trace[949299371] 'process raft request' (duration: 176.425354ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:07.433565Z","caller":"traceutil/trace.go:171","msg":"trace[14428792] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"189.648007ms","start":"2026-04-07T05:46:07.243887Z","end":"2026-04-07T05:46:07.433535Z","steps":["trace[14428792] 'process raft request' (duration: 185.41888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:07.841005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.96553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:46:07.841265Z","caller":"traceutil/trace.go:171","msg":"trace[1242408996] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"579.473637ms","start":"2026-04-07T05:46:07.261762Z","end":"2026-04-07T05:46:07.841235Z","steps":["trace[1242408996] 'process raft request' (duration: 469.191152ms)","trace[1242408996] 'compare' (duration: 109.828474ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:07.841319Z","caller":"traceutil/trace.go:171","msg":"trace[1614268196] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:538; }","duration":"489.927904ms","start":"2026-04-07T05:46:07.35134Z","end":"2026-04-07T05:46:07.841268Z","steps":["trace[1614268196] 'read index received' (duration: 77.993817ms)","trace[1614268196] 'applied index is now lower than readState.Index' (duration: 411.933147ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:46:07.841437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.261744Z","time spent":"579.590923ms","remote":"127.0.0.1:59420","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-04-07T05:46:07.841373Z","caller":"traceutil/trace.go:171","msg":"trace[1078840210] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"572.503964ms","start":"2026-04-07T05:46:07.268837Z","end":"2026-04-07T05:46:07.841341Z","steps":["trace[1078840210] 'process raft request' (duration: 572.267773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:07.841621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.26882Z","time spent":"572.712323ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:46:07.84163Z","caller":"traceutil/trace.go:171","msg":"trace[587398528] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"519.323477ms","start":"2026-04-07T05:46:07.322294Z","end":"2026-04-07T05:46:07.841617Z","steps":["trace[587398528] 'process raft request' (duration: 518.875086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:07.841694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.354784ms","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-04-07T05:46:07.841725Z","caller":"traceutil/trace.go:171","msg":"trace[2143377627] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"490.421076ms","start":"2026-04-07T05:46:07.351295Z","end":"2026-04-07T05:46:07.841716Z","steps":["trace[2143377627] 'agreement among raft nodes before linearized reading' (duration: 490.358823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:07.841756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.351276Z","time spent":"490.473169ms","remote":"127.0.0.1:59420","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-04-07T05:46:07.841789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.322267Z","time spent":"519.470054ms","remote":"127.0.0.1:59420","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-04-07T05:46:08.181827Z","caller":"traceutil/trace.go:171","msg":"trace[1592359094] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"688.149413ms","start":"2026-04-07T05:46:07.493655Z","end":"2026-04-07T05:46:08.181804Z","steps":["trace[1592359094] 'process raft request' (duration: 688.067849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.182213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.493625Z","time spent":"688.536681ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:46:08.181861Z","caller":"traceutil/trace.go:171","msg":"trace[1470714751] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"733.079797ms","start":"2026-04-07T05:46:07.448749Z","end":"2026-04-07T05:46:08.181829Z","steps":["trace[1470714751] 'process raft request' (duration: 732.743062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.182582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.448722Z","time spent":"733.744387ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:46:08.347436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.636876ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-07T05:46:08.347541Z","caller":"traceutil/trace.go:171","msg":"trace[212108416] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:543; }","duration":"506.172246ms","start":"2026-04-07T05:46:07.841352Z","end":"2026-04-07T05:46:08.347524Z","steps":["trace[212108416] 'read index received' (duration: 340.1124ms)","trace[212108416] 'applied index is now lower than readState.Index' (duration: 166.058336ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:46:08.347603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.879271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:46:08.347628Z","caller":"traceutil/trace.go:171","msg":"trace[1903421902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"945.935663ms","start":"2026-04-07T05:46:07.401684Z","end":"2026-04-07T05:46:08.34762Z","steps":["trace[1903421902] 'agreement among raft nodes before linearized reading' (duration: 945.884331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.347614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.79793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:46:08.347667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.401666Z","time spent":"945.994286ms","remote":"127.0.0.1:59152","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-04-07T05:46:08.347682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.620371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:46:08.347728Z","caller":"traceutil/trace.go:171","msg":"trace[424023677] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:523; }","duration":"718.695814ms","start":"2026-04-07T05:46:07.629018Z","end":"2026-04-07T05:46:08.347714Z","steps":["trace[424023677] 'agreement among raft nodes before linearized reading' (duration: 718.633891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.347786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.628999Z","time spent":"718.772577ms","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:46:08.347812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.538308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:46:08.347996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.054004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:46:08.348075Z","caller":"traceutil/trace.go:171","msg":"trace[2090408685] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:523; }","duration":"734.850502ms","start":"2026-04-07T05:46:07.613202Z","end":"2026-04-07T05:46:08.348052Z","steps":["trace[2090408685] 'agreement among raft nodes before linearized reading' (duration: 734.466705ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:08.347682Z","caller":"traceutil/trace.go:171","msg":"trace[752151281] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:523; }","duration":"734.910315ms","start":"2026-04-07T05:46:07.612755Z","end":"2026-04-07T05:46:08.347665Z","steps":["trace[752151281] 'agreement among raft nodes before linearized reading' (duration: 734.7925ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:08.348148Z","caller":"traceutil/trace.go:171","msg":"trace[1834720790] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:523; }","duration":"751.191331ms","start":"2026-04-07T05:46:07.596899Z","end":"2026-04-07T05:46:08.34809Z","steps":["trace[1834720790] 'agreement among raft nodes before linearized reading' (duration: 750.987352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.348196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.612735Z","time spent":"735.44322ms","remote":"127.0.0.1:36692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T05:46:08.348248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.596892Z","time spent":"751.335757ms","remote":"127.0.0.1:36682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-07T05:46:08.347884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.653262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:46:08.348405Z","caller":"traceutil/trace.go:171","msg":"trace[1013378278] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:523; }","duration":"752.217448ms","start":"2026-04-07T05:46:07.596163Z","end":"2026-04-07T05:46:08.348381Z","steps":["trace[1013378278] 'agreement among raft nodes before linearized reading' (duration: 751.659313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.348474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.596143Z","time spent":"752.310301ms","remote":"127.0.0.1:36682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T05:46:08.347864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.796198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:46:08.348612Z","caller":"traceutil/trace.go:171","msg":"trace[726302527] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:523; }","duration":"719.593965ms","start":"2026-04-07T05:46:07.628987Z","end":"2026-04-07T05:46:08.348581Z","steps":["trace[726302527] 'agreement among raft nodes before linearized reading' (duration: 718.758767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.347616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.635281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:46:08.348679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.628978Z","time spent":"719.683819ms","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"info","ts":"2026-04-07T05:46:08.348721Z","caller":"traceutil/trace.go:171","msg":"trace[1546590552] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:523; }","duration":"719.771323ms","start":"2026-04-07T05:46:07.628934Z","end":"2026-04-07T05:46:08.348705Z","steps":["trace[1546590552] 'agreement among raft nodes before linearized reading' (duration: 718.630301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.348765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.62892Z","time spent":"719.832626ms","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T05:46:08.347949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.548887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:46:08.348857Z","caller":"traceutil/trace.go:171","msg":"trace[1523324335] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:523; }","duration":"752.473409ms","start":"2026-04-07T05:46:07.596374Z","end":"2026-04-07T05:46:08.348847Z","steps":["trace[1523324335] 'agreement among raft nodes before linearized reading' (duration: 751.546237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.348887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.596366Z","time spent":"752.510791ms","remote":"127.0.0.1:36682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:46:08.347973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.306977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T05:46:08.348659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.613193Z","time spent":"735.44849ms","remote":"127.0.0.1:36692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"info","ts":"2026-04-07T05:46:08.349005Z","caller":"traceutil/trace.go:171","msg":"trace[1611571739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"854.360035ms","start":"2026-04-07T05:46:07.49463Z","end":"2026-04-07T05:46:08.34899Z","steps":["trace[1611571739] 'agreement among raft nodes before linearized reading' (duration: 853.300347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.347985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.742104ms","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-04-07T05:46:08.349113Z","caller":"traceutil/trace.go:171","msg":"trace[422914660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"946.982801ms","start":"2026-04-07T05:46:07.40212Z","end":"2026-04-07T05:46:08.349103Z","steps":["trace[422914660] 'agreement among raft nodes before linearized reading' (duration: 945.783396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.349152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.402104Z","time spent":"947.039124ms","remote":"127.0.0.1:59420","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-04-07T05:46:08.34915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.49462Z","time spent":"854.46511ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T05:46:08.349712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.959717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:46:08.349842Z","caller":"traceutil/trace.go:171","msg":"trace[610952908] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:523; }","duration":"737.009162ms","start":"2026-04-07T05:46:07.612815Z","end":"2026-04-07T05:46:08.349824Z","steps":["trace[610952908] 'agreement among raft nodes before linearized reading' (duration: 734.947337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.349989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.612809Z","time spent":"737.117466ms","remote":"127.0.0.1:36692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-04-07T05:46:08.500874Z","caller":"traceutil/trace.go:171","msg":"trace[1245144657] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"654.759329ms","start":"2026-04-07T05:46:07.846092Z","end":"2026-04-07T05:46:08.500851Z","steps":["trace[1245144657] 'process raft request' (duration: 653.895349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.501024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.846071Z","time spent":"654.878314ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:46:08.501065Z","caller":"traceutil/trace.go:171","msg":"trace[928336118] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"644.229271ms","start":"2026-04-07T05:46:07.8568Z","end":"2026-04-07T05:46:08.501029Z","steps":["trace[928336118] 'process raft request' (duration: 643.98698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.501235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:07.856773Z","time spent":"644.364527ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:46:08.740603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.19518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:46:08.740845Z","caller":"traceutil/trace.go:171","msg":"trace[380782196] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"530.891996ms","start":"2026-04-07T05:46:08.209913Z","end":"2026-04-07T05:46:08.740805Z","steps":["trace[380782196] 'process raft request' (duration: 423.412463ms)","trace[380782196] 'compare' (duration: 106.97331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:46:08.741017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:08.209896Z","time spent":"531.040652ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:46:08.741028Z","caller":"traceutil/trace.go:171","msg":"trace[1874558650] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"531.027252ms","start":"2026-04-07T05:46:08.209971Z","end":"2026-04-07T05:46:08.740998Z","steps":["trace[1874558650] 'process raft request' (duration: 530.76897ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:08.741101Z","caller":"traceutil/trace.go:171","msg":"trace[145911412] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"227.386195ms","start":"2026-04-07T05:46:08.513699Z","end":"2026-04-07T05:46:08.741085Z","steps":["trace[145911412] 'process raft request' (duration: 227.314631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.741147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:08.209968Z","time spent":"531.117686ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:46:08.741217Z","caller":"traceutil/trace.go:171","msg":"trace[1037484920] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:546; }","duration":"339.94286ms","start":"2026-04-07T05:46:08.40126Z","end":"2026-04-07T05:46:08.741203Z","steps":["trace[1037484920] 'read index received' (duration: 98.828146ms)","trace[1037484920] 'applied index is now lower than readState.Index' (duration: 241.112824ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:08.741242Z","caller":"traceutil/trace.go:171","msg":"trace[1373437155] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"385.738587ms","start":"2026-04-07T05:46:08.355469Z","end":"2026-04-07T05:46:08.741208Z","steps":["trace[1373437155] 'process raft request' (duration: 385.457425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.74134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.070016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:46:08.741371Z","caller":"traceutil/trace.go:171","msg":"trace[1469552831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"340.142509ms","start":"2026-04-07T05:46:08.401219Z","end":"2026-04-07T05:46:08.741362Z","steps":["trace[1469552831] 'agreement among raft nodes before linearized reading' (duration: 340.029774ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:08.741386Z","caller":"traceutil/trace.go:171","msg":"trace[98805987] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"387.74883ms","start":"2026-04-07T05:46:08.35362Z","end":"2026-04-07T05:46:08.741369Z","steps":["trace[98805987] 'process raft request' (duration: 387.243277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:08.741406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:08.401199Z","time spent":"340.200892ms","remote":"127.0.0.1:59136","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-04-07T05:46:08.7414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:08.355448Z","time spent":"385.866833ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:46:08.741513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:08.353602Z","time spent":"387.861954ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:46:09.465477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.439844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:46:09.46558Z","caller":"traceutil/trace.go:171","msg":"trace[1068306295] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"700.28444ms","start":"2026-04-07T05:46:08.765279Z","end":"2026-04-07T05:46:09.465564Z","steps":["trace[1068306295] 'process raft request' (duration: 551.665547ms)","trace[1068306295] 'compare' (duration: 148.318269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:46:09.465638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:08.765254Z","time spent":"700.356533ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:46:09.795744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.215653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:46:09.796613Z","caller":"traceutil/trace.go:171","msg":"trace[450589754] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.028855769s","start":"2026-04-07T05:46:08.767713Z","end":"2026-04-07T05:46:09.796569Z","steps":["trace[450589754] 'process raft request' (duration: 843.761937ms)","trace[450589754] 'compare' (duration: 184.105637ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:46:09.797497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:08.767689Z","time spent":"1.029454186s","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:46:09.802212Z","caller":"traceutil/trace.go:171","msg":"trace[274368973] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:554; }","duration":"400.260664ms","start":"2026-04-07T05:46:09.401914Z","end":"2026-04-07T05:46:09.802175Z","steps":["trace[274368973] 'read index received' (duration: 209.475635ms)","trace[274368973] 'applied index is now lower than readState.Index' (duration: 190.784459ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:09.802276Z","caller":"traceutil/trace.go:171","msg":"trace[760330612] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"774.122094ms","start":"2026-04-07T05:46:09.028136Z","end":"2026-04-07T05:46:09.802258Z","steps":["trace[760330612] 'process raft request' (duration: 772.837666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:09.802335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.40342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:46:09.802373Z","caller":"traceutil/trace.go:171","msg":"trace[1621177779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"400.489755ms","start":"2026-04-07T05:46:09.401874Z","end":"2026-04-07T05:46:09.802364Z","steps":["trace[1621177779] 'agreement among raft nodes before linearized reading' (duration: 400.3951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:09.802368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:09.028116Z","time spent":"774.207458ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:46:09.802406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:09.401855Z","time spent":"400.542677ms","remote":"127.0.0.1:59136","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-04-07T05:46:10.381952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.412471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-04-07T05:46:10.382072Z","caller":"traceutil/trace.go:171","msg":"trace[1525729748] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:552; }","duration":"127.576428ms","start":"2026-04-07T05:46:10.254469Z","end":"2026-04-07T05:46:10.382045Z","steps":["trace[1525729748] 'range keys from in-memory index tree' (duration: 127.210402ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:10.40894Z","caller":"traceutil/trace.go:171","msg":"trace[1537051876] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"152.379257ms","start":"2026-04-07T05:46:10.256547Z","end":"2026-04-07T05:46:10.408926Z","steps":["trace[1537051876] 'process raft request' (duration: 152.2071ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:10.955498Z","caller":"traceutil/trace.go:171","msg":"trace[1608407700] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"164.209046ms","start":"2026-04-07T05:46:10.791265Z","end":"2026-04-07T05:46:10.955474Z","steps":["trace[1608407700] 'process raft request' (duration: 83.149905ms)","trace[1608407700] 'compare' (duration: 80.8183ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:46:12.373871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743848004400346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:46:12.874119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743848004400346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:46:13.375351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743848004400346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:46:13.391231Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.590359623s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T05:46:13.391742Z","caller":"traceutil/trace.go:171","msg":"trace[1776094563] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.590987941s","start":"2026-04-07T05:46:11.800729Z","end":"2026-04-07T05:46:13.391717Z","steps":["trace[1776094563] 'process raft request' (duration: 1.590834985s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:13.391881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:11.800702Z","time spent":"1.591110206s","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:46:13.41293Z","caller":"traceutil/trace.go:171","msg":"trace[1965465304] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:607; }","duration":"1.539940886s","start":"2026-04-07T05:46:11.872962Z","end":"2026-04-07T05:46:13.412903Z","steps":["trace[1965465304] 'read index received' (duration: 1.519132751s)","trace[1965465304] 'applied index is now lower than readState.Index' (duration: 20.807195ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:13.41333Z","caller":"traceutil/trace.go:171","msg":"trace[1581507394] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.41335817s","start":"2026-04-07T05:46:11.999965Z","end":"2026-04-07T05:46:13.413323Z","steps":["trace[1581507394] 'process raft request' (duration: 1.412664581s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:13.41346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:11.999946Z","time spent":"1.413411333s","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:46:13.413566Z","caller":"traceutil/trace.go:171","msg":"trace[45566370] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"811.681105ms","start":"2026-04-07T05:46:12.601869Z","end":"2026-04-07T05:46:13.41355Z","steps":["trace[45566370] 'process raft request' (duration: 810.965484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:13.413849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:12.601843Z","time spent":"811.758038ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:46:13.413874Z","caller":"traceutil/trace.go:171","msg":"trace[365579939] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.214530587s","start":"2026-04-07T05:46:12.199315Z","end":"2026-04-07T05:46:13.413846Z","steps":["trace[365579939] 'process raft request' (duration: 1.21344476s)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:13.413922Z","caller":"traceutil/trace.go:171","msg":"trace[643325296] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.012435621s","start":"2026-04-07T05:46:12.401474Z","end":"2026-04-07T05:46:13.41391Z","steps":["trace[643325296] 'process raft request' (duration: 1.011318784s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:13.413955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:12.401448Z","time spent":"1.012491954s","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:46:13.413923Z","caller":"traceutil/trace.go:171","msg":"trace[303359259] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"609.041255ms","start":"2026-04-07T05:46:12.80485Z","end":"2026-04-07T05:46:13.413891Z","steps":["trace[303359259] 'process raft request' (duration: 608.023642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:13.414054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541074564s","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-04-07T05:46:13.414088Z","caller":"traceutil/trace.go:171","msg":"trace[1917653472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"1.541165848s","start":"2026-04-07T05:46:11.872914Z","end":"2026-04-07T05:46:13.41408Z","steps":["trace[1917653472] 'agreement among raft nodes before linearized reading' (duration: 1.540925958s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:13.41402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:12.199284Z","time spent":"1.214659723s","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:46:13.414136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:11.872894Z","time spent":"1.541223791s","remote":"127.0.0.1:59420","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-04-07T05:46:13.414215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:12.804815Z","time spent":"609.248584ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:46:13.428365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.579858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-07T05:46:13.428429Z","caller":"traceutil/trace.go:171","msg":"trace[328283418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:590; }","duration":"222.717084ms","start":"2026-04-07T05:46:13.205694Z","end":"2026-04-07T05:46:13.428411Z","steps":["trace[328283418] 'agreement among raft nodes before linearized reading' (duration: 222.581288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:13.428437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.007796ms","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-04-07T05:46:13.428571Z","caller":"traceutil/trace.go:171","msg":"trace[1912465322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"668.197135ms","start":"2026-04-07T05:46:12.760347Z","end":"2026-04-07T05:46:13.428544Z","steps":["trace[1912465322] 'agreement among raft nodes before linearized reading' (duration: 667.907801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:13.428636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:12.760325Z","time spent":"668.301289ms","remote":"127.0.0.1:59420","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-04-07T05:46:13.428599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.479367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T05:46:13.428753Z","caller":"traceutil/trace.go:171","msg":"trace[509459756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"668.672006ms","start":"2026-04-07T05:46:12.760066Z","end":"2026-04-07T05:46:13.428738Z","steps":["trace[509459756] 'agreement among raft nodes before linearized reading' (duration: 668.31369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:13.428781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028996906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:46:13.428811Z","caller":"traceutil/trace.go:171","msg":"trace[1821278236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"1.029054169s","start":"2026-04-07T05:46:12.39975Z","end":"2026-04-07T05:46:13.428804Z","steps":["trace[1821278236] 'agreement among raft nodes before linearized reading' (duration: 1.029010497s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:13.428829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:12.399731Z","time spent":"1.02909262s","remote":"127.0.0.1:59136","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-04-07T05:46:13.428832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.954165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:46:13.428841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:12.760043Z","time spent":"668.749919ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T05:46:13.42897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.507167ms","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-04-07T05:46:13.428987Z","caller":"traceutil/trace.go:171","msg":"trace[1939187498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"869.565648ms","start":"2026-04-07T05:46:12.559417Z","end":"2026-04-07T05:46:13.428983Z","steps":["trace[1939187498] 'agreement among raft nodes before linearized reading' (duration: 869.484285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:13.429007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:12.559399Z","time spent":"869.60498ms","remote":"127.0.0.1:59420","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-04-07T05:46:13.429071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.636313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-07T05:46:13.429102Z","caller":"traceutil/trace.go:171","msg":"trace[907813851] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:590; }","duration":"718.692025ms","start":"2026-04-07T05:46:12.710402Z","end":"2026-04-07T05:46:13.429094Z","steps":["trace[907813851] 'agreement among raft nodes before linearized reading' (duration: 718.581161ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:13.428882Z","caller":"traceutil/trace.go:171","msg":"trace[1156422057] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:590; }","duration":"230.04274ms","start":"2026-04-07T05:46:13.19883Z","end":"2026-04-07T05:46:13.428873Z","steps":["trace[1156422057] 'agreement among raft nodes before linearized reading' (duration: 229.952516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:13.429123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:12.710389Z","time spent":"718.728246ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29592,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-07T05:46:13.429122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.71391ms","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-04-07T05:46:13.429195Z","caller":"traceutil/trace.go:171","msg":"trace[1512420575] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:590; }","duration":"431.818775ms","start":"2026-04-07T05:46:12.99737Z","end":"2026-04-07T05:46:13.429189Z","steps":["trace[1512420575] 'agreement among raft nodes before linearized reading' (duration: 431.738962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:13.42921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:12.997348Z","time spent":"431.857737ms","remote":"127.0.0.1:59192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-07T05:46:13.429078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126778585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T05:46:13.429242Z","caller":"traceutil/trace.go:171","msg":"trace[1293367548] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:590; }","duration":"1.126979585s","start":"2026-04-07T05:46:12.302255Z","end":"2026-04-07T05:46:13.429234Z","steps":["trace[1293367548] 'agreement among raft nodes before linearized reading' (duration: 1.126793267s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:13.429268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:12.302231Z","time spent":"1.127030647s","remote":"127.0.0.1:59182","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.162\" "} {"level":"info","ts":"2026-04-07T05:46:16.177497Z","caller":"traceutil/trace.go:171","msg":"trace[2130084356] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"150.357937ms","start":"2026-04-07T05:46:16.027089Z","end":"2026-04-07T05:46:16.177446Z","steps":["trace[2130084356] 'process raft request' (duration: 145.844119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:17.905532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743848004400470,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T05:46:18.012973Z","caller":"traceutil/trace.go:171","msg":"trace[1124325443] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"767.993941ms","start":"2026-04-07T05:46:17.244949Z","end":"2026-04-07T05:46:18.012943Z","steps":["trace[1124325443] 'process raft request' (duration: 756.34329ms)","trace[1124325443] 'compare' (duration: 10.940173ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:46:18.013132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:17.244925Z","time spent":"768.123657ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:46:18.055034Z","caller":"traceutil/trace.go:171","msg":"trace[866391083] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"650.395762ms","start":"2026-04-07T05:46:17.404611Z","end":"2026-04-07T05:46:18.055006Z","steps":["trace[866391083] 'read index received' (duration: 596.478539ms)","trace[866391083] 'applied index is now lower than readState.Index' (duration: 53.915843ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:46:18.055249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.6192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:46:18.0554Z","caller":"traceutil/trace.go:171","msg":"trace[391620675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"650.84218ms","start":"2026-04-07T05:46:17.404535Z","end":"2026-04-07T05:46:18.055377Z","steps":["trace[391620675] 'agreement among raft nodes before linearized reading' (duration: 650.58964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:18.05548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:17.404492Z","time spent":"650.977035ms","remote":"127.0.0.1:59152","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-04-07T05:46:18.055967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.948866ms","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-04-07T05:46:18.056005Z","caller":"traceutil/trace.go:171","msg":"trace[856403283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"571.0827ms","start":"2026-04-07T05:46:17.484911Z","end":"2026-04-07T05:46:18.055994Z","steps":["trace[856403283] 'agreement among raft nodes before linearized reading' (duration: 571.010228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:18.056026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:17.484886Z","time spent":"571.134904ms","remote":"127.0.0.1:59420","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-04-07T05:46:18.056138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.304786ms","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-04-07T05:46:18.056155Z","caller":"traceutil/trace.go:171","msg":"trace[299617737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"566.357258ms","start":"2026-04-07T05:46:17.489793Z","end":"2026-04-07T05:46:18.05615Z","steps":["trace[299617737] 'agreement among raft nodes before linearized reading' (duration: 566.324376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:18.056153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.455904ms","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-04-07T05:46:18.056171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:17.489768Z","time spent":"566.399589ms","remote":"127.0.0.1:59420","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":"info","ts":"2026-04-07T05:46:18.056203Z","caller":"traceutil/trace.go:171","msg":"trace[1209597997] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"559.520077ms","start":"2026-04-07T05:46:17.496666Z","end":"2026-04-07T05:46:18.056186Z","steps":["trace[1209597997] 'agreement among raft nodes before linearized reading' (duration: 559.358511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:18.056237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:17.496653Z","time spent":"559.57604ms","remote":"127.0.0.1:59420","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-04-07T05:46:18.056251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.690863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T05:46:18.056297Z","caller":"traceutil/trace.go:171","msg":"trace[800482602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:629; }","duration":"557.759816ms","start":"2026-04-07T05:46:17.498522Z","end":"2026-04-07T05:46:18.056282Z","steps":["trace[800482602] 'agreement among raft nodes before linearized reading' (duration: 557.637371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:18.056336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:17.498513Z","time spent":"557.815048ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T05:46:18.056504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.899952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T05:46:18.056561Z","caller":"traceutil/trace.go:171","msg":"trace[405592067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"558.013627ms","start":"2026-04-07T05:46:17.498532Z","end":"2026-04-07T05:46:18.056546Z","steps":["trace[405592067] 'agreement among raft nodes before linearized reading' (duration: 557.416782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:18.05663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:17.49852Z","time spent":"558.100191ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T05:46:21.902715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743848004400520,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T05:46:22.146608Z","caller":"traceutil/trace.go:171","msg":"trace[647056938] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"744.249868ms","start":"2026-04-07T05:46:21.402339Z","end":"2026-04-07T05:46:22.146588Z","steps":["trace[647056938] 'read index received' (duration: 744.145054ms)","trace[647056938] 'applied index is now lower than readState.Index' (duration: 103.574µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:22.146888Z","caller":"traceutil/trace.go:171","msg":"trace[569163200] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"790.220612ms","start":"2026-04-07T05:46:21.356656Z","end":"2026-04-07T05:46:22.146876Z","steps":["trace[569163200] 'process raft request' (duration: 789.814405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:22.146992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:21.356632Z","time spent":"790.288924ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:46:22.146987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.630712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:46:22.147062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.840986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c8msq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-07T05:46:22.14707Z","caller":"traceutil/trace.go:171","msg":"trace[325751154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"744.767477ms","start":"2026-04-07T05:46:21.40228Z","end":"2026-04-07T05:46:22.147048Z","steps":["trace[325751154] 'agreement among raft nodes before linearized reading' (duration: 744.613701ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:22.147114Z","caller":"traceutil/trace.go:171","msg":"trace[2100868997] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c8msq; range_end:; response_count:1; response_revision:657; }","duration":"644.93251ms","start":"2026-04-07T05:46:21.502167Z","end":"2026-04-07T05:46:22.1471Z","steps":["trace[2100868997] 'agreement among raft nodes before linearized reading' (duration: 644.724252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:22.147122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:21.40225Z","time spent":"744.864152ms","remote":"127.0.0.1:59152","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-04-07T05:46:22.147165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:21.50215Z","time spent":"645.007742ms","remote":"127.0.0.1:59320","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-c8msq\" "} {"level":"info","ts":"2026-04-07T05:46:22.295932Z","caller":"traceutil/trace.go:171","msg":"trace[799710463] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"143.465303ms","start":"2026-04-07T05:46:22.152441Z","end":"2026-04-07T05:46:22.295907Z","steps":["trace[799710463] 'process raft request' (duration: 143.215564ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:22.296249Z","caller":"traceutil/trace.go:171","msg":"trace[889597520] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"143.689541ms","start":"2026-04-07T05:46:22.152477Z","end":"2026-04-07T05:46:22.296167Z","steps":["trace[889597520] 'process raft request' (duration: 143.365039ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:22.296459Z","caller":"traceutil/trace.go:171","msg":"trace[1208784946] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"143.461403ms","start":"2026-04-07T05:46:22.152969Z","end":"2026-04-07T05:46:22.296431Z","steps":["trace[1208784946] 'process raft request' (duration: 143.128021ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:22.296523Z","caller":"traceutil/trace.go:171","msg":"trace[1493295943] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"142.908392ms","start":"2026-04-07T05:46:22.153607Z","end":"2026-04-07T05:46:22.296515Z","steps":["trace[1493295943] 'process raft request' (duration: 142.710924ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:22.296467Z","caller":"traceutil/trace.go:171","msg":"trace[323160914] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"137.512554ms","start":"2026-04-07T05:46:22.158931Z","end":"2026-04-07T05:46:22.296443Z","steps":["trace[323160914] 'process raft request' (duration: 137.458472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:22.94668Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743848004400533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:46:23.447847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743848004400533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:46:23.948815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743848004400533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:46:24.446764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000102952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T05:46:24.446843Z","caller":"traceutil/trace.go:171","msg":"trace[367527778] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000212286s","start":"2026-04-07T05:46:22.446611Z","end":"2026-04-07T05:46:24.446823Z","steps":["trace[367527778] 'agreement among raft nodes before linearized reading' (duration: 2.000122123s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:24.446902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:22.446599Z","time spent":"2.00027968s","remote":"127.0.0.1:59152","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-04-07T05:46:24.449198Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743848004400533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:46:24.950056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743848004400533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:46:25.357479Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.911045366s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T05:46:25.904573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.774171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:46:25.904727Z","caller":"traceutil/trace.go:171","msg":"trace[1695789675] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:689; }","duration":"3.458078865s","start":"2026-04-07T05:46:22.446638Z","end":"2026-04-07T05:46:25.904717Z","steps":["trace[1695789675] 'read index received' (duration: 2.911107918s)","trace[1695789675] 'applied index is now lower than readState.Index' (duration: 546.970397ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:25.904788Z","caller":"traceutil/trace.go:171","msg":"trace[1094813578] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.455193838s","start":"2026-04-07T05:46:24.449562Z","end":"2026-04-07T05:46:25.904756Z","steps":["trace[1094813578] 'process raft request' (duration: 1.455089544s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:25.904906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:24.449546Z","time spent":"1.455298612s","remote":"127.0.0.1:59220","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-04-07T05:46:25.904932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.603596628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T05:46:25.904966Z","caller":"traceutil/trace.go:171","msg":"trace[1966653533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:665; }","duration":"1.60366142s","start":"2026-04-07T05:46:24.301294Z","end":"2026-04-07T05:46:25.904956Z","steps":["trace[1966653533] 'agreement among raft nodes before linearized reading' (duration: 1.603537106s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:25.904986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.60366425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T05:46:25.905004Z","caller":"traceutil/trace.go:171","msg":"trace[2021508413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:665; }","duration":"1.603701512s","start":"2026-04-07T05:46:24.301298Z","end":"2026-04-07T05:46:25.904999Z","steps":["trace[2021508413] 'agreement among raft nodes before linearized reading' (duration: 1.603630639s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:25.905003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:24.301276Z","time spent":"1.603719133s","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T05:46:25.905012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451390725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:46:25.905023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:24.301289Z","time spent":"1.603729453s","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T05:46:25.904782Z","caller":"traceutil/trace.go:171","msg":"trace[962926021] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"3.458394288s","start":"2026-04-07T05:46:22.446362Z","end":"2026-04-07T05:46:25.904756Z","steps":["trace[962926021] 'process raft request' (duration: 2.911373299s)","trace[962926021] 'compare' (duration: 546.49972ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:25.905056Z","caller":"traceutil/trace.go:171","msg":"trace[1850867783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"1.451456077s","start":"2026-04-07T05:46:24.453586Z","end":"2026-04-07T05:46:25.905042Z","steps":["trace[1850867783] 'agreement among raft nodes before linearized reading' (duration: 1.451404965s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:25.905094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:24.453581Z","time spent":"1.451507209s","remote":"127.0.0.1:59136","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-04-07T05:46:25.905111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:22.446346Z","time spent":"3.45872795s","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:46:25.904959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.602717245s","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-04-07T05:46:25.905176Z","caller":"traceutil/trace.go:171","msg":"trace[718187954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:665; }","duration":"1.602957454s","start":"2026-04-07T05:46:24.302209Z","end":"2026-04-07T05:46:25.905167Z","steps":["trace[718187954] 'agreement among raft nodes before linearized reading' (duration: 1.602598681s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:25.905209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:24.302201Z","time spent":"1.602999355s","remote":"127.0.0.1:59420","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-04-07T05:46:25.904916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.73274501s","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-04-07T05:46:25.905256Z","caller":"traceutil/trace.go:171","msg":"trace[55109101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:665; }","duration":"1.733110135s","start":"2026-04-07T05:46:24.172132Z","end":"2026-04-07T05:46:25.905243Z","steps":["trace[55109101] 'agreement among raft nodes before linearized reading' (duration: 1.732659748s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:25.905301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:24.172109Z","time spent":"1.733182298s","remote":"127.0.0.1:59420","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-04-07T05:46:25.905503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.604137727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-07T05:46:25.905572Z","caller":"traceutil/trace.go:171","msg":"trace[1852816448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:665; }","duration":"1.604241091s","start":"2026-04-07T05:46:24.301317Z","end":"2026-04-07T05:46:25.905559Z","steps":["trace[1852816448] 'agreement among raft nodes before linearized reading' (duration: 1.603596388s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:25.905678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:24.301306Z","time spent":"1.604361465s","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T05:46:29.878887Z","caller":"traceutil/trace.go:171","msg":"trace[211139803] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"156.527988ms","start":"2026-04-07T05:46:29.722336Z","end":"2026-04-07T05:46:29.878864Z","steps":["trace[211139803] 'process raft request' (duration: 156.377183ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:30.326409Z","caller":"traceutil/trace.go:171","msg":"trace[301202190] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"289.192292ms","start":"2026-04-07T05:46:30.037192Z","end":"2026-04-07T05:46:30.326384Z","steps":["trace[301202190] 'process raft request' (duration: 288.963244ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:30.326606Z","caller":"traceutil/trace.go:171","msg":"trace[734883419] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"289.309336ms","start":"2026-04-07T05:46:30.037281Z","end":"2026-04-07T05:46:30.326591Z","steps":["trace[734883419] 'process raft request' (duration: 289.039717ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:30.326943Z","caller":"traceutil/trace.go:171","msg":"trace[1384305064] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"289.538715ms","start":"2026-04-07T05:46:30.037385Z","end":"2026-04-07T05:46:30.326924Z","steps":["trace[1384305064] 'process raft request' (duration: 289.430811ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:30.326962Z","caller":"traceutil/trace.go:171","msg":"trace[1501050130] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"289.661439ms","start":"2026-04-07T05:46:30.037289Z","end":"2026-04-07T05:46:30.326951Z","steps":["trace[1501050130] 'process raft request' (duration: 289.253923ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:30.84099Z","caller":"traceutil/trace.go:171","msg":"trace[76010515] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"276.432214ms","start":"2026-04-07T05:46:30.564533Z","end":"2026-04-07T05:46:30.840965Z","steps":["trace[76010515] 'process raft request' (duration: 276.281109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:44.464302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.853618267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6rh2f\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-07T05:46:44.464384Z","caller":"traceutil/trace.go:171","msg":"trace[1920216682] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6rh2f; range_end:; response_count:1; response_revision:786; }","duration":"1.853740251s","start":"2026-04-07T05:46:42.610626Z","end":"2026-04-07T05:46:44.464366Z","steps":["trace[1920216682] 'range keys from in-memory index tree' (duration: 1.853454653s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:44.464425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:42.610608Z","time spent":"1.853806583s","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6rh2f\" "} {"level":"warn","ts":"2026-04-07T05:46:44.46456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.81282ms","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-04-07T05:46:44.464643Z","caller":"traceutil/trace.go:171","msg":"trace[502355393] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"312.902253ms","start":"2026-04-07T05:46:44.151717Z","end":"2026-04-07T05:46:44.464619Z","steps":["trace[502355393] 'range keys from in-memory index tree' (duration: 312.591343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:44.464647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666492471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T05:46:44.464683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:44.151649Z","time spent":"313.025046ms","remote":"127.0.0.1:59420","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-04-07T05:46:44.464717Z","caller":"traceutil/trace.go:171","msg":"trace[1624732391] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:786; }","duration":"1.666608724s","start":"2026-04-07T05:46:42.79809Z","end":"2026-04-07T05:46:44.464698Z","steps":["trace[1624732391] 'count revisions from in-memory index tree' (duration: 1.666391398s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:44.464768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:42.798069Z","time spent":"1.666689307s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-04-07T05:47:00.746619Z","caller":"traceutil/trace.go:171","msg":"trace[962642824] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"103.064404ms","start":"2026-04-07T05:47:00.643528Z","end":"2026-04-07T05:47:00.746592Z","steps":["trace[962642824] 'read index received' (duration: 24.326866ms)","trace[962642824] 'applied index is now lower than readState.Index' (duration: 78.736287ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:47:00.746735Z","caller":"traceutil/trace.go:171","msg":"trace[517623307] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"104.218673ms","start":"2026-04-07T05:47:00.642478Z","end":"2026-04-07T05:47:00.746697Z","steps":["trace[517623307] 'process raft request' (duration: 25.452775ms)","trace[517623307] 'compare' (duration: 78.511842ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:47:00.74687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.72067ms","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-04-07T05:47:00.74691Z","caller":"traceutil/trace.go:171","msg":"trace[1459647099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:965; }","duration":"101.799732ms","start":"2026-04-07T05:47:00.645096Z","end":"2026-04-07T05:47:00.746896Z","steps":["trace[1459647099] 'agreement among raft nodes before linearized reading' (duration: 101.651598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:47:00.747131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.797693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T05:47:00.747256Z","caller":"traceutil/trace.go:171","msg":"trace[580482554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:965; }","duration":"101.970927ms","start":"2026-04-07T05:47:00.645255Z","end":"2026-04-07T05:47:00.747226Z","steps":["trace[580482554] 'agreement among raft nodes before linearized reading' (duration: 101.564786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:47:00.747594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.069839ms","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-04-07T05:47:00.747644Z","caller":"traceutil/trace.go:171","msg":"trace[273323593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:965; }","duration":"104.131012ms","start":"2026-04-07T05:47:00.643499Z","end":"2026-04-07T05:47:00.74763Z","steps":["trace[273323593] 'agreement among raft nodes before linearized reading' (duration: 103.254869ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:47:00.920104Z","caller":"traceutil/trace.go:171","msg":"trace[360387195] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"168.795561ms","start":"2026-04-07T05:47:00.751274Z","end":"2026-04-07T05:47:00.92007Z","steps":["trace[360387195] 'process raft request' (duration: 141.181401ms)","trace[360387195] 'compare' (duration: 27.480607ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:47:00.935881Z","caller":"traceutil/trace.go:171","msg":"trace[268254990] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"183.925766ms","start":"2026-04-07T05:47:00.751934Z","end":"2026-04-07T05:47:00.93586Z","steps":["trace[268254990] 'process raft request' (duration: 183.882435ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:47:00.935969Z","caller":"traceutil/trace.go:171","msg":"trace[682567024] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"184.559212ms","start":"2026-04-07T05:47:00.751392Z","end":"2026-04-07T05:47:00.935951Z","steps":["trace[682567024] 'process raft request' (duration: 184.313065ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:47:01.118945Z","caller":"traceutil/trace.go:171","msg":"trace[389036423] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"140.884745ms","start":"2026-04-07T05:47:00.978023Z","end":"2026-04-07T05:47:01.118908Z","steps":["trace[389036423] 'process raft request' (duration: 44.044933ms)","trace[389036423] 'compare' (duration: 96.680978ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:47:42.455259Z","caller":"traceutil/trace.go:171","msg":"trace[612102425] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"118.633964ms","start":"2026-04-07T05:47:42.336594Z","end":"2026-04-07T05:47:42.455228Z","steps":["trace[612102425] 'process raft request' (duration: 66.235113ms)","trace[612102425] 'compare' (duration: 52.178047ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:47:49.354637Z","caller":"traceutil/trace.go:171","msg":"trace[326239251] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"126.786277ms","start":"2026-04-07T05:47:49.227833Z","end":"2026-04-07T05:47:49.354619Z","steps":["trace[326239251] 'process raft request' (duration: 126.683426ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:47:51.402891Z","caller":"traceutil/trace.go:171","msg":"trace[757443602] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"154.101698ms","start":"2026-04-07T05:47:51.248757Z","end":"2026-04-07T05:47:51.402859Z","steps":["trace[757443602] 'process raft request' (duration: 153.923805ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:47:51.403798Z","caller":"traceutil/trace.go:171","msg":"trace[1065770424] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"154.912832ms","start":"2026-04-07T05:47:51.248863Z","end":"2026-04-07T05:47:51.403776Z","steps":["trace[1065770424] 'process raft request' (duration: 154.77354ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:47:51.555577Z","caller":"traceutil/trace.go:171","msg":"trace[2056506604] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"148.704829ms","start":"2026-04-07T05:47:51.406849Z","end":"2026-04-07T05:47:51.555554Z","steps":["trace[2056506604] 'process raft request' (duration: 97.353572ms)","trace[2056506604] 'compare' (duration: 51.248355ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:47:57.072657Z","caller":"traceutil/trace.go:171","msg":"trace[1829391286] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"142.667485ms","start":"2026-04-07T05:47:56.929959Z","end":"2026-04-07T05:47:57.072627Z","steps":["trace[1829391286] 'process raft request' (duration: 142.507162ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:48:29.606426Z","caller":"traceutil/trace.go:171","msg":"trace[978250667] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"113.266698ms","start":"2026-04-07T05:48:29.493134Z","end":"2026-04-07T05:48:29.606401Z","steps":["trace[978250667] 'process raft request' (duration: 113.127016ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:48:29.80261Z","caller":"traceutil/trace.go:171","msg":"trace[1218949012] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1350; }","duration":"126.596014ms","start":"2026-04-07T05:48:29.675984Z","end":"2026-04-07T05:48:29.80258Z","steps":["trace[1218949012] 'read index received' (duration: 126.23956ms)","trace[1218949012] 'applied index is now lower than readState.Index' (duration: 355.094µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:48:29.802709Z","caller":"traceutil/trace.go:171","msg":"trace[2110146989] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"215.688932ms","start":"2026-04-07T05:48:29.586991Z","end":"2026-04-07T05:48:29.80268Z","steps":["trace[2110146989] 'process raft request' (duration: 215.321037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:48:29.802858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.861828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T05:48:29.802906Z","caller":"traceutil/trace.go:171","msg":"trace[1846475241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1295; }","duration":"126.957819ms","start":"2026-04-07T05:48:29.675935Z","end":"2026-04-07T05:48:29.802892Z","steps":["trace[1846475241] 'agreement among raft nodes before linearized reading' (duration: 126.758437ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:48:37.262988Z","caller":"traceutil/trace.go:171","msg":"trace[151898344] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"143.373308ms","start":"2026-04-07T05:48:37.119591Z","end":"2026-04-07T05:48:37.262965Z","steps":["trace[151898344] 'process raft request' (duration: 143.233736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:48:37.386709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.290255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2578"} {"level":"info","ts":"2026-04-07T05:48:37.386797Z","caller":"traceutil/trace.go:171","msg":"trace[1613907538] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1325; }","duration":"117.431328ms","start":"2026-04-07T05:48:37.269347Z","end":"2026-04-07T05:48:37.386778Z","steps":["trace[1613907538] 'agreement among raft nodes before linearized reading' (duration: 69.666109ms)","trace[1613907538] 'range keys from in-memory index tree' (duration: 47.555396ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:48:37.534073Z","caller":"traceutil/trace.go:171","msg":"trace[1881741453] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"116.980772ms","start":"2026-04-07T05:48:37.417061Z","end":"2026-04-07T05:48:37.534042Z","steps":["trace[1881741453] 'read index received' (duration: 116.656307ms)","trace[1881741453] 'applied index is now lower than readState.Index' (duration: 323.265µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:48:37.534207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.133044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:48:37.534234Z","caller":"traceutil/trace.go:171","msg":"trace[619439434] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1326; }","duration":"117.213594ms","start":"2026-04-07T05:48:37.417012Z","end":"2026-04-07T05:48:37.534225Z","steps":["trace[619439434] 'agreement among raft nodes before linearized reading' (duration: 117.146693ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:48:37.5343Z","caller":"traceutil/trace.go:171","msg":"trace[307863191] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"143.241626ms","start":"2026-04-07T05:48:37.391043Z","end":"2026-04-07T05:48:37.534285Z","steps":["trace[307863191] 'process raft request' (duration: 142.780099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:48:38.119134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.315822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:48:38.119234Z","caller":"traceutil/trace.go:171","msg":"trace[2131997166] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1326; }","duration":"318.482114ms","start":"2026-04-07T05:48:37.800728Z","end":"2026-04-07T05:48:38.11921Z","steps":["trace[2131997166] 'count revisions from in-memory index tree' (duration: 318.214621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:48:38.119291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:48:37.800705Z","time spent":"318.572586ms","remote":"127.0.0.1:55298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:48:38.119539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.351701ms","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-04-07T05:48:38.1196Z","caller":"traceutil/trace.go:171","msg":"trace[516246455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1326; }","duration":"455.460772ms","start":"2026-04-07T05:48:37.664123Z","end":"2026-04-07T05:48:38.119584Z","steps":["trace[516246455] 'range keys from in-memory index tree' (duration: 455.191049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:48:38.119637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:48:37.664101Z","time spent":"455.527833ms","remote":"127.0.0.1:59420","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-04-07T05:48:38.119688Z","caller":"traceutil/trace.go:171","msg":"trace[786216758] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"283.104274ms","start":"2026-04-07T05:48:37.836559Z","end":"2026-04-07T05:48:38.119664Z","steps":["trace[786216758] 'read index received' (duration: 239.108055ms)","trace[786216758] 'applied index is now lower than readState.Index' (duration: 43.995139ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:48:38.119823Z","caller":"traceutil/trace.go:171","msg":"trace[1792320012] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"285.830578ms","start":"2026-04-07T05:48:37.833975Z","end":"2026-04-07T05:48:38.119806Z","steps":["trace[1792320012] 'process raft request' (duration: 241.703768ms)","trace[1792320012] 'compare' (duration: 43.709895ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:48:38.119925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.370718ms","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-04-07T05:48:38.119965Z","caller":"traceutil/trace.go:171","msg":"trace[1979880399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1327; }","duration":"276.426019ms","start":"2026-04-07T05:48:37.843526Z","end":"2026-04-07T05:48:38.119952Z","steps":["trace[1979880399] 'agreement among raft nodes before linearized reading' (duration: 276.308008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:48:38.120027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.463088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T05:48:38.120061Z","caller":"traceutil/trace.go:171","msg":"trace[1547327717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1327; }","duration":"283.519558ms","start":"2026-04-07T05:48:37.836531Z","end":"2026-04-07T05:48:38.12005Z","steps":["trace[1547327717] 'agreement among raft nodes before linearized reading' (duration: 283.418397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:48:38.120201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.733872ms","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-04-07T05:48:38.120242Z","caller":"traceutil/trace.go:171","msg":"trace[1417985926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1327; }","duration":"272.802973ms","start":"2026-04-07T05:48:37.847429Z","end":"2026-04-07T05:48:38.120232Z","steps":["trace[1417985926] 'agreement among raft nodes before linearized reading' (duration: 272.653271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:48:38.364006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.511834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:48:38.364132Z","caller":"traceutil/trace.go:171","msg":"trace[1391477306] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"239.515622ms","start":"2026-04-07T05:48:38.124591Z","end":"2026-04-07T05:48:38.364107Z","steps":["trace[1391477306] 'process raft request' (duration: 109.807565ms)","trace[1391477306] 'compare' (duration: 129.364033ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:48:38.763948Z","caller":"traceutil/trace.go:171","msg":"trace[2021530441] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1386; }","duration":"464.118443ms","start":"2026-04-07T05:48:38.299812Z","end":"2026-04-07T05:48:38.763931Z","steps":["trace[2021530441] 'read index received' (duration: 437.648736ms)","trace[2021530441] 'applied index is now lower than readState.Index' (duration: 26.468427ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:48:38.76428Z","caller":"traceutil/trace.go:171","msg":"trace[811217746] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"637.450584ms","start":"2026-04-07T05:48:38.126731Z","end":"2026-04-07T05:48:38.764181Z","steps":["trace[811217746] 'process raft request' (duration: 637.17017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:48:38.764263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.439597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:48:38.764349Z","caller":"traceutil/trace.go:171","msg":"trace[696348511] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"639.368418ms","start":"2026-04-07T05:48:38.124963Z","end":"2026-04-07T05:48:38.764331Z","steps":["trace[696348511] 'process raft request' (duration: 612.481286ms)","trace[696348511] 'compare' (duration: 26.298584ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:48:38.764395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:48:38.126722Z","time spent":"637.596885ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:48:38.764336Z","caller":"traceutil/trace.go:171","msg":"trace[79876330] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1331; }","duration":"464.553118ms","start":"2026-04-07T05:48:38.299767Z","end":"2026-04-07T05:48:38.76432Z","steps":["trace[79876330] 'agreement among raft nodes before linearized reading' (duration: 464.433647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:48:38.76452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:48:38.124949Z","time spent":"639.518349ms","remote":"127.0.0.1:59420","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-04-07T05:48:38.764555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:48:38.299745Z","time spent":"464.765081ms","remote":"127.0.0.1:59274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-04-07T05:48:38.764783Z","caller":"traceutil/trace.go:171","msg":"trace[1517346029] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"639.060624ms","start":"2026-04-07T05:48:38.12511Z","end":"2026-04-07T05:48:38.764171Z","steps":["trace[1517346029] 'process raft request' (duration: 638.74712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:48:38.76562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:48:38.125087Z","time spent":"639.803923ms","remote":"127.0.0.1:59420","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-04-07T05:48:39.350512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.686113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:48:39.350643Z","caller":"traceutil/trace.go:171","msg":"trace[377934120] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"971.651359ms","start":"2026-04-07T05:48:38.378953Z","end":"2026-04-07T05:48:39.350604Z","steps":["trace[377934120] 'process raft request' (duration: 768.789154ms)","trace[377934120] 'compare' (duration: 202.167507ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:48:39.350619Z","caller":"traceutil/trace.go:171","msg":"trace[664275766] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1389; }","duration":"586.59583ms","start":"2026-04-07T05:48:38.764003Z","end":"2026-04-07T05:48:39.350599Z","steps":["trace[664275766] 'read index received' (duration: 383.730015ms)","trace[664275766] 'applied index is now lower than readState.Index' (duration: 202.863905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:48:39.350862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:48:38.378935Z","time spent":"971.801231ms","remote":"127.0.0.1:59304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5898,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:48:39.350925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.693413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:48:39.350992Z","caller":"traceutil/trace.go:171","msg":"trace[1760618678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"950.801414ms","start":"2026-04-07T05:48:38.400174Z","end":"2026-04-07T05:48:39.350976Z","steps":["trace[1760618678] 'agreement among raft nodes before linearized reading' (duration: 950.676312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:48:39.351026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:48:38.400161Z","time spent":"950.859274ms","remote":"127.0.0.1:59136","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-04-07T05:48:39.351215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.907999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:48:39.351284Z","caller":"traceutil/trace.go:171","msg":"trace[884193290] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1332; }","duration":"144.01531ms","start":"2026-04-07T05:48:39.207252Z","end":"2026-04-07T05:48:39.351267Z","steps":["trace[884193290] 'agreement among raft nodes before linearized reading' (duration: 143.904459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:48:39.351578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.51116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:48:39.351642Z","caller":"traceutil/trace.go:171","msg":"trace[786855640] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1332; }","duration":"583.655853ms","start":"2026-04-07T05:48:38.767971Z","end":"2026-04-07T05:48:39.351627Z","steps":["trace[786855640] 'agreement among raft nodes before linearized reading' (duration: 583.504701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:48:39.351676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:48:38.767956Z","time spent":"583.712143ms","remote":"127.0.0.1:59274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-07T05:48:39.821065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.095731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T05:48:39.821158Z","caller":"traceutil/trace.go:171","msg":"trace[675060967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1332; }","duration":"284.231613ms","start":"2026-04-07T05:48:39.536903Z","end":"2026-04-07T05:48:39.821134Z","steps":["trace[675060967] 'range keys from in-memory index tree' (duration: 283.935359ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:48:40.063844Z","caller":"traceutil/trace.go:171","msg":"trace[135171745] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"238.287439ms","start":"2026-04-07T05:48:39.825529Z","end":"2026-04-07T05:48:40.063816Z","steps":["trace[135171745] 'process raft request' (duration: 238.097147ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:48:40.448976Z","caller":"traceutil/trace.go:171","msg":"trace[1603237592] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"324.148901ms","start":"2026-04-07T05:48:40.124795Z","end":"2026-04-07T05:48:40.448944Z","steps":["trace[1603237592] 'process raft request' (duration: 251.161623ms)","trace[1603237592] 'compare' (duration: 72.393141ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:48:40.449374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:48:40.124772Z","time spent":"324.284433ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}