{"level":"warn","ts":"2026-07-06T23:08:34.330289Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T23:08:34.330407Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-06T23:08:34.330529Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T23:08:34.330552Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-07-06T23:08:34.330573Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-06T23:08:34.331392Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-07-06T23:08:34.331522Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-06T23:08:34.513681Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"181.851847ms"} {"level":"info","ts":"2026-07-06T23:08:34.670806Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-07-06T23:08:34.671069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-07-06T23:08:34.671113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-07-06T23:08:34.671139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-06T23:08:34.671154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-07-06T23:08:34.671219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-07-06T23:08:34.680493Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-06T23:08:34.68612Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-06T23:08:34.698525Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-06T23:08:34.706834Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-06T23:08:34.707554Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-06T23:08:34.707688Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-06T23:08:34.707865Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-06T23:08:34.707905Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-06T23:08:34.711558Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-06T23:08:34.711683Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-07-06T23:08:34.711746Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-07-06T23:08:34.711939Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-06T23:08:34.711996Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-06T23:08:34.719453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-07-06T23:08:34.719818Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-07-06T23:08:35.472622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-07-06T23:08:35.472701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-06T23:08:35.472744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-07-06T23:08:35.472765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-07-06T23:08:35.472777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-07-06T23:08:35.472794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-07-06T23:08:35.472814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-07-06T23:08:35.481375Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:08:35.482744Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T23:08:35.482733Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-07-06T23:08:35.482833Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T23:08:35.483201Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-06T23:08:35.483244Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-06T23:08:35.483936Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:08:35.484067Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:08:35.484107Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:08:35.487175Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-07-06T23:08:35.488148Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-06T23:08:37.233766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.536324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-07-06T23:08:37.233885Z","caller":"traceutil/trace.go:171","msg":"trace[469187146] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"289.557348ms","start":"2026-07-06T23:08:36.944314Z","end":"2026-07-06T23:08:37.233871Z","steps":["trace[469187146] 'read index received' (duration: 110.413707ms)","trace[469187146] 'applied index is now lower than readState.Index' (duration: 179.142601ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:37.233957Z","caller":"traceutil/trace.go:171","msg":"trace[1983491913] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"290.544091ms","start":"2026-07-06T23:08:36.943361Z","end":"2026-07-06T23:08:37.233906Z","steps":["trace[1983491913] 'process raft request' (duration: 111.37945ms)","trace[1983491913] 'compare' (duration: 178.393088ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:08:37.234179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.854512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T23:08:37.234461Z","caller":"traceutil/trace.go:171","msg":"trace[1388575531] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:3; }","duration":"290.076421ms","start":"2026-07-06T23:08:36.944272Z","end":"2026-07-06T23:08:37.234349Z","steps":["trace[1388575531] 'agreement among raft nodes before linearized reading' (duration: 289.671223ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.249635Z","caller":"traceutil/trace.go:171","msg":"trace[52391203] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"276.367ms","start":"2026-07-06T23:08:36.973247Z","end":"2026-07-06T23:08:37.249614Z","steps":["trace[52391203] 'process raft request' (duration: 276.222314ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.249688Z","caller":"traceutil/trace.go:171","msg":"trace[1359052993] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"276.36692ms","start":"2026-07-06T23:08:36.973299Z","end":"2026-07-06T23:08:37.249666Z","steps":["trace[1359052993] 'process raft request' (duration: 276.201303ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.249859Z","caller":"traceutil/trace.go:171","msg":"trace[134597868] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"276.678164ms","start":"2026-07-06T23:08:36.973145Z","end":"2026-07-06T23:08:37.249823Z","steps":["trace[134597868] 'process raft request' (duration: 276.261206ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.249985Z","caller":"traceutil/trace.go:171","msg":"trace[247999120] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"276.721915ms","start":"2026-07-06T23:08:36.973216Z","end":"2026-07-06T23:08:37.249938Z","steps":["trace[247999120] 'process raft request' (duration: 276.221773ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.250013Z","caller":"traceutil/trace.go:171","msg":"trace[566355864] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"253.900162ms","start":"2026-07-06T23:08:36.996106Z","end":"2026-07-06T23:08:37.250006Z","steps":["trace[566355864] 'process raft request' (duration: 253.469173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:37.250181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.522907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T23:08:37.250225Z","caller":"traceutil/trace.go:171","msg":"trace[519151845] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"256.597319ms","start":"2026-07-06T23:08:36.993616Z","end":"2026-07-06T23:08:37.250213Z","steps":["trace[519151845] 'agreement among raft nodes before linearized reading' (duration: 256.513845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:37.250211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.401432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-06T23:08:37.250287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.072308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T23:08:37.2503Z","caller":"traceutil/trace.go:171","msg":"trace[841597773] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"226.518997ms","start":"2026-07-06T23:08:37.023757Z","end":"2026-07-06T23:08:37.250276Z","steps":["trace[841597773] 'agreement among raft nodes before linearized reading' (duration: 226.243555ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.250314Z","caller":"traceutil/trace.go:171","msg":"trace[52220423] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"276.719016ms","start":"2026-07-06T23:08:36.973556Z","end":"2026-07-06T23:08:37.250275Z","steps":["trace[52220423] 'process raft request' (duration: 275.978043ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.250323Z","caller":"traceutil/trace.go:171","msg":"trace[305115660] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"199.11548ms","start":"2026-07-06T23:08:37.051194Z","end":"2026-07-06T23:08:37.250309Z","steps":["trace[305115660] 'agreement among raft nodes before linearized reading' (duration: 199.073908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:37.250413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.075566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T23:08:37.250452Z","caller":"traceutil/trace.go:171","msg":"trace[1771905590] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"276.136839ms","start":"2026-07-06T23:08:36.974304Z","end":"2026-07-06T23:08:37.250441Z","steps":["trace[1771905590] 'agreement among raft nodes before linearized reading' (duration: 276.081317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:37.250494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.295321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T23:08:37.250539Z","caller":"traceutil/trace.go:171","msg":"trace[1538599076] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:10; }","duration":"202.358043ms","start":"2026-07-06T23:08:37.048157Z","end":"2026-07-06T23:08:37.250515Z","steps":["trace[1538599076] 'agreement among raft nodes before linearized reading' (duration: 202.305411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:37.250239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.900933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T23:08:37.249796Z","caller":"traceutil/trace.go:171","msg":"trace[316333959] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"276.591089ms","start":"2026-07-06T23:08:36.973149Z","end":"2026-07-06T23:08:37.24974Z","steps":["trace[316333959] 'process raft request' (duration: 276.13978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:37.250703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.092655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T23:08:37.25077Z","caller":"traceutil/trace.go:171","msg":"trace[724316720] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"202.337582ms","start":"2026-07-06T23:08:37.048264Z","end":"2026-07-06T23:08:37.250602Z","steps":["trace[724316720] 'agreement among raft nodes before linearized reading' (duration: 201.893443ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.250811Z","caller":"traceutil/trace.go:171","msg":"trace[2026074656] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"278.19647ms","start":"2026-07-06T23:08:36.972579Z","end":"2026-07-06T23:08:37.250776Z","steps":["trace[2026074656] 'agreement among raft nodes before linearized reading' (duration: 278.051803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:37.25317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.838645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T23:08:37.253227Z","caller":"traceutil/trace.go:171","msg":"trace[923330070] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:0; response_revision:10; }","duration":"282.942699ms","start":"2026-07-06T23:08:36.970271Z","end":"2026-07-06T23:08:37.253214Z","steps":["trace[923330070] 'agreement among raft nodes before linearized reading' (duration: 282.824544ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.442379Z","caller":"traceutil/trace.go:171","msg":"trace[1316332842] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"185.842756ms","start":"2026-07-06T23:08:37.256506Z","end":"2026-07-06T23:08:37.442348Z","steps":["trace[1316332842] 'process raft request' (duration: 185.666168ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.442482Z","caller":"traceutil/trace.go:171","msg":"trace[1835337232] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13; }","duration":"185.544452ms","start":"2026-07-06T23:08:37.256894Z","end":"2026-07-06T23:08:37.442439Z","steps":["trace[1835337232] 'process raft request' (duration: 185.370774ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.443182Z","caller":"traceutil/trace.go:171","msg":"trace[797784160] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"185.374014ms","start":"2026-07-06T23:08:37.257735Z","end":"2026-07-06T23:08:37.443109Z","steps":["trace[797784160] 'process raft request' (duration: 184.702675ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.443287Z","caller":"traceutil/trace.go:171","msg":"trace[1349886449] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"185.058751ms","start":"2026-07-06T23:08:37.258212Z","end":"2026-07-06T23:08:37.443271Z","steps":["trace[1349886449] 'process raft request' (duration: 184.745258ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.442468Z","caller":"traceutil/trace.go:171","msg":"trace[1776275692] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"187.07854ms","start":"2026-07-06T23:08:37.255368Z","end":"2026-07-06T23:08:37.442446Z","steps":["trace[1776275692] 'process raft request' (duration: 95.615262ms)","trace[1776275692] 'compare' (duration: 90.874233ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:37.442654Z","caller":"traceutil/trace.go:171","msg":"trace[1318280774] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"185.137075ms","start":"2026-07-06T23:08:37.257428Z","end":"2026-07-06T23:08:37.442565Z","steps":["trace[1318280774] 'process raft request' (duration: 184.899114ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.443741Z","caller":"traceutil/trace.go:171","msg":"trace[155358088] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"184.746027ms","start":"2026-07-06T23:08:37.258977Z","end":"2026-07-06T23:08:37.443723Z","steps":["trace[155358088] 'process raft request' (duration: 184.602091ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.443929Z","caller":"traceutil/trace.go:171","msg":"trace[688730048] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:14; }","duration":"133.484328ms","start":"2026-07-06T23:08:37.310428Z","end":"2026-07-06T23:08:37.443912Z","steps":["trace[688730048] 'read index received' (duration: 40.567776ms)","trace[688730048] 'applied index is now lower than readState.Index' (duration: 92.891691ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:37.444008Z","caller":"traceutil/trace.go:171","msg":"trace[428569625] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"182.132592ms","start":"2026-07-06T23:08:37.26186Z","end":"2026-07-06T23:08:37.443992Z","steps":["trace[428569625] 'process raft request' (duration: 181.878311ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.444126Z","caller":"traceutil/trace.go:171","msg":"trace[872079428] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"158.839015ms","start":"2026-07-06T23:08:37.285277Z","end":"2026-07-06T23:08:37.444116Z","steps":["trace[872079428] 'process raft request' (duration: 158.567814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:37.444346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.898296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T23:08:37.444405Z","caller":"traceutil/trace.go:171","msg":"trace[1478385395] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"134.001571ms","start":"2026-07-06T23:08:37.310389Z","end":"2026-07-06T23:08:37.44439Z","steps":["trace[1478385395] 'agreement among raft nodes before linearized reading' (duration: 133.840064ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.444597Z","caller":"traceutil/trace.go:171","msg":"trace[1206716774] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"185.341075ms","start":"2026-07-06T23:08:37.25924Z","end":"2026-07-06T23:08:37.444581Z","steps":["trace[1206716774] 'process raft request' (duration: 184.431494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:37.444643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.612315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-07-06T23:08:37.444689Z","caller":"traceutil/trace.go:171","msg":"trace[1812802232] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"128.670987ms","start":"2026-07-06T23:08:37.316007Z","end":"2026-07-06T23:08:37.444678Z","steps":["trace[1812802232] 'agreement among raft nodes before linearized reading' (duration: 128.598844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:37.720468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.466489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-06T23:08:37.720598Z","caller":"traceutil/trace.go:171","msg":"trace[1891976846] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"210.597556ms","start":"2026-07-06T23:08:37.509978Z","end":"2026-07-06T23:08:37.720575Z","steps":["trace[1891976846] 'process raft request' (duration: 81.927918ms)","trace[1891976846] 'compare' (duration: 128.289951ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:37.721795Z","caller":"traceutil/trace.go:171","msg":"trace[1868127179] linearizableReadLoop","detail":"{readStateIndex:40; appliedIndex:31; }","duration":"157.602781ms","start":"2026-07-06T23:08:37.564168Z","end":"2026-07-06T23:08:37.72177Z","steps":["trace[1868127179] 'read index received' (duration: 27.751242ms)","trace[1868127179] 'applied index is now lower than readState.Index' (duration: 129.850189ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:08:37.721955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.77082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T23:08:37.721996Z","caller":"traceutil/trace.go:171","msg":"trace[1876675755] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:36; }","duration":"157.866264ms","start":"2026-07-06T23:08:37.564116Z","end":"2026-07-06T23:08:37.721982Z","steps":["trace[1876675755] 'agreement among raft nodes before linearized reading' (duration: 157.754378ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.722122Z","caller":"traceutil/trace.go:171","msg":"trace[2057318434] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"161.761425ms","start":"2026-07-06T23:08:37.560349Z","end":"2026-07-06T23:08:37.72211Z","steps":["trace[2057318434] 'process raft request' (duration: 161.357826ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.722102Z","caller":"traceutil/trace.go:171","msg":"trace[401055870] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"163.533344ms","start":"2026-07-06T23:08:37.558507Z","end":"2026-07-06T23:08:37.72204Z","steps":["trace[401055870] 'process raft request' (duration: 163.152616ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.722191Z","caller":"traceutil/trace.go:171","msg":"trace[984565331] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"164.497545ms","start":"2026-07-06T23:08:37.55767Z","end":"2026-07-06T23:08:37.722167Z","steps":["trace[984565331] 'process raft request' (duration: 163.874747ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.722311Z","caller":"traceutil/trace.go:171","msg":"trace[483974399] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"164.615ms","start":"2026-07-06T23:08:37.557553Z","end":"2026-07-06T23:08:37.722168Z","steps":["trace[483974399] 'process raft request' (duration: 163.94041ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.722378Z","caller":"traceutil/trace.go:171","msg":"trace[1540958379] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"164.531277ms","start":"2026-07-06T23:08:37.557833Z","end":"2026-07-06T23:08:37.722364Z","steps":["trace[1540958379] 'process raft request' (duration: 163.746532ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.722753Z","caller":"traceutil/trace.go:171","msg":"trace[124793027] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"164.488595ms","start":"2026-07-06T23:08:37.558242Z","end":"2026-07-06T23:08:37.722731Z","steps":["trace[124793027] 'process raft request' (duration: 163.370815ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.722351Z","caller":"traceutil/trace.go:171","msg":"trace[969217777] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"161.853859ms","start":"2026-07-06T23:08:37.560484Z","end":"2026-07-06T23:08:37.722338Z","steps":["trace[969217777] 'process raft request' (duration: 161.251002ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.722816Z","caller":"traceutil/trace.go:171","msg":"trace[1864332495] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"208.755585ms","start":"2026-07-06T23:08:37.513993Z","end":"2026-07-06T23:08:37.722749Z","steps":["trace[1864332495] 'process raft request' (duration: 207.406135ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.985913Z","caller":"traceutil/trace.go:171","msg":"trace[934765110] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"109.501584ms","start":"2026-07-06T23:08:37.876393Z","end":"2026-07-06T23:08:37.985895Z","steps":["trace[934765110] 'process raft request' (duration: 109.478593ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.986052Z","caller":"traceutil/trace.go:171","msg":"trace[832681970] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"116.401108ms","start":"2026-07-06T23:08:37.869608Z","end":"2026-07-06T23:08:37.986009Z","steps":["trace[832681970] 'process raft request' (duration: 116.144187ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.986235Z","caller":"traceutil/trace.go:171","msg":"trace[467167436] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"115.722137ms","start":"2026-07-06T23:08:37.870453Z","end":"2026-07-06T23:08:37.986175Z","steps":["trace[467167436] 'process raft request' (duration: 115.372182ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:37.986325Z","caller":"traceutil/trace.go:171","msg":"trace[619222606] linearizableReadLoop","detail":"{readStateIndex:52; appliedIndex:48; }","duration":"114.696142ms","start":"2026-07-06T23:08:37.871618Z","end":"2026-07-06T23:08:37.986314Z","steps":["trace[619222606] 'read index received' (duration: 2.777212ms)","trace[619222606] 'applied index is now lower than readState.Index' (duration: 111.91785ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:08:37.986549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.88932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T23:08:37.986581Z","caller":"traceutil/trace.go:171","msg":"trace[1545242808] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:48; }","duration":"114.990476ms","start":"2026-07-06T23:08:37.871582Z","end":"2026-07-06T23:08:37.986573Z","steps":["trace[1545242808] 'agreement among raft nodes before linearized reading' (duration: 114.897602ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:39.574702Z","caller":"traceutil/trace.go:171","msg":"trace[73292341] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"100.803571ms","start":"2026-07-06T23:08:39.473865Z","end":"2026-07-06T23:08:39.574668Z","steps":["trace[73292341] 'process raft request' (duration: 21.008247ms)","trace[73292341] 'compare' (duration: 79.428088ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:39.928589Z","caller":"traceutil/trace.go:171","msg":"trace[1897897572] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"117.384003ms","start":"2026-07-06T23:08:39.81115Z","end":"2026-07-06T23:08:39.928534Z","steps":["trace[1897897572] 'process raft request' (duration: 33.168655ms)","trace[1897897572] 'compare' (duration: 83.933926ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:40.094361Z","caller":"traceutil/trace.go:171","msg":"trace[1908190964] transaction","detail":"{read_only:false; response_revision:220; number_of_response:1; }","duration":"157.016638ms","start":"2026-07-06T23:08:39.937312Z","end":"2026-07-06T23:08:40.094329Z","steps":["trace[1908190964] 'process raft request' (duration: 105.574884ms)","trace[1908190964] 'compare' (duration: 51.308008ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:41.263984Z","caller":"traceutil/trace.go:171","msg":"trace[1594857507] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"150.125885ms","start":"2026-07-06T23:08:41.113833Z","end":"2026-07-06T23:08:41.263959Z","steps":["trace[1594857507] 'process raft request' (duration: 149.984029ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:42.124417Z","caller":"traceutil/trace.go:171","msg":"trace[1983006809] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:281; }","duration":"110.748278ms","start":"2026-07-06T23:08:42.013644Z","end":"2026-07-06T23:08:42.124393Z","steps":["trace[1983006809] 'read index received' (duration: 110.635523ms)","trace[1983006809] 'applied index is now lower than readState.Index' (duration: 111.565µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:42.124522Z","caller":"traceutil/trace.go:171","msg":"trace[435308802] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"114.333326ms","start":"2026-07-06T23:08:42.010169Z","end":"2026-07-06T23:08:42.124502Z","steps":["trace[435308802] 'process raft request' (duration: 114.078805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:42.124634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.967678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:08:42.1249Z","caller":"traceutil/trace.go:171","msg":"trace[1600805836] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:276; }","duration":"111.204668ms","start":"2026-07-06T23:08:42.013619Z","end":"2026-07-06T23:08:42.124823Z","steps":["trace[1600805836] 'agreement among raft nodes before linearized reading' (duration: 110.903485ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:42.139926Z","caller":"traceutil/trace.go:171","msg":"trace[1660255449] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"124.134541ms","start":"2026-07-06T23:08:42.015777Z","end":"2026-07-06T23:08:42.139912Z","steps":["trace[1660255449] 'process raft request' (duration: 123.731493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:42.381717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.908658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:08:42.382002Z","caller":"traceutil/trace.go:171","msg":"trace[1389773804] linearizableReadLoop","detail":"{readStateIndex:290; appliedIndex:288; }","duration":"168.077996ms","start":"2026-07-06T23:08:42.213907Z","end":"2026-07-06T23:08:42.381985Z","steps":["trace[1389773804] 'read index received' (duration: 11.784522ms)","trace[1389773804] 'applied index is now lower than readState.Index' (duration: 156.292544ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:42.382067Z","caller":"traceutil/trace.go:171","msg":"trace[1466533300] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"180.78852ms","start":"2026-07-06T23:08:42.201251Z","end":"2026-07-06T23:08:42.382039Z","steps":["trace[1466533300] 'process raft request' (duration: 180.610183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:42.38234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.424841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-07-06T23:08:42.382384Z","caller":"traceutil/trace.go:171","msg":"trace[244939271] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:284; }","duration":"168.498205ms","start":"2026-07-06T23:08:42.213873Z","end":"2026-07-06T23:08:42.382371Z","steps":["trace[244939271] 'agreement among raft nodes before linearized reading' (duration: 168.227253ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:42.382833Z","caller":"traceutil/trace.go:171","msg":"trace[1730268097] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"193.908551ms","start":"2026-07-06T23:08:42.188872Z","end":"2026-07-06T23:08:42.382781Z","steps":["trace[1730268097] 'process raft request' (duration: 36.858954ms)","trace[1730268097] 'compare' (duration: 155.790402ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:42.445803Z","caller":"traceutil/trace.go:171","msg":"trace[2135383422] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"100.138568ms","start":"2026-07-06T23:08:42.345636Z","end":"2026-07-06T23:08:42.445775Z","steps":["trace[2135383422] 'process raft request' (duration: 100.020733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:42.445934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.801862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-06T23:08:42.446153Z","caller":"traceutil/trace.go:171","msg":"trace[1823920546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:285; }","duration":"179.073554ms","start":"2026-07-06T23:08:42.267062Z","end":"2026-07-06T23:08:42.446135Z","steps":["trace[1823920546] 'agreement among raft nodes before linearized reading' (duration: 178.75175ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:43.024531Z","caller":"traceutil/trace.go:171","msg":"trace[1489661376] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"109.659601ms","start":"2026-07-06T23:08:42.914842Z","end":"2026-07-06T23:08:43.024502Z","steps":["trace[1489661376] 'process raft request' (duration: 89.046816ms)","trace[1489661376] 'compare' (duration: 20.460218ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:43.18877Z","caller":"traceutil/trace.go:171","msg":"trace[1237409802] transaction","detail":"{read_only:false; number_of_response:0; response_revision:303; }","duration":"130.05677ms","start":"2026-07-06T23:08:43.058685Z","end":"2026-07-06T23:08:43.188742Z","steps":["trace[1237409802] 'process raft request' (duration: 69.271372ms)","trace[1237409802] 'compare' (duration: 60.724966ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:43.201932Z","caller":"traceutil/trace.go:171","msg":"trace[958606593] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"143.040397ms","start":"2026-07-06T23:08:43.058852Z","end":"2026-07-06T23:08:43.201892Z","steps":["trace[958606593] 'process raft request' (duration: 142.545444ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:43.201959Z","caller":"traceutil/trace.go:171","msg":"trace[1304684903] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"125.565421ms","start":"2026-07-06T23:08:43.076363Z","end":"2026-07-06T23:08:43.201929Z","steps":["trace[1304684903] 'process raft request' (duration: 125.395154ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:43.201987Z","caller":"traceutil/trace.go:171","msg":"trace[719982913] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"138.201933ms","start":"2026-07-06T23:08:43.063767Z","end":"2026-07-06T23:08:43.201969Z","steps":["trace[719982913] 'process raft request' (duration: 137.947972ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:43.202125Z","caller":"traceutil/trace.go:171","msg":"trace[1084911428] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"125.08324ms","start":"2026-07-06T23:08:43.077031Z","end":"2026-07-06T23:08:43.202114Z","steps":["trace[1084911428] 'process raft request' (duration: 124.802328ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:43.202172Z","caller":"traceutil/trace.go:171","msg":"trace[1837496632] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"124.813828ms","start":"2026-07-06T23:08:43.077341Z","end":"2026-07-06T23:08:43.202155Z","steps":["trace[1837496632] 'process raft request' (duration: 124.512485ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:43.202237Z","caller":"traceutil/trace.go:171","msg":"trace[1821287646] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"125.260748ms","start":"2026-07-06T23:08:43.076968Z","end":"2026-07-06T23:08:43.202229Z","steps":["trace[1821287646] 'process raft request' (duration: 124.8309ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:43.201978Z","caller":"traceutil/trace.go:171","msg":"trace[719705460] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"139.621625ms","start":"2026-07-06T23:08:43.062335Z","end":"2026-07-06T23:08:43.201956Z","steps":["trace[719705460] 'process raft request' (duration: 139.325872ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:43.202289Z","caller":"traceutil/trace.go:171","msg":"trace[167103611] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"124.694563ms","start":"2026-07-06T23:08:43.077583Z","end":"2026-07-06T23:08:43.202278Z","steps":["trace[167103611] 'process raft request' (duration: 124.300375ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:43.391371Z","caller":"traceutil/trace.go:171","msg":"trace[1440183359] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"180.1212ms","start":"2026-07-06T23:08:43.211218Z","end":"2026-07-06T23:08:43.391339Z","steps":["trace[1440183359] 'process raft request' (duration: 112.672058ms)","trace[1440183359] 'compare' (duration: 67.193171ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:43.391397Z","caller":"traceutil/trace.go:171","msg":"trace[123262507] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"174.582985ms","start":"2026-07-06T23:08:43.216789Z","end":"2026-07-06T23:08:43.391372Z","steps":["trace[123262507] 'process raft request' (duration: 174.421547ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:43.391473Z","caller":"traceutil/trace.go:171","msg":"trace[569629216] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"129.326937ms","start":"2026-07-06T23:08:43.262131Z","end":"2026-07-06T23:08:43.391458Z","steps":["trace[569629216] 'process raft request' (duration: 129.143039ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:43.391497Z","caller":"traceutil/trace.go:171","msg":"trace[1177950487] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:320; }","duration":"178.685996ms","start":"2026-07-06T23:08:43.21276Z","end":"2026-07-06T23:08:43.391446Z","steps":["trace[1177950487] 'read index received' (duration: 47.81109ms)","trace[1177950487] 'applied index is now lower than readState.Index' (duration: 130.865225ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:08:43.39188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.076294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-06T23:08:43.391934Z","caller":"traceutil/trace.go:171","msg":"trace[2056335684] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:312; }","duration":"179.182738ms","start":"2026-07-06T23:08:43.212736Z","end":"2026-07-06T23:08:43.391918Z","steps":["trace[2056335684] 'agreement among raft nodes before linearized reading' (duration: 178.861814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:45.864675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478480409,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T23:08:46.967461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.827763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:08:46.967586Z","caller":"traceutil/trace.go:171","msg":"trace[1646037381] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:355; }","duration":"1.602960252s","start":"2026-07-06T23:08:45.3646Z","end":"2026-07-06T23:08:46.96756Z","steps":["trace[1646037381] 'read index received' (duration: 666.885617ms)","trace[1646037381] 'applied index is now lower than readState.Index' (duration: 936.072814ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:46.967682Z","caller":"traceutil/trace.go:171","msg":"trace[1532167635] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"1.619778529s","start":"2026-07-06T23:08:45.34788Z","end":"2026-07-06T23:08:46.967659Z","steps":["trace[1532167635] 'process raft request' (duration: 683.650372ms)","trace[1532167635] 'compare' (duration: 935.428946ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:08:46.96779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580332225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4317"} {"level":"warn","ts":"2026-07-06T23:08:46.967796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.347853Z","time spent":"1.619879194s","remote":"127.0.0.1:33140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:08:46.9678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.603178401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-07-06T23:08:46.967863Z","caller":"traceutil/trace.go:171","msg":"trace[254027702] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:345; }","duration":"1.58042899s","start":"2026-07-06T23:08:45.387403Z","end":"2026-07-06T23:08:46.967832Z","steps":["trace[254027702] 'agreement among raft nodes before linearized reading' (duration: 1.580290043s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:46.967892Z","caller":"traceutil/trace.go:171","msg":"trace[1387772622] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:345; }","duration":"1.603308177s","start":"2026-07-06T23:08:45.364559Z","end":"2026-07-06T23:08:46.967867Z","steps":["trace[1387772622] 'agreement among raft nodes before linearized reading' (duration: 1.603098188s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:46.96792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.387379Z","time spent":"1.580530564s","remote":"127.0.0.1:33086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4340,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-07-06T23:08:46.967937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.364541Z","time spent":"1.603386711s","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-07-06T23:08:46.967931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30349967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-07-06T23:08:46.968003Z","caller":"traceutil/trace.go:171","msg":"trace[1928225365] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:345; }","duration":"1.303615374s","start":"2026-07-06T23:08:45.664369Z","end":"2026-07-06T23:08:46.967984Z","steps":["trace[1928225365] 'agreement among raft nodes before linearized reading' (duration: 1.303481568s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:46.968008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.503432247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-07-06T23:08:46.968045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.664353Z","time spent":"1.303682608s","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-07-06T23:08:46.968067Z","caller":"traceutil/trace.go:171","msg":"trace[1164579482] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:345; }","duration":"1.503533312s","start":"2026-07-06T23:08:45.464519Z","end":"2026-07-06T23:08:46.968052Z","steps":["trace[1164579482] 'agreement among raft nodes before linearized reading' (duration: 1.503429468s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:46.968108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.464498Z","time spent":"1.503600935s","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-07-06T23:08:46.968175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.554218055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-07-06T23:08:46.968223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398049512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-07-06T23:08:46.968234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152760298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-07-06T23:08:46.968249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355082373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-07-06T23:08:46.968274Z","caller":"traceutil/trace.go:171","msg":"trace[46653975] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:345; }","duration":"1.398135647s","start":"2026-07-06T23:08:45.570128Z","end":"2026-07-06T23:08:46.968263Z","steps":["trace[46653975] 'agreement among raft nodes before linearized reading' (duration: 1.398058373s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:46.968286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454256961s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-07-06T23:08:46.968294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153115135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-06T23:08:46.968298Z","caller":"traceutil/trace.go:171","msg":"trace[486188626] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:345; }","duration":"1.355159926s","start":"2026-07-06T23:08:45.613125Z","end":"2026-07-06T23:08:46.968284Z","steps":["trace[486188626] 'agreement among raft nodes before linearized reading' (duration: 1.355074063s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:46.968281Z","caller":"traceutil/trace.go:171","msg":"trace[105197952] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:345; }","duration":"1.152854352s","start":"2026-07-06T23:08:45.815413Z","end":"2026-07-06T23:08:46.968267Z","steps":["trace[105197952] 'agreement among raft nodes before linearized reading' (duration: 1.152765158s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:46.968318Z","caller":"traceutil/trace.go:171","msg":"trace[1780221122] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:345; }","duration":"1.454330595s","start":"2026-07-06T23:08:45.513978Z","end":"2026-07-06T23:08:46.968309Z","steps":["trace[1780221122] 'agreement among raft nodes before linearized reading' (duration: 1.454281762s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:46.968339Z","caller":"traceutil/trace.go:171","msg":"trace[1754360707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:345; }","duration":"1.153182817s","start":"2026-07-06T23:08:45.815146Z","end":"2026-07-06T23:08:46.968329Z","steps":["trace[1754360707] 'agreement among raft nodes before linearized reading' (duration: 1.153115884s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:46.968343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580226501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5316"} {"level":"warn","ts":"2026-07-06T23:08:46.968347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.513954Z","time spent":"1.454387457s","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-07-06T23:08:46.968317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.713658ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T23:08:46.968372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.815131Z","time spent":"1.15323433s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-06T23:08:46.968386Z","caller":"traceutil/trace.go:171","msg":"trace[1647907745] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:345; }","duration":"388.783381ms","start":"2026-07-06T23:08:46.579595Z","end":"2026-07-06T23:08:46.968379Z","steps":["trace[1647907745] 'agreement among raft nodes before linearized reading' (duration: 388.703197ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:46.968384Z","caller":"traceutil/trace.go:171","msg":"trace[811929458] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:345; }","duration":"1.580271473s","start":"2026-07-06T23:08:45.388099Z","end":"2026-07-06T23:08:46.96837Z","steps":["trace[811929458] 'agreement among raft nodes before linearized reading' (duration: 1.580218741s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:46.968394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.90396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-06T23:08:46.968416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.388092Z","time spent":"1.580317935s","remote":"127.0.0.1:33086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5339,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-07-06T23:08:46.968427Z","caller":"traceutil/trace.go:171","msg":"trace[1597130655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:345; }","duration":"407.967993ms","start":"2026-07-06T23:08:46.560447Z","end":"2026-07-06T23:08:46.968415Z","steps":["trace[1597130655] 'agreement among raft nodes before linearized reading' (duration: 407.917411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:46.968488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204954469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-07-06T23:08:46.968489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.560428Z","time spent":"408.045937ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-06T23:08:46.968299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580604798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4195"} {"level":"warn","ts":"2026-07-06T23:08:46.968526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254955922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-06T23:08:46.968531Z","caller":"traceutil/trace.go:171","msg":"trace[1681083878] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:345; }","duration":"1.205022502s","start":"2026-07-06T23:08:45.763499Z","end":"2026-07-06T23:08:46.968521Z","steps":["trace[1681083878] 'agreement among raft nodes before linearized reading' (duration: 1.204942468s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:46.968552Z","caller":"traceutil/trace.go:171","msg":"trace[1252560475] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:345; }","duration":"1.254997323s","start":"2026-07-06T23:08:45.713546Z","end":"2026-07-06T23:08:46.968543Z","steps":["trace[1252560475] 'agreement among raft nodes before linearized reading' (duration: 1.254953031s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:46.968554Z","caller":"traceutil/trace.go:171","msg":"trace[1228944106] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:345; }","duration":"1.580864259s","start":"2026-07-06T23:08:45.387678Z","end":"2026-07-06T23:08:46.968542Z","steps":["trace[1228944106] 'agreement among raft nodes before linearized reading' (duration: 1.580596017s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:46.968559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.763486Z","time spent":"1.205066653s","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-07-06T23:08:46.968579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.713533Z","time spent":"1.255040755s","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-07-06T23:08:46.968589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.387673Z","time spent":"1.58090601s","remote":"127.0.0.1:33082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4218,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-06T23:08:46.968344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.815391Z","time spent":"1.152947706s","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-07-06T23:08:46.968239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408867614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-06T23:08:46.968676Z","caller":"traceutil/trace.go:171","msg":"trace[1110363903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:345; }","duration":"1.409338724s","start":"2026-07-06T23:08:45.559329Z","end":"2026-07-06T23:08:46.968667Z","steps":["trace[1110363903] 'agreement among raft nodes before linearized reading' (duration: 1.408872814s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:46.968702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.559307Z","time spent":"1.409387757s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-06T23:08:46.968302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580017402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4655"} {"level":"info","ts":"2026-07-06T23:08:46.968741Z","caller":"traceutil/trace.go:171","msg":"trace[1299336273] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:345; }","duration":"1.580467161s","start":"2026-07-06T23:08:45.388264Z","end":"2026-07-06T23:08:46.968731Z","steps":["trace[1299336273] 'agreement among raft nodes before linearized reading' (duration: 1.57999455s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:46.968772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.388255Z","time spent":"1.580508904s","remote":"127.0.0.1:33086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4678,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-07-06T23:08:46.968311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.570102Z","time spent":"1.398202309s","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-07-06T23:08:46.968229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.113561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T23:08:46.968931Z","caller":"traceutil/trace.go:171","msg":"trace[86574861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:345; }","duration":"315.815492ms","start":"2026-07-06T23:08:46.653104Z","end":"2026-07-06T23:08:46.96892Z","steps":["trace[86574861] 'agreement among raft nodes before linearized reading' (duration: 315.080949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:46.968973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.653046Z","time spent":"315.914646ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-06T23:08:46.96823Z","caller":"traceutil/trace.go:171","msg":"trace[2124056553] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:345; }","duration":"1.554302719s","start":"2026-07-06T23:08:45.413911Z","end":"2026-07-06T23:08:46.968213Z","steps":["trace[2124056553] 'agreement among raft nodes before linearized reading' (duration: 1.554219846s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:46.969027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.41389Z","time spent":"1.555123755s","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-07-06T23:08:46.968346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:45.613107Z","time spent":"1.3552312s","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-07-06T23:08:47.476338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478480415,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T23:08:47.976572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478480415,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T23:08:47.985204Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.009213377s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-06T23:08:49.573063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.711466ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:08:49.573197Z","caller":"traceutil/trace.go:171","msg":"trace[1987980845] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:345; }","duration":"393.859382ms","start":"2026-07-06T23:08:49.179314Z","end":"2026-07-06T23:08:49.573173Z","steps":["trace[1987980845] 'range keys from in-memory index tree' (duration: 393.692125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.573249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587733092s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:08:49.573325Z","caller":"traceutil/trace.go:171","msg":"trace[411127602] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"2.597401579s","start":"2026-07-06T23:08:46.975911Z","end":"2026-07-06T23:08:49.573313Z","steps":["trace[411127602] 'process raft request' (duration: 1.009552453s)","trace[411127602] 'compare' (duration: 1.587635048s)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:08:49.573366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.975889Z","time spent":"2.597462142s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:08:49.579861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000116614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-06T23:08:49.580171Z","caller":"traceutil/trace.go:171","msg":"trace[1367091400] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000470868s","start":"2026-07-06T23:08:47.57968Z","end":"2026-07-06T23:08:49.580151Z","steps":["trace[1367091400] 'agreement among raft nodes before linearized reading' (duration: 2.000149925s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.58034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:47.579626Z","time spent":"2.000698827s","remote":"127.0.0.1:32872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/06 23:08:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-06T23:08:49.774246Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.788743607s","expected-duration":"1s"} {"level":"info","ts":"2026-07-06T23:08:49.783898Z","caller":"traceutil/trace.go:171","msg":"trace[912266854] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"2.805578453s","start":"2026-07-06T23:08:46.978299Z","end":"2026-07-06T23:08:49.783877Z","steps":["trace[912266854] 'process raft request' (duration: 2.796103611s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.784031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.978291Z","time spent":"2.805668007s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:08:49.784199Z","caller":"traceutil/trace.go:171","msg":"trace[2091655064] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"2.805858514s","start":"2026-07-06T23:08:46.978298Z","end":"2026-07-06T23:08:49.784156Z","steps":["trace[2091655064] 'process raft request' (duration: 2.805465759s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.784426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.978282Z","time spent":"2.806055652s","remote":"127.0.0.1:33440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:08:49.784593Z","caller":"traceutil/trace.go:171","msg":"trace[939277166] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"2.806016279s","start":"2026-07-06T23:08:46.978548Z","end":"2026-07-06T23:08:49.784564Z","steps":["trace[939277166] 'process raft request' (duration: 2.805855073s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:49.784657Z","caller":"traceutil/trace.go:171","msg":"trace[1589832774] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"2.806091992s","start":"2026-07-06T23:08:46.978553Z","end":"2026-07-06T23:08:49.784645Z","steps":["trace[1589832774] 'process raft request' (duration: 2.805875694s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:49.784664Z","caller":"traceutil/trace.go:171","msg":"trace[590055557] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"2.805115849s","start":"2026-07-06T23:08:46.979523Z","end":"2026-07-06T23:08:49.784639Z","steps":["trace[590055557] 'process raft request' (duration: 2.804963383s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.784702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.978532Z","time spent":"2.806147284s","remote":"127.0.0.1:33262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:08:49.784723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.97853Z","time spent":"2.806121023s","remote":"127.0.0.1:33082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:08:49.785579Z","caller":"traceutil/trace.go:171","msg":"trace[1366282036] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"2.807104973s","start":"2026-07-06T23:08:46.9784Z","end":"2026-07-06T23:08:49.785505Z","steps":["trace[1366282036] 'process raft request' (duration: 2.805911086s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.786237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.978392Z","time spent":"2.807666575s","remote":"127.0.0.1:33006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:08:49.786759Z","caller":"traceutil/trace.go:171","msg":"trace[1826075151] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"2.808048489s","start":"2026-07-06T23:08:46.978678Z","end":"2026-07-06T23:08:49.786726Z","steps":["trace[1826075151] 'process raft request' (duration: 2.80577835s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.786897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.978668Z","time spent":"2.808179354s","remote":"127.0.0.1:33086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:08:49.787083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.811006038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4367"} {"level":"info","ts":"2026-07-06T23:08:49.787165Z","caller":"traceutil/trace.go:171","msg":"trace[1531484317] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:360; }","duration":"2.811090222s","start":"2026-07-06T23:08:46.976045Z","end":"2026-07-06T23:08:49.787135Z","steps":["trace[1531484317] 'agreement among raft nodes before linearized reading' (duration: 2.809335224s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.787216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.976037Z","time spent":"2.811163825s","remote":"127.0.0.1:33082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4390,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-06T23:08:49.787256Z","caller":"traceutil/trace.go:171","msg":"trace[2144892396] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:356; }","duration":"2.811160314s","start":"2026-07-06T23:08:46.976063Z","end":"2026-07-06T23:08:49.787223Z","steps":["trace[2144892396] 'read index received' (duration: 1.009411466s)","trace[2144892396] 'applied index is now lower than readState.Index' (duration: 1.799144956s)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:08:49.784801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.979512Z","time spent":"2.805209003s","remote":"127.0.0.1:33262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:08:49.787988Z","caller":"traceutil/trace.go:171","msg":"trace[568333670] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"2.809477397s","start":"2026-07-06T23:08:46.978497Z","end":"2026-07-06T23:08:49.787974Z","steps":["trace[568333670] 'process raft request' (duration: 2.805882465s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:49.788008Z","caller":"traceutil/trace.go:171","msg":"trace[2037439330] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"2.80819023s","start":"2026-07-06T23:08:46.97957Z","end":"2026-07-06T23:08:49.78776Z","steps":["trace[2037439330] 'process raft request' (duration: 2.804939212s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.788073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.97849Z","time spent":"2.809540629s","remote":"127.0.0.1:33006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:08:49.7881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.979557Z","time spent":"2.808487792s","remote":"127.0.0.1:33086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:08:49.788307Z","caller":"traceutil/trace.go:171","msg":"trace[666479331] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"2.809737767s","start":"2026-07-06T23:08:46.978468Z","end":"2026-07-06T23:08:49.788206Z","steps":["trace[666479331] 'process raft request' (duration: 2.805875414s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.788356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.978464Z","time spent":"2.809867412s","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:08:49.788633Z","caller":"traceutil/trace.go:171","msg":"trace[136847145] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"2.808003277s","start":"2026-07-06T23:08:46.980614Z","end":"2026-07-06T23:08:49.788617Z","steps":["trace[136847145] 'process raft request' (duration: 2.803963867s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:49.788922Z","caller":"traceutil/trace.go:171","msg":"trace[2126072342] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"2.809426199s","start":"2026-07-06T23:08:46.979481Z","end":"2026-07-06T23:08:49.788907Z","steps":["trace[2126072342] 'process raft request' (duration: 2.805047646s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:49.788923Z","caller":"traceutil/trace.go:171","msg":"trace[1889803956] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"2.809183829s","start":"2026-07-06T23:08:46.979716Z","end":"2026-07-06T23:08:49.7889Z","steps":["trace[1889803956] 'process raft request' (duration: 2.804836837s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.788997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.979474Z","time spent":"2.809483672s","remote":"127.0.0.1:33078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T23:08:49.789041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.979691Z","time spent":"2.809302813s","remote":"127.0.0.1:33262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:08:49.789065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.980606Z","time spent":"2.808325251s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:08:49.788835Z","caller":"traceutil/trace.go:171","msg":"trace[2054188260] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"2.8080942s","start":"2026-07-06T23:08:46.980726Z","end":"2026-07-06T23:08:49.78882Z","steps":["trace[2054188260] 'process raft request' (duration: 2.803872093s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.78921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.98072Z","time spent":"2.808443804s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:08:49.801032Z","caller":"traceutil/trace.go:171","msg":"trace[965929787] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"1.754760002s","start":"2026-07-06T23:08:48.046198Z","end":"2026-07-06T23:08:49.800958Z","steps":["trace[965929787] 'process raft request' (duration: 1.753658829s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.801159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:48.046176Z","time spent":"1.754910468s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:08:49.801523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.82306729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-07-06T23:08:49.801553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.823040079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-07-06T23:08:49.801568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.82329005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4367"} {"level":"info","ts":"2026-07-06T23:08:49.801369Z","caller":"traceutil/trace.go:171","msg":"trace[1859610954] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"217.992343ms","start":"2026-07-06T23:08:49.583368Z","end":"2026-07-06T23:08:49.801361Z","steps":["trace[1859610954] 'process raft request' (duration: 217.456482ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:49.801607Z","caller":"traceutil/trace.go:171","msg":"trace[1445206494] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:362; }","duration":"2.823106712s","start":"2026-07-06T23:08:46.978486Z","end":"2026-07-06T23:08:49.801593Z","steps":["trace[1445206494] 'agreement among raft nodes before linearized reading' (duration: 2.822977967s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:49.801617Z","caller":"traceutil/trace.go:171","msg":"trace[251622182] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:362; }","duration":"2.823367863s","start":"2026-07-06T23:08:46.978235Z","end":"2026-07-06T23:08:49.801603Z","steps":["trace[251622182] 'agreement among raft nodes before linearized reading' (duration: 2.823229918s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.801636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.978483Z","time spent":"2.823146743s","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-07-06T23:08:49.801652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.978228Z","time spent":"2.823415905s","remote":"127.0.0.1:33082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4390,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-06T23:08:49.801577Z","caller":"traceutil/trace.go:171","msg":"trace[594785495] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:362; }","duration":"2.823125332s","start":"2026-07-06T23:08:46.978439Z","end":"2026-07-06T23:08:49.801564Z","steps":["trace[594785495] 'agreement among raft nodes before linearized reading' (duration: 2.823018928s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:49.801701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:08:46.978425Z","time spent":"2.823261049s","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-07-06T23:08:53.969855Z","caller":"traceutil/trace.go:171","msg":"trace[1605450214] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"154.643666ms","start":"2026-07-06T23:08:53.815178Z","end":"2026-07-06T23:08:53.969822Z","steps":["trace[1605450214] 'process raft request' (duration: 56.71069ms)","trace[1605450214] 'compare' (duration: 97.680226ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:53.970017Z","caller":"traceutil/trace.go:171","msg":"trace[628750452] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"130.500201ms","start":"2026-07-06T23:08:53.839485Z","end":"2026-07-06T23:08:53.969985Z","steps":["trace[628750452] 'process raft request' (duration: 130.284161ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:55.74758Z","caller":"traceutil/trace.go:171","msg":"trace[1724460708] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"119.73563ms","start":"2026-07-06T23:08:55.62782Z","end":"2026-07-06T23:08:55.747555Z","steps":["trace[1724460708] 'process raft request' (duration: 119.58969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:55.948787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.95266ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-06T23:08:55.948919Z","caller":"traceutil/trace.go:171","msg":"trace[2001473418] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"108.755485ms","start":"2026-07-06T23:08:55.840138Z","end":"2026-07-06T23:08:55.948894Z","steps":["trace[2001473418] 'read index received' (duration: 170.201µs)","trace[2001473418] 'applied index is now lower than readState.Index' (duration: 108.583154ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:08:55.949048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.904655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T23:08:55.949104Z","caller":"traceutil/trace.go:171","msg":"trace[611064361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:411; }","duration":"109.007575ms","start":"2026-07-06T23:08:55.840077Z","end":"2026-07-06T23:08:55.949084Z","steps":["trace[611064361] 'agreement among raft nodes before linearized reading' (duration: 108.890575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:08:57.850612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.822652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7285"} {"level":"info","ts":"2026-07-06T23:08:57.850681Z","caller":"traceutil/trace.go:171","msg":"trace[1553263228] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:415; }","duration":"220.927203ms","start":"2026-07-06T23:08:57.629737Z","end":"2026-07-06T23:08:57.850664Z","steps":["trace[1553263228] 'range keys from in-memory index tree' (duration: 220.739752ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:57.979296Z","caller":"traceutil/trace.go:171","msg":"trace[1175273215] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"124.341668ms","start":"2026-07-06T23:08:57.85493Z","end":"2026-07-06T23:08:57.979271Z","steps":["trace[1175273215] 'process raft request' (duration: 124.130038ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:08:58.139596Z","caller":"traceutil/trace.go:171","msg":"trace[454491924] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:430; }","duration":"143.305046ms","start":"2026-07-06T23:08:57.996266Z","end":"2026-07-06T23:08:58.139571Z","steps":["trace[454491924] 'read index received' (duration: 78.960509ms)","trace[454491924] 'applied index is now lower than readState.Index' (duration: 64.343487ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:08:58.139775Z","caller":"traceutil/trace.go:171","msg":"trace[1255313547] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"280.662498ms","start":"2026-07-06T23:08:57.859069Z","end":"2026-07-06T23:08:58.139731Z","steps":["trace[1255313547] 'process raft request' (duration: 216.22439ms)","trace[1255313547] 'compare' (duration: 64.109137ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:08:58.140121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.815258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T23:08:58.140285Z","caller":"traceutil/trace.go:171","msg":"trace[1672312059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:417; }","duration":"144.014628ms","start":"2026-07-06T23:08:57.996237Z","end":"2026-07-06T23:08:58.140252Z","steps":["trace[1672312059] 'agreement among raft nodes before linearized reading' (duration: 143.726107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:04.373519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.070039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-06T23:09:04.373618Z","caller":"traceutil/trace.go:171","msg":"trace[1490979387] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:433; }","duration":"100.211081ms","start":"2026-07-06T23:09:04.273381Z","end":"2026-07-06T23:09:04.373592Z","steps":["trace[1490979387] 'agreement among raft nodes before linearized reading' (duration: 100.05103ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:04.373803Z","caller":"traceutil/trace.go:171","msg":"trace[480326587] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"120.166073ms","start":"2026-07-06T23:09:04.253458Z","end":"2026-07-06T23:09:04.373624Z","steps":["trace[480326587] 'process raft request' (duration: 119.785791ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:08.11592Z","caller":"traceutil/trace.go:171","msg":"trace[1136904518] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"234.977974ms","start":"2026-07-06T23:09:07.880911Z","end":"2026-07-06T23:09:08.115889Z","steps":["trace[1136904518] 'process raft request' (duration: 234.826933ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:08.45923Z","caller":"traceutil/trace.go:171","msg":"trace[525680431] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"239.621058ms","start":"2026-07-06T23:09:08.219585Z","end":"2026-07-06T23:09:08.459206Z","steps":["trace[525680431] 'process raft request' (duration: 147.231867ms)","trace[525680431] 'compare' (duration: 92.25644ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:09:08.468023Z","caller":"traceutil/trace.go:171","msg":"trace[148046825] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"211.233341ms","start":"2026-07-06T23:09:08.256768Z","end":"2026-07-06T23:09:08.468002Z","steps":["trace[148046825] 'process raft request' (duration: 211.12931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:08.553168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.017945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T23:09:08.553247Z","caller":"traceutil/trace.go:171","msg":"trace[1429668103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:447; }","duration":"161.123256ms","start":"2026-07-06T23:09:08.392104Z","end":"2026-07-06T23:09:08.553227Z","steps":["trace[1429668103] 'agreement among raft nodes before linearized reading' (duration: 160.984305ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:08.871071Z","caller":"traceutil/trace.go:171","msg":"trace[1907980442] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"312.225547ms","start":"2026-07-06T23:09:08.558819Z","end":"2026-07-06T23:09:08.871044Z","steps":["trace[1907980442] 'process raft request' (duration: 312.172586ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:08.871186Z","caller":"traceutil/trace.go:171","msg":"trace[1500095845] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"392.345321ms","start":"2026-07-06T23:09:08.478822Z","end":"2026-07-06T23:09:08.871167Z","steps":["trace[1500095845] 'process raft request' (duration: 392.067278ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:08.871227Z","caller":"traceutil/trace.go:171","msg":"trace[1512272119] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"395.429248ms","start":"2026-07-06T23:09:08.475767Z","end":"2026-07-06T23:09:08.871196Z","steps":["trace[1512272119] 'process raft request' (duration: 394.884733ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:08.871307Z","caller":"traceutil/trace.go:171","msg":"trace[1789714865] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:463; }","duration":"318.527002ms","start":"2026-07-06T23:09:08.552765Z","end":"2026-07-06T23:09:08.871292Z","steps":["trace[1789714865] 'read index received' (duration: 242.322232ms)","trace[1789714865] 'applied index is now lower than readState.Index' (duration: 76.2034ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:09:08.871374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:08.475749Z","time spent":"395.555219ms","remote":"127.0.0.1:32964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:09:08.871412Z","caller":"traceutil/trace.go:171","msg":"trace[1816882486] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"393.631223ms","start":"2026-07-06T23:09:08.477774Z","end":"2026-07-06T23:09:08.871405Z","steps":["trace[1816882486] 'process raft request' (duration: 393.053738ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:08.871414Z","caller":"traceutil/trace.go:171","msg":"trace[500301401] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"313.046344ms","start":"2026-07-06T23:09:08.558356Z","end":"2026-07-06T23:09:08.871402Z","steps":["trace[500301401] 'process raft request' (duration: 312.59246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:08.871462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:08.477758Z","time spent":"393.672643ms","remote":"127.0.0.1:33086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:09:08.871315Z","caller":"traceutil/trace.go:171","msg":"trace[26968788] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"396.061444ms","start":"2026-07-06T23:09:08.475244Z","end":"2026-07-06T23:09:08.871305Z","steps":["trace[26968788] 'process raft request' (duration: 395.482659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:08.871233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:08.558784Z","time spent":"312.379499ms","remote":"127.0.0.1:32964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T23:09:08.871581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.591618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-9vxqj\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-07-06T23:09:08.871638Z","caller":"traceutil/trace.go:171","msg":"trace[415472844] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-9vxqj; range_end:; response_count:1; response_revision:454; }","duration":"313.662088ms","start":"2026-07-06T23:09:08.557962Z","end":"2026-07-06T23:09:08.871624Z","steps":["trace[415472844] 'agreement among raft nodes before linearized reading' (duration: 313.570148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:08.871686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:08.557954Z","time spent":"313.72197ms","remote":"127.0.0.1:33086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4039,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-9vxqj\" "} {"level":"warn","ts":"2026-07-06T23:09:08.871705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.069659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-07-06T23:09:08.871728Z","caller":"traceutil/trace.go:171","msg":"trace[807448640] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:454; }","duration":"309.132259ms","start":"2026-07-06T23:09:08.562589Z","end":"2026-07-06T23:09:08.871722Z","steps":["trace[807448640] 'agreement among raft nodes before linearized reading' (duration: 309.090169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:08.871754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:08.562579Z","time spent":"309.170889ms","remote":"127.0.0.1:33402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4218,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-07-06T23:09:08.871838Z","caller":"traceutil/trace.go:171","msg":"trace[1534993787] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"395.998122ms","start":"2026-07-06T23:09:08.475429Z","end":"2026-07-06T23:09:08.871427Z","steps":["trace[1534993787] 'process raft request' (duration: 319.646551ms)","trace[1534993787] 'compare' (duration: 75.252692ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:09:08.871973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.405474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-06T23:09:08.871938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:08.475207Z","time spent":"396.477407ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:09:08.872317Z","caller":"traceutil/trace.go:171","msg":"trace[1716560092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:454; }","duration":"375.776807ms","start":"2026-07-06T23:09:08.496519Z","end":"2026-07-06T23:09:08.872296Z","steps":["trace[1716560092] 'agreement among raft nodes before linearized reading' (duration: 375.052721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:08.871493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:08.558328Z","time spent":"313.119024ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:09:08.872939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:08.475392Z","time spent":"396.590447ms","remote":"127.0.0.1:32964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T23:09:08.872403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:08.496509Z","time spent":"375.876608ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-06T23:09:08.87362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:08.478787Z","time spent":"392.464612ms","remote":"127.0.0.1:33426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:09:09.221392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.81779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:09:09.221462Z","caller":"traceutil/trace.go:171","msg":"trace[2042104271] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"346.35021ms","start":"2026-07-06T23:09:08.8751Z","end":"2026-07-06T23:09:09.22145Z","steps":["trace[2042104271] 'process raft request' (duration: 144.258078ms)","trace[2042104271] 'compare' (duration: 201.744799ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:09:09.221502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:08.875081Z","time spent":"346.398121ms","remote":"127.0.0.1:33086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4013,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:09:09.262097Z","caller":"traceutil/trace.go:171","msg":"trace[1338065231] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"384.575461ms","start":"2026-07-06T23:09:08.877498Z","end":"2026-07-06T23:09:09.262073Z","steps":["trace[1338065231] 'process raft request' (duration: 384.39775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:09.262242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:08.877478Z","time spent":"384.690632ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:09:09.262502Z","caller":"traceutil/trace.go:171","msg":"trace[1966494501] transaction","detail":"{read_only:false; number_of_response:1; response_revision:457; }","duration":"381.695568ms","start":"2026-07-06T23:09:08.880779Z","end":"2026-07-06T23:09:09.262474Z","steps":["trace[1966494501] 'process raft request' (duration: 381.516085ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:09.26259Z","caller":"traceutil/trace.go:171","msg":"trace[2105137180] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"382.709446ms","start":"2026-07-06T23:09:08.879793Z","end":"2026-07-06T23:09:09.262503Z","steps":["trace[2105137180] 'process raft request' (duration: 382.223881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:09.262734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:08.88077Z","time spent":"381.833949ms","remote":"127.0.0.1:33426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56,"response count":0,"response size":3889,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:09:09.262788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:08.879784Z","time spent":"382.925878ms","remote":"127.0.0.1:33426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:09:09.472101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.505745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:09:09.472338Z","caller":"traceutil/trace.go:171","msg":"trace[622252552] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"200.406886ms","start":"2026-07-06T23:09:09.27191Z","end":"2026-07-06T23:09:09.472317Z","steps":["trace[622252552] 'process raft request' (duration: 69.575348ms)","trace[622252552] 'compare' (duration: 130.358764ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:09:09.472684Z","caller":"traceutil/trace.go:171","msg":"trace[840148073] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:476; }","duration":"176.918882ms","start":"2026-07-06T23:09:09.295739Z","end":"2026-07-06T23:09:09.472658Z","steps":["trace[840148073] 'read index received' (duration: 45.817671ms)","trace[840148073] 'applied index is now lower than readState.Index' (duration: 131.100201ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:09:09.472831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.064854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:09:09.47287Z","caller":"traceutil/trace.go:171","msg":"trace[170345337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"177.152074ms","start":"2026-07-06T23:09:09.295708Z","end":"2026-07-06T23:09:09.47286Z","steps":["trace[170345337] 'agreement among raft nodes before linearized reading' (duration: 177.044163ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:09.47295Z","caller":"traceutil/trace.go:171","msg":"trace[2121039477] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"181.227491ms","start":"2026-07-06T23:09:09.291696Z","end":"2026-07-06T23:09:09.472923Z","steps":["trace[2121039477] 'process raft request' (duration: 180.700815ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:09.472947Z","caller":"traceutil/trace.go:171","msg":"trace[85330917] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"179.130072ms","start":"2026-07-06T23:09:09.293777Z","end":"2026-07-06T23:09:09.472907Z","steps":["trace[85330917] 'process raft request' (duration: 178.737457ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:11.016859Z","caller":"traceutil/trace.go:171","msg":"trace[1393748234] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"134.694706ms","start":"2026-07-06T23:09:10.882126Z","end":"2026-07-06T23:09:11.016821Z","steps":["trace[1393748234] 'process raft request' (duration: 134.499904ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:11.046069Z","caller":"traceutil/trace.go:171","msg":"trace[1961692817] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"163.827511ms","start":"2026-07-06T23:09:10.882205Z","end":"2026-07-06T23:09:11.046033Z","steps":["trace[1961692817] 'process raft request' (duration: 163.6902ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:21.342168Z","caller":"traceutil/trace.go:171","msg":"trace[290202215] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"244.631346ms","start":"2026-07-06T23:09:21.097499Z","end":"2026-07-06T23:09:21.342131Z","steps":["trace[290202215] 'process raft request' (duration: 170.372184ms)","trace[290202215] 'compare' (duration: 73.748574ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:09:22.978832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069228234s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:09:22.97893Z","caller":"traceutil/trace.go:171","msg":"trace[1289880400] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"705.698859ms","start":"2026-07-06T23:09:22.273217Z","end":"2026-07-06T23:09:22.978916Z","steps":["trace[1289880400] 'read index received' (duration: 28.67µs)","trace[1289880400] 'applied index is now lower than readState.Index' (duration: 705.668969ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:09:22.979031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.81589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-06T23:09:22.979017Z","caller":"traceutil/trace.go:171","msg":"trace[1489098926] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.554824622s","start":"2026-07-06T23:09:21.424162Z","end":"2026-07-06T23:09:22.978987Z","steps":["trace[1489098926] 'process raft request' (duration: 485.371044ms)","trace[1489098926] 'compare' (duration: 1.068651986s)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:09:22.979052Z","caller":"traceutil/trace.go:171","msg":"trace[1632367814] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:506; }","duration":"705.869101ms","start":"2026-07-06T23:09:22.273175Z","end":"2026-07-06T23:09:22.979044Z","steps":["trace[1632367814] 'agreement among raft nodes before linearized reading' (duration: 705.7763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:22.979078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:22.273158Z","time spent":"705.914832ms","remote":"127.0.0.1:33154","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-07-06T23:09:22.979168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:21.42414Z","time spent":"1.554930833s","remote":"127.0.0.1:33006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T23:09:25.508739Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478480786,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T23:09:25.539841Z","caller":"traceutil/trace.go:171","msg":"trace[999379930] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"888.871077ms","start":"2026-07-06T23:09:24.650937Z","end":"2026-07-06T23:09:25.539808Z","steps":["trace[999379930] 'process raft request' (duration: 888.720384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:25.540001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:24.650915Z","time spent":"889.0131ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:09:25.554574Z","caller":"traceutil/trace.go:171","msg":"trace[1267175526] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"891.486954ms","start":"2026-07-06T23:09:24.66306Z","end":"2026-07-06T23:09:25.554546Z","steps":["trace[1267175526] 'process raft request' (duration: 891.187919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:25.554682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:24.663042Z","time spent":"891.592746ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:09:25.554667Z","caller":"traceutil/trace.go:171","msg":"trace[1679087655] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"891.514004ms","start":"2026-07-06T23:09:24.663133Z","end":"2026-07-06T23:09:25.554647Z","steps":["trace[1679087655] 'process raft request' (duration: 891.313961ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:25.554773Z","caller":"traceutil/trace.go:171","msg":"trace[691445364] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"891.560735ms","start":"2026-07-06T23:09:24.663195Z","end":"2026-07-06T23:09:25.554755Z","steps":["trace[691445364] 'process raft request' (duration: 891.314041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:25.554812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:24.663129Z","time spent":"891.598876ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:09:25.554841Z","caller":"traceutil/trace.go:171","msg":"trace[1854997896] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"891.575295ms","start":"2026-07-06T23:09:24.66325Z","end":"2026-07-06T23:09:25.554825Z","steps":["trace[1854997896] 'process raft request' (duration: 891.371882ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:25.554905Z","caller":"traceutil/trace.go:171","msg":"trace[1110959676] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"890.105192ms","start":"2026-07-06T23:09:24.664765Z","end":"2026-07-06T23:09:25.55487Z","steps":["trace[1110959676] 'process raft request' (duration: 889.96279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:25.554933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:24.663246Z","time spent":"891.626237ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T23:09:25.554913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:24.663192Z","time spent":"891.642466ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T23:09:25.554964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:24.664749Z","time spent":"890.194093ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:09:25.55508Z","caller":"traceutil/trace.go:171","msg":"trace[253116081] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"888.290385ms","start":"2026-07-06T23:09:24.666769Z","end":"2026-07-06T23:09:25.55506Z","steps":["trace[253116081] 'process raft request' (duration: 888.081551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:25.555174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:24.666753Z","time spent":"888.379077ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:09:25.55523Z","caller":"traceutil/trace.go:171","msg":"trace[1836376210] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"886.737231ms","start":"2026-07-06T23:09:24.668439Z","end":"2026-07-06T23:09:25.555176Z","steps":["trace[1836376210] 'process raft request' (duration: 886.496796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:25.555516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:24.668425Z","time spent":"886.936544ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:09:25.559563Z","caller":"traceutil/trace.go:171","msg":"trace[1832006361] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"858.626278ms","start":"2026-07-06T23:09:24.700787Z","end":"2026-07-06T23:09:25.559413Z","steps":["trace[1832006361] 'process raft request' (duration: 854.777236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:25.559668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:24.700764Z","time spent":"858.857491ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:09:25.56004Z","caller":"traceutil/trace.go:171","msg":"trace[578863550] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"876.782185ms","start":"2026-07-06T23:09:24.683019Z","end":"2026-07-06T23:09:25.559801Z","steps":["trace[578863550] 'process raft request' (duration: 872.12166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:25.56012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:24.682999Z","time spent":"877.085899ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:09:25.565501Z","caller":"traceutil/trace.go:171","msg":"trace[1783219472] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:561; }","duration":"557.321977ms","start":"2026-07-06T23:09:25.008125Z","end":"2026-07-06T23:09:25.565447Z","steps":["trace[1783219472] 'read index received' (duration: 531.610562ms)","trace[1783219472] 'applied index is now lower than readState.Index' (duration: 25.709975ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:09:25.56559Z","caller":"traceutil/trace.go:171","msg":"trace[1966730538] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"857.568574ms","start":"2026-07-06T23:09:24.707992Z","end":"2026-07-06T23:09:25.565561Z","steps":["trace[1966730538] 'process raft request' (duration: 856.744411ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:25.565619Z","caller":"traceutil/trace.go:171","msg":"trace[675702242] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"761.427097ms","start":"2026-07-06T23:09:24.804178Z","end":"2026-07-06T23:09:25.565605Z","steps":["trace[675702242] 'process raft request' (duration: 761.113502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:25.565685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:24.804145Z","time spent":"761.496359ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T23:09:25.565692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:24.707965Z","time spent":"857.665975ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T23:09:25.565987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.868615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-06T23:09:25.56604Z","caller":"traceutil/trace.go:171","msg":"trace[63842425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"557.962717ms","start":"2026-07-06T23:09:25.008057Z","end":"2026-07-06T23:09:25.56602Z","steps":["trace[63842425] 'agreement among raft nodes before linearized reading' (duration: 557.732163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:25.566071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:25.008033Z","time spent":"558.030469ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-06T23:09:25.578854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.424936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:09:25.578925Z","caller":"traceutil/trace.go:171","msg":"trace[1397894627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"279.545158ms","start":"2026-07-06T23:09:25.299365Z","end":"2026-07-06T23:09:25.57891Z","steps":["trace[1397894627] 'agreement among raft nodes before linearized reading' (duration: 279.426106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:25.578947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.436942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-06T23:09:25.57898Z","caller":"traceutil/trace.go:171","msg":"trace[1154084613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"413.501553ms","start":"2026-07-06T23:09:25.165468Z","end":"2026-07-06T23:09:25.57897Z","steps":["trace[1154084613] 'agreement among raft nodes before linearized reading' (duration: 413.380351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:25.579007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:25.165449Z","time spent":"413.550044ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-06T23:09:25.579777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.938727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-06T23:09:25.579803Z","caller":"traceutil/trace.go:171","msg":"trace[1675987000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"180.003999ms","start":"2026-07-06T23:09:25.399792Z","end":"2026-07-06T23:09:25.579796Z","steps":["trace[1675987000] 'agreement among raft nodes before linearized reading' (duration: 178.958582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:25.580191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.011881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-06T23:09:25.580268Z","caller":"traceutil/trace.go:171","msg":"trace[782175998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"479.144553ms","start":"2026-07-06T23:09:25.101115Z","end":"2026-07-06T23:09:25.580259Z","steps":["trace[782175998] 'agreement among raft nodes before linearized reading' (duration: 477.534568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:25.580311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:25.101084Z","time spent":"479.219526ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-06T23:09:26.951747Z","caller":"traceutil/trace.go:171","msg":"trace[1390243287] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"485.802917ms","start":"2026-07-06T23:09:26.465898Z","end":"2026-07-06T23:09:26.951701Z","steps":["trace[1390243287] 'process raft request' (duration: 484.716289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:26.951942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:26.465849Z","time spent":"485.993289ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:09:27.643061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.727349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:09:27.643397Z","caller":"traceutil/trace.go:171","msg":"trace[1254015614] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.144533863s","start":"2026-07-06T23:09:26.498817Z","end":"2026-07-06T23:09:27.643351Z","steps":["trace[1254015614] 'process raft request' (duration: 736.435798ms)","trace[1254015614] 'compare' (duration: 407.410914ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:09:27.643459Z","caller":"traceutil/trace.go:171","msg":"trace[1606353501] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"634.680538ms","start":"2026-07-06T23:09:27.008739Z","end":"2026-07-06T23:09:27.64342Z","steps":["trace[1606353501] 'process raft request' (duration: 634.429314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:27.643622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:26.498775Z","time spent":"1.144738807s","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T23:09:27.643641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:27.008719Z","time spent":"634.82614ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:09:27.643673Z","caller":"traceutil/trace.go:171","msg":"trace[741223959] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:641; }","duration":"345.586406ms","start":"2026-07-06T23:09:27.298058Z","end":"2026-07-06T23:09:27.643645Z","steps":["trace[741223959] 'read index received' (duration: 274.612995ms)","trace[741223959] 'applied index is now lower than readState.Index' (duration: 70.972691ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:09:27.643768Z","caller":"traceutil/trace.go:171","msg":"trace[1904400083] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"472.447304ms","start":"2026-07-06T23:09:27.171314Z","end":"2026-07-06T23:09:27.643761Z","steps":["trace[1904400083] 'process raft request' (duration: 472.058487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:27.643844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:27.171293Z","time spent":"472.495224ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:09:27.644145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.028524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:09:27.64418Z","caller":"traceutil/trace.go:171","msg":"trace[1695615289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"346.154326ms","start":"2026-07-06T23:09:27.298015Z","end":"2026-07-06T23:09:27.644169Z","steps":["trace[1695615289] 'agreement among raft nodes before linearized reading' (duration: 346.040444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:27.644224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:27.297998Z","time spent":"346.197556ms","remote":"127.0.0.1:32874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-06T23:09:27.908372Z","caller":"traceutil/trace.go:171","msg":"trace[1932751716] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"259.565712ms","start":"2026-07-06T23:09:27.648739Z","end":"2026-07-06T23:09:27.908305Z","steps":["trace[1932751716] 'process raft request' (duration: 185.951896ms)","trace[1932751716] 'compare' (duration: 73.28858ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:09:27.908385Z","caller":"traceutil/trace.go:171","msg":"trace[687000115] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"259.592362ms","start":"2026-07-06T23:09:27.648739Z","end":"2026-07-06T23:09:27.908331Z","steps":["trace[687000115] 'process raft request' (duration: 259.447549ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:27.908851Z","caller":"traceutil/trace.go:171","msg":"trace[1576229385] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"260.004579ms","start":"2026-07-06T23:09:27.648829Z","end":"2026-07-06T23:09:27.908833Z","steps":["trace[1576229385] 'process raft request' (duration: 259.443109ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:27.909151Z","caller":"traceutil/trace.go:171","msg":"trace[875938766] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"182.399336ms","start":"2026-07-06T23:09:27.726737Z","end":"2026-07-06T23:09:27.909136Z","steps":["trace[875938766] 'process raft request' (duration: 181.992689ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:27.909212Z","caller":"traceutil/trace.go:171","msg":"trace[2134366455] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:645; }","duration":"232.298451ms","start":"2026-07-06T23:09:27.676891Z","end":"2026-07-06T23:09:27.909189Z","steps":["trace[2134366455] 'read index received' (duration: 63.384513ms)","trace[2134366455] 'applied index is now lower than readState.Index' (duration: 168.913168ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:09:27.90933Z","caller":"traceutil/trace.go:171","msg":"trace[1625211557] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"171.032654ms","start":"2026-07-06T23:09:27.738279Z","end":"2026-07-06T23:09:27.909311Z","steps":["trace[1625211557] 'process raft request' (duration: 170.613917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:27.911389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.417077ms","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-07-06T23:09:27.911444Z","caller":"traceutil/trace.go:171","msg":"trace[877515667] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:623; }","duration":"234.573739ms","start":"2026-07-06T23:09:27.676856Z","end":"2026-07-06T23:09:27.911429Z","steps":["trace[877515667] 'agreement among raft nodes before linearized reading' (duration: 232.426982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:32.228104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.847639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:09:32.228218Z","caller":"traceutil/trace.go:171","msg":"trace[1887291433] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"471.74765ms","start":"2026-07-06T23:09:31.756451Z","end":"2026-07-06T23:09:32.228198Z","steps":["trace[1887291433] 'process raft request' (duration: 364.726397ms)","trace[1887291433] 'compare' (duration: 106.755978ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:09:32.228274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:31.756431Z","time spent":"471.817951ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:09:32.286392Z","caller":"traceutil/trace.go:171","msg":"trace[819730188] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"366.095799ms","start":"2026-07-06T23:09:31.920271Z","end":"2026-07-06T23:09:32.286367Z","steps":["trace[819730188] 'read index received' (duration: 200.951369ms)","trace[819730188] 'applied index is now lower than readState.Index' (duration: 165.14345ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:09:32.286804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.501917ms","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-07-06T23:09:32.286852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.28479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-06T23:09:32.286827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.890774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gqwg8\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-07-06T23:09:32.286953Z","caller":"traceutil/trace.go:171","msg":"trace[1072378631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"366.676421ms","start":"2026-07-06T23:09:31.920229Z","end":"2026-07-06T23:09:32.286905Z","steps":["trace[1072378631] 'agreement among raft nodes before linearized reading' (duration: 366.417285ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:32.287094Z","caller":"traceutil/trace.go:171","msg":"trace[496964292] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"430.575483ms","start":"2026-07-06T23:09:31.856039Z","end":"2026-07-06T23:09:32.286615Z","steps":["trace[496964292] 'process raft request' (duration: 429.354251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:32.287119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:31.920209Z","time spent":"366.871324ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-06T23:09:32.286943Z","caller":"traceutil/trace.go:171","msg":"trace[594813703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"362.411952ms","start":"2026-07-06T23:09:31.924517Z","end":"2026-07-06T23:09:32.286928Z","steps":["trace[594813703] 'agreement among raft nodes before linearized reading' (duration: 362.220768ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:32.287361Z","caller":"traceutil/trace.go:171","msg":"trace[1553470337] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gqwg8; range_end:; response_count:1; response_revision:645; }","duration":"174.133168ms","start":"2026-07-06T23:09:32.11287Z","end":"2026-07-06T23:09:32.287003Z","steps":["trace[1553470337] 'agreement among raft nodes before linearized reading' (duration: 173.827082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:32.286801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.328641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-06T23:09:32.287465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:31.924497Z","time spent":"362.82427ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-06T23:09:32.287492Z","caller":"traceutil/trace.go:171","msg":"trace[585733874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"363.085785ms","start":"2026-07-06T23:09:31.924396Z","end":"2026-07-06T23:09:32.287482Z","steps":["trace[585733874] 'agreement among raft nodes before linearized reading' (duration: 362.257739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:32.287551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:31.924367Z","time spent":"363.151746ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-06T23:09:32.287724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:31.856004Z","time spent":"431.151114ms","remote":"127.0.0.1:32990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:09:32.481101Z","caller":"traceutil/trace.go:171","msg":"trace[425280223] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"188.624739ms","start":"2026-07-06T23:09:32.292454Z","end":"2026-07-06T23:09:32.481079Z","steps":["trace[425280223] 'process raft request' (duration: 124.905036ms)","trace[425280223] 'compare' (duration: 63.544879ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:09:32.481312Z","caller":"traceutil/trace.go:171","msg":"trace[239257799] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"186.666453ms","start":"2026-07-06T23:09:32.294638Z","end":"2026-07-06T23:09:32.481304Z","steps":["trace[239257799] 'process raft request' (duration: 186.393878ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:32.561341Z","caller":"traceutil/trace.go:171","msg":"trace[138047813] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"266.511148ms","start":"2026-07-06T23:09:32.294796Z","end":"2026-07-06T23:09:32.561307Z","steps":["trace[138047813] 'process raft request' (duration: 266.301723ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:32.561808Z","caller":"traceutil/trace.go:171","msg":"trace[1277884917] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"257.07034ms","start":"2026-07-06T23:09:32.30471Z","end":"2026-07-06T23:09:32.56178Z","steps":["trace[1277884917] 'process raft request' (duration: 256.531669ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:32.562051Z","caller":"traceutil/trace.go:171","msg":"trace[633958887] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"226.712122ms","start":"2026-07-06T23:09:32.335326Z","end":"2026-07-06T23:09:32.562038Z","steps":["trace[633958887] 'process raft request' (duration: 226.404706ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:32.562002Z","caller":"traceutil/trace.go:171","msg":"trace[1859527578] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:673; }","duration":"264.52892ms","start":"2026-07-06T23:09:32.297436Z","end":"2026-07-06T23:09:32.561965Z","steps":["trace[1859527578] 'read index received' (duration: 120.052616ms)","trace[1859527578] 'applied index is now lower than readState.Index' (duration: 144.474384ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:09:32.562342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.861426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:09:32.562415Z","caller":"traceutil/trace.go:171","msg":"trace[1823994951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"264.985769ms","start":"2026-07-06T23:09:32.297415Z","end":"2026-07-06T23:09:32.562401Z","steps":["trace[1823994951] 'agreement among raft nodes before linearized reading' (duration: 264.739794ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:33.355736Z","caller":"traceutil/trace.go:171","msg":"trace[2008356453] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"392.288029ms","start":"2026-07-06T23:09:32.963416Z","end":"2026-07-06T23:09:33.355704Z","steps":["trace[2008356453] 'process raft request' (duration: 391.437043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:33.355927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:32.963385Z","time spent":"392.427992ms","remote":"127.0.0.1:32990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:09:34.733004Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478480974,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T23:09:35.99542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.552299178s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:09:35.996186Z","caller":"traceutil/trace.go:171","msg":"trace[1658005878] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"2.5828022s","start":"2026-07-06T23:09:33.413336Z","end":"2026-07-06T23:09:35.996138Z","steps":["trace[1658005878] 'process raft request' (duration: 29.677725ms)","trace[1658005878] 'compare' (duration: 2.551868519s)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:09:35.996363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:33.413306Z","time spent":"2.582973733s","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:09:35.99662Z","caller":"traceutil/trace.go:171","msg":"trace[1639764412] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"2.153911509s","start":"2026-07-06T23:09:33.842699Z","end":"2026-07-06T23:09:35.996611Z","steps":["trace[1639764412] 'process raft request' (duration: 2.152878178s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:35.996692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:33.842674Z","time spent":"2.15397382s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:09:35.996773Z","caller":"traceutil/trace.go:171","msg":"trace[246161156] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:679; }","duration":"1.764408283s","start":"2026-07-06T23:09:34.232308Z","end":"2026-07-06T23:09:35.996716Z","steps":["trace[246161156] 'read index received' (duration: 897.936029ms)","trace[246161156] 'applied index is now lower than readState.Index' (duration: 866.467904ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:09:36.004696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.706437371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:09:36.004759Z","caller":"traceutil/trace.go:171","msg":"trace[855946462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"1.706536354s","start":"2026-07-06T23:09:34.298206Z","end":"2026-07-06T23:09:36.004743Z","steps":["trace[855946462] 'agreement among raft nodes before linearized reading' (duration: 1.706427031s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:36.004794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:34.298194Z","time spent":"1.706593244s","remote":"127.0.0.1:32872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T23:09:36.004846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437762963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-06T23:09:36.004905Z","caller":"traceutil/trace.go:171","msg":"trace[2135919037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:653; }","duration":"1.437857474s","start":"2026-07-06T23:09:34.567032Z","end":"2026-07-06T23:09:36.004889Z","steps":["trace[2135919037] 'agreement among raft nodes before linearized reading' (duration: 1.437659831s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:36.00489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.772578614s","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-07-06T23:09:36.004941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:34.567015Z","time spent":"1.437919696s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-06T23:09:36.004928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514356729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-06T23:09:36.004948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.515247175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T23:09:36.004961Z","caller":"traceutil/trace.go:171","msg":"trace[987894813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:653; }","duration":"1.772697637s","start":"2026-07-06T23:09:34.232248Z","end":"2026-07-06T23:09:36.004945Z","steps":["trace[987894813] 'agreement among raft nodes before linearized reading' (duration: 1.764560146s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:36.004979Z","caller":"traceutil/trace.go:171","msg":"trace[1531200312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"1.514477431s","start":"2026-07-06T23:09:34.490491Z","end":"2026-07-06T23:09:36.004969Z","steps":["trace[1531200312] 'agreement among raft nodes before linearized reading' (duration: 1.514298888s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:36.004991Z","caller":"traceutil/trace.go:171","msg":"trace[1264022711] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:653; }","duration":"1.515331927s","start":"2026-07-06T23:09:34.489646Z","end":"2026-07-06T23:09:36.004978Z","steps":["trace[1264022711] 'agreement among raft nodes before linearized reading' (duration: 1.515159434s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:36.00501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:34.490471Z","time spent":"1.514532622s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-06T23:09:36.005012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:34.23222Z","time spent":"1.772783729s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-06T23:09:36.005019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:34.489622Z","time spent":"1.515391918s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-06T23:09:42.033912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.781882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-07-06T23:09:42.033999Z","caller":"traceutil/trace.go:171","msg":"trace[2130389372] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:744; }","duration":"117.927395ms","start":"2026-07-06T23:09:41.91605Z","end":"2026-07-06T23:09:42.033978Z","steps":["trace[2130389372] 'range keys from in-memory index tree' (duration: 117.584597ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:48.354797Z","caller":"traceutil/trace.go:171","msg":"trace[878094274] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"247.68306ms","start":"2026-07-06T23:09:48.10709Z","end":"2026-07-06T23:09:48.354773Z","steps":["trace[878094274] 'read index received' (duration: 247.466315ms)","trace[878094274] 'applied index is now lower than readState.Index' (duration: 215.895µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:09:48.354881Z","caller":"traceutil/trace.go:171","msg":"trace[1330579265] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"270.498557ms","start":"2026-07-06T23:09:48.084342Z","end":"2026-07-06T23:09:48.35484Z","steps":["trace[1330579265] 'process raft request' (duration: 270.280872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:48.355095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.998777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-06T23:09:48.355148Z","caller":"traceutil/trace.go:171","msg":"trace[72426295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"248.14297ms","start":"2026-07-06T23:09:48.106991Z","end":"2026-07-06T23:09:48.355134Z","steps":["trace[72426295] 'agreement among raft nodes before linearized reading' (duration: 247.956016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:48.35528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.60062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-06T23:09:48.355309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.525979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-06T23:09:48.355355Z","caller":"traceutil/trace.go:171","msg":"trace[382528715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:801; }","duration":"245.712003ms","start":"2026-07-06T23:09:48.10962Z","end":"2026-07-06T23:09:48.355332Z","steps":["trace[382528715] 'agreement among raft nodes before linearized reading' (duration: 245.505108ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:09:48.355386Z","caller":"traceutil/trace.go:171","msg":"trace[554350456] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"245.643323ms","start":"2026-07-06T23:09:48.109727Z","end":"2026-07-06T23:09:48.35537Z","steps":["trace[554350456] 'agreement among raft nodes before linearized reading' (duration: 245.406516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:48.355291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.2194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-06T23:09:48.355456Z","caller":"traceutil/trace.go:171","msg":"trace[1238145419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"239.411925ms","start":"2026-07-06T23:09:48.116034Z","end":"2026-07-06T23:09:48.355446Z","steps":["trace[1238145419] 'agreement among raft nodes before linearized reading' (duration: 239.123448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:51.735545Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478481386,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T23:09:51.808031Z","caller":"traceutil/trace.go:171","msg":"trace[105443760] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"589.178994ms","start":"2026-07-06T23:09:51.218839Z","end":"2026-07-06T23:09:51.808018Z","steps":["trace[105443760] 'process raft request' (duration: 588.929698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:51.808199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:51.21881Z","time spent":"589.305807ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:09:51.808577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.690596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ckxqd\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-07-06T23:09:51.80865Z","caller":"traceutil/trace.go:171","msg":"trace[731271579] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ckxqd; range_end:; response_count:1; response_revision:820; }","duration":"573.809849ms","start":"2026-07-06T23:09:51.234824Z","end":"2026-07-06T23:09:51.808633Z","steps":["trace[731271579] 'agreement among raft nodes before linearized reading' (duration: 573.577604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:51.808692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:51.234807Z","time spent":"573.872511ms","remote":"127.0.0.1:33086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ckxqd\" "} {"level":"info","ts":"2026-07-06T23:09:51.807974Z","caller":"traceutil/trace.go:171","msg":"trace[2117408358] linearizableReadLoop","detail":"{readStateIndex:855; appliedIndex:854; }","duration":"573.066552ms","start":"2026-07-06T23:09:51.234867Z","end":"2026-07-06T23:09:51.807933Z","steps":["trace[2117408358] 'read index received' (duration: 572.824296ms)","trace[2117408358] 'applied index is now lower than readState.Index' (duration: 241.356µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:09:51.80892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.337801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:09:51.808962Z","caller":"traceutil/trace.go:171","msg":"trace[293976269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:820; }","duration":"512.442204ms","start":"2026-07-06T23:09:51.296508Z","end":"2026-07-06T23:09:51.80895Z","steps":["trace[293976269] 'agreement among raft nodes before linearized reading' (duration: 512.344812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:51.809025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:51.296486Z","time spent":"512.529637ms","remote":"127.0.0.1:32872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T23:09:52.455901Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478481391,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T23:09:52.467942Z","caller":"traceutil/trace.go:171","msg":"trace[1205485274] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:855; }","duration":"512.817451ms","start":"2026-07-06T23:09:51.955098Z","end":"2026-07-06T23:09:52.467915Z","steps":["trace[1205485274] 'read index received' (duration: 512.618015ms)","trace[1205485274] 'applied index is now lower than readState.Index' (duration: 198.796µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:09:52.468129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.988325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:09:52.468197Z","caller":"traceutil/trace.go:171","msg":"trace[618892717] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:821; }","duration":"513.153519ms","start":"2026-07-06T23:09:51.955007Z","end":"2026-07-06T23:09:52.468161Z","steps":["trace[618892717] 'agreement among raft nodes before linearized reading' (duration: 513.024056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:52.468229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:51.954983Z","time spent":"513.237741ms","remote":"127.0.0.1:33214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-07-06T23:09:52.467994Z","caller":"traceutil/trace.go:171","msg":"trace[1673691329] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"651.263314ms","start":"2026-07-06T23:09:51.816709Z","end":"2026-07-06T23:09:52.467972Z","steps":["trace[1673691329] 'process raft request' (duration: 651.063058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:52.468488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.323723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:09:52.468552Z","caller":"traceutil/trace.go:171","msg":"trace[169319484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:821; }","duration":"168.510977ms","start":"2026-07-06T23:09:52.300029Z","end":"2026-07-06T23:09:52.46854Z","steps":["trace[169319484] 'agreement among raft nodes before linearized reading' (duration: 168.317633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:09:52.47049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:09:51.816694Z","time spent":"651.690754ms","remote":"127.0.0.1:33086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4023,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:10:05.597712Z","caller":"traceutil/trace.go:171","msg":"trace[781690547] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"174.158851ms","start":"2026-07-06T23:10:05.423503Z","end":"2026-07-06T23:10:05.597662Z","steps":["trace[781690547] 'process raft request' (duration: 173.936815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:16.873995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.187404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:10:16.874687Z","caller":"traceutil/trace.go:171","msg":"trace[591173123] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"193.635919ms","start":"2026-07-06T23:10:16.681037Z","end":"2026-07-06T23:10:16.874673Z","steps":["trace[591173123] 'process raft request' (duration: 48.721533ms)","trace[591173123] 'compare' (duration: 144.116202ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:10:16.874965Z","caller":"traceutil/trace.go:171","msg":"trace[1138472462] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"193.910567ms","start":"2026-07-06T23:10:16.681047Z","end":"2026-07-06T23:10:16.874957Z","steps":["trace[1138472462] 'process raft request' (duration: 193.273058ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:16.875214Z","caller":"traceutil/trace.go:171","msg":"trace[1147636014] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"193.839764ms","start":"2026-07-06T23:10:16.681367Z","end":"2026-07-06T23:10:16.875207Z","steps":["trace[1147636014] 'process raft request' (duration: 192.987219ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:16.875567Z","caller":"traceutil/trace.go:171","msg":"trace[1135572682] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"194.113293ms","start":"2026-07-06T23:10:16.681442Z","end":"2026-07-06T23:10:16.875556Z","steps":["trace[1135572682] 'process raft request' (duration: 192.934958ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:16.875973Z","caller":"traceutil/trace.go:171","msg":"trace[771748288] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"194.055631ms","start":"2026-07-06T23:10:16.681904Z","end":"2026-07-06T23:10:16.87596Z","steps":["trace[771748288] 'process raft request' (duration: 193.605768ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:19.029969Z","caller":"traceutil/trace.go:171","msg":"trace[382748227] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"148.192586ms","start":"2026-07-06T23:10:18.881742Z","end":"2026-07-06T23:10:19.029935Z","steps":["trace[382748227] 'process raft request' (duration: 147.99491ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:19.095291Z","caller":"traceutil/trace.go:171","msg":"trace[607637840] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"211.456135ms","start":"2026-07-06T23:10:18.883797Z","end":"2026-07-06T23:10:19.095253Z","steps":["trace[607637840] 'process raft request' (duration: 211.363522ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:19.095297Z","caller":"traceutil/trace.go:171","msg":"trace[738233488] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"211.521406ms","start":"2026-07-06T23:10:18.88375Z","end":"2026-07-06T23:10:19.095271Z","steps":["trace[738233488] 'process raft request' (duration: 211.260518ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:19.095474Z","caller":"traceutil/trace.go:171","msg":"trace[784916689] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"210.858397ms","start":"2026-07-06T23:10:18.884605Z","end":"2026-07-06T23:10:19.095463Z","steps":["trace[784916689] 'process raft request' (duration: 210.607239ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:19.095606Z","caller":"traceutil/trace.go:171","msg":"trace[834970386] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"210.816796ms","start":"2026-07-06T23:10:18.884778Z","end":"2026-07-06T23:10:19.095595Z","steps":["trace[834970386] 'process raft request' (duration: 210.6221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:30.715762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.515534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:10:30.716034Z","caller":"traceutil/trace.go:171","msg":"trace[1173338377] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"114.523592ms","start":"2026-07-06T23:10:30.601422Z","end":"2026-07-06T23:10:30.715945Z","steps":["trace[1173338377] 'compare' (duration: 104.38669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:39.393696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.8185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-06T23:10:39.39378Z","caller":"traceutil/trace.go:171","msg":"trace[557045128] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"167.949645ms","start":"2026-07-06T23:10:39.225809Z","end":"2026-07-06T23:10:39.393758Z","steps":["trace[557045128] 'range keys from in-memory index tree' (duration: 167.618384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:39.393918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.255637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-06T23:10:39.394024Z","caller":"traceutil/trace.go:171","msg":"trace[1258374881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"162.388772ms","start":"2026-07-06T23:10:39.231613Z","end":"2026-07-06T23:10:39.394001Z","steps":["trace[1258374881] 'range keys from in-memory index tree' (duration: 162.150573ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:39.736398Z","caller":"traceutil/trace.go:171","msg":"trace[1829491625] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"338.453596ms","start":"2026-07-06T23:10:39.397917Z","end":"2026-07-06T23:10:39.736371Z","steps":["trace[1829491625] 'process raft request' (duration: 337.523484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:39.736584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:10:39.397896Z","time spent":"338.586799ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:10:39.736704Z","caller":"traceutil/trace.go:171","msg":"trace[1882598909] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"338.238828ms","start":"2026-07-06T23:10:39.398454Z","end":"2026-07-06T23:10:39.736693Z","steps":["trace[1882598909] 'process raft request' (duration: 337.342239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:39.73676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:10:39.398445Z","time spent":"338.287469ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:10:39.736859Z","caller":"traceutil/trace.go:171","msg":"trace[542722989] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"337.617088ms","start":"2026-07-06T23:10:39.399233Z","end":"2026-07-06T23:10:39.736851Z","steps":["trace[542722989] 'process raft request' (duration: 336.607975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:10:39.736908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:10:39.399222Z","time spent":"337.663369ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:10:40.218962Z","caller":"traceutil/trace.go:171","msg":"trace[132770702] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"334.115288ms","start":"2026-07-06T23:10:39.884811Z","end":"2026-07-06T23:10:40.218926Z","steps":["trace[132770702] 'process raft request' (duration: 323.61145ms)","trace[132770702] 'compare' (duration: 10.25493ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:10:40.219113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:10:39.884783Z","time spent":"334.249762ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:10:41.846195Z","caller":"traceutil/trace.go:171","msg":"trace[1600514835] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"100.548007ms","start":"2026-07-06T23:10:41.745624Z","end":"2026-07-06T23:10:41.846172Z","steps":["trace[1600514835] 'process raft request' (duration: 100.360161ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:41.846434Z","caller":"traceutil/trace.go:171","msg":"trace[604865414] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"100.432934ms","start":"2026-07-06T23:10:41.745969Z","end":"2026-07-06T23:10:41.846402Z","steps":["trace[604865414] 'process raft request' (duration: 100.159665ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:43.518978Z","caller":"traceutil/trace.go:171","msg":"trace[273734496] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"213.33049ms","start":"2026-07-06T23:10:43.305624Z","end":"2026-07-06T23:10:43.518955Z","steps":["trace[273734496] 'process raft request' (duration: 213.01154ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:45.797541Z","caller":"traceutil/trace.go:171","msg":"trace[702742472] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"268.309726ms","start":"2026-07-06T23:10:45.529209Z","end":"2026-07-06T23:10:45.797519Z","steps":["trace[702742472] 'process raft request' (duration: 268.163481ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:45.797772Z","caller":"traceutil/trace.go:171","msg":"trace[1653300908] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1168; }","duration":"257.196832ms","start":"2026-07-06T23:10:45.540565Z","end":"2026-07-06T23:10:45.797762Z","steps":["trace[1653300908] 'read index received' (duration: 257.182722ms)","trace[1653300908] 'applied index is now lower than readState.Index' (duration: 8.39µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:10:45.798022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.454212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T23:10:45.798057Z","caller":"traceutil/trace.go:171","msg":"trace[1431249275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1123; }","duration":"257.554376ms","start":"2026-07-06T23:10:45.540492Z","end":"2026-07-06T23:10:45.798046Z","steps":["trace[1431249275] 'agreement among raft nodes before linearized reading' (duration: 257.432082ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:10:50.574145Z","caller":"traceutil/trace.go:171","msg":"trace[36374448] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"179.273225ms","start":"2026-07-06T23:10:50.394847Z","end":"2026-07-06T23:10:50.574121Z","steps":["trace[36374448] 'process raft request' (duration: 179.078809ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:02.778265Z","caller":"traceutil/trace.go:171","msg":"trace[1379167984] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"102.160684ms","start":"2026-07-06T23:11:02.676084Z","end":"2026-07-06T23:11:02.778244Z","steps":["trace[1379167984] 'process raft request' (duration: 102.0467ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:04.299057Z","caller":"traceutil/trace.go:171","msg":"trace[1367022921] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"118.382636ms","start":"2026-07-06T23:11:04.180647Z","end":"2026-07-06T23:11:04.299029Z","steps":["trace[1367022921] 'process raft request' (duration: 100.025595ms)","trace[1367022921] 'compare' (duration: 18.211566ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:11:06.343105Z","caller":"traceutil/trace.go:171","msg":"trace[556028038] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"127.928593ms","start":"2026-07-06T23:11:06.215131Z","end":"2026-07-06T23:11:06.343059Z","steps":["trace[556028038] 'process raft request' (duration: 41.012759ms)","trace[556028038] 'compare' (duration: 86.719777ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:11:06.499694Z","caller":"traceutil/trace.go:171","msg":"trace[1427550795] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"152.56628ms","start":"2026-07-06T23:11:06.347061Z","end":"2026-07-06T23:11:06.499627Z","steps":["trace[1427550795] 'process raft request' (duration: 131.140748ms)","trace[1427550795] 'compare' (duration: 21.256396ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:11:08.536466Z","caller":"traceutil/trace.go:171","msg":"trace[248788616] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"184.866368ms","start":"2026-07-06T23:11:08.351576Z","end":"2026-07-06T23:11:08.536443Z","steps":["trace[248788616] 'process raft request' (duration: 184.735594ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:08.542036Z","caller":"traceutil/trace.go:171","msg":"trace[1784241629] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1247; }","duration":"155.209458ms","start":"2026-07-06T23:11:08.386804Z","end":"2026-07-06T23:11:08.542013Z","steps":["trace[1784241629] 'read index received' (duration: 149.520914ms)","trace[1784241629] 'applied index is now lower than readState.Index' (duration: 5.687454ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:11:08.542081Z","caller":"traceutil/trace.go:171","msg":"trace[1925195552] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"184.707232ms","start":"2026-07-06T23:11:08.357357Z","end":"2026-07-06T23:11:08.542064Z","steps":["trace[1925195552] 'process raft request' (duration: 184.549567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:08.542165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.335183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:11:08.542224Z","caller":"traceutil/trace.go:171","msg":"trace[2059677680] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1199; }","duration":"155.444207ms","start":"2026-07-06T23:11:08.386766Z","end":"2026-07-06T23:11:08.542211Z","steps":["trace[2059677680] 'agreement among raft nodes before linearized reading' (duration: 155.331593ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:28.426762Z","caller":"traceutil/trace.go:171","msg":"trace[233239397] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"108.949536ms","start":"2026-07-06T23:11:28.317787Z","end":"2026-07-06T23:11:28.426737Z","steps":["trace[233239397] 'process raft request' (duration: 108.350624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:46.029569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.050663ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-06T23:11:46.631529Z","caller":"traceutil/trace.go:171","msg":"trace[732028123] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"104.023915ms","start":"2026-07-06T23:11:46.527485Z","end":"2026-07-06T23:11:46.631509Z","steps":["trace[732028123] 'process raft request' (duration: 103.889249ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:46.985384Z","caller":"traceutil/trace.go:171","msg":"trace[1457842365] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"121.781027ms","start":"2026-07-06T23:11:46.863577Z","end":"2026-07-06T23:11:46.985358Z","steps":["trace[1457842365] 'process raft request' (duration: 49.469965ms)","trace[1457842365] 'compare' (duration: 72.224149ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:11:47.032577Z","caller":"traceutil/trace.go:171","msg":"trace[1256003755] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1420; }","duration":"158.548063ms","start":"2026-07-06T23:11:46.874012Z","end":"2026-07-06T23:11:47.03256Z","steps":["trace[1256003755] 'read index received' (duration: 39.04345ms)","trace[1256003755] 'applied index is now lower than readState.Index' (duration: 119.503483ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:11:47.032619Z","caller":"traceutil/trace.go:171","msg":"trace[302454033] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"168.182258ms","start":"2026-07-06T23:11:46.864395Z","end":"2026-07-06T23:11:47.032578Z","steps":["trace[302454033] 'process raft request' (duration: 168.047683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:47.032783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.72751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T23:11:47.032814Z","caller":"traceutil/trace.go:171","msg":"trace[290394803] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1362; }","duration":"158.822863ms","start":"2026-07-06T23:11:46.873983Z","end":"2026-07-06T23:11:47.032806Z","steps":["trace[290394803] 'agreement among raft nodes before linearized reading' (duration: 158.703189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:49.538799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478482683,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T23:11:49.589711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.639139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-06T23:11:49.589802Z","caller":"traceutil/trace.go:171","msg":"trace[1247478017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1366; }","duration":"600.799495ms","start":"2026-07-06T23:11:48.988983Z","end":"2026-07-06T23:11:49.589783Z","steps":["trace[1247478017] 'range keys from in-memory index tree' (duration: 600.39811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:49.589846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:48.988956Z","time spent":"600.880578ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-06T23:11:49.590018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.726617ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:11:49.59015Z","caller":"traceutil/trace.go:171","msg":"trace[2021972519] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1366; }","duration":"409.882402ms","start":"2026-07-06T23:11:49.180251Z","end":"2026-07-06T23:11:49.590133Z","steps":["trace[2021972519] 'range keys from in-memory index tree' (duration: 409.708336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:49.59027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.264378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T23:11:49.590338Z","caller":"traceutil/trace.go:171","msg":"trace[2072740045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1366; }","duration":"954.393732ms","start":"2026-07-06T23:11:48.635926Z","end":"2026-07-06T23:11:49.59032Z","steps":["trace[2072740045] 'range keys from in-memory index tree' (duration: 954.115012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:49.590382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:48.635903Z","time spent":"954.466825ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-06T23:11:49.590522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.625743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-06T23:11:49.5906Z","caller":"traceutil/trace.go:171","msg":"trace[1587724808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1366; }","duration":"908.737948ms","start":"2026-07-06T23:11:48.681851Z","end":"2026-07-06T23:11:49.590589Z","steps":["trace[1587724808] 'range keys from in-memory index tree' (duration: 908.484898ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:49.590606Z","caller":"traceutil/trace.go:171","msg":"trace[816377070] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"590.141441ms","start":"2026-07-06T23:11:49.000448Z","end":"2026-07-06T23:11:49.590589Z","steps":["trace[816377070] 'process raft request' (duration: 545.817074ms)","trace[816377070] 'compare' (duration: 43.226155ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:11:49.59077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:49.000423Z","time spent":"590.261715ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:11:49.590634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:48.681835Z","time spent":"908.790719ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-06T23:11:49.63776Z","caller":"traceutil/trace.go:171","msg":"trace[1610611857] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1426; }","duration":"600.103839ms","start":"2026-07-06T23:11:49.037631Z","end":"2026-07-06T23:11:49.637734Z","steps":["trace[1610611857] 'read index received' (duration: 508.615649ms)","trace[1610611857] 'applied index is now lower than readState.Index' (duration: 91.48659ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:11:49.637891Z","caller":"traceutil/trace.go:171","msg":"trace[309575306] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"430.412784ms","start":"2026-07-06T23:11:49.207467Z","end":"2026-07-06T23:11:49.63788Z","steps":["trace[309575306] 'process raft request' (duration: 430.093802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:49.638002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.363239ms","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-07-06T23:11:49.638024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:49.207441Z","time spent":"430.485537ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:11:49.638057Z","caller":"traceutil/trace.go:171","msg":"trace[1675806200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1368; }","duration":"600.465143ms","start":"2026-07-06T23:11:49.037577Z","end":"2026-07-06T23:11:49.638043Z","steps":["trace[1675806200] 'agreement among raft nodes before linearized reading' (duration: 600.329708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:49.638104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:49.037552Z","time spent":"600.542826ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-06T23:11:50.138043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478482689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T23:11:50.638155Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478482689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T23:11:51.139008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478482689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T23:11:51.302144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000333609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-06T23:11:51.302217Z","caller":"traceutil/trace.go:171","msg":"trace[410567401] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000454214s","start":"2026-07-06T23:11:49.301745Z","end":"2026-07-06T23:11:51.302199Z","steps":["trace[410567401] 'agreement among raft nodes before linearized reading' (duration: 2.000366191s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:51.302252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:49.301726Z","time spent":"2.000518376s","remote":"127.0.0.1:32872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/06 23:11:51 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-06T23:11:51.639377Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478482689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T23:11:52.140396Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478482689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T23:11:52.641238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478482689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T23:11:53.141675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478482689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T23:11:53.302279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999720461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-06T23:11:53.302365Z","caller":"traceutil/trace.go:171","msg":"trace[139776471] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99996769s","start":"2026-07-06T23:11:51.302379Z","end":"2026-07-06T23:11:53.302347Z","steps":["trace[139776471] 'agreement among raft nodes before linearized reading' (duration: 1.999846805s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:53.302413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:51.302358Z","time spent":"2.000045463s","remote":"127.0.0.1:32874","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-07-06T23:11:53.642782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312529478482689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T23:11:54.110894Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.473326642s","expected-duration":"1s"} {"level":"info","ts":"2026-07-06T23:11:54.111628Z","caller":"traceutil/trace.go:171","msg":"trace[2091913466] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"4.517423091s","start":"2026-07-06T23:11:49.594168Z","end":"2026-07-06T23:11:54.111591Z","steps":["trace[2091913466] 'process raft request' (duration: 4.517023325s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:54.111864Z","caller":"traceutil/trace.go:171","msg":"trace[2017906501] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"4.516273596s","start":"2026-07-06T23:11:49.595562Z","end":"2026-07-06T23:11:54.111836Z","steps":["trace[2017906501] 'process raft request' (duration: 4.515808008s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:54.112017Z","caller":"traceutil/trace.go:171","msg":"trace[456907760] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"4.516056328s","start":"2026-07-06T23:11:49.595951Z","end":"2026-07-06T23:11:54.112007Z","steps":["trace[456907760] 'process raft request' (duration: 4.515813429s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:54.112356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:49.595545Z","time spent":"4.516678402s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:11:54.112474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:49.595941Z","time spent":"4.51637266s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:11:54.112199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:49.594146Z","time spent":"4.517974851s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:11:54.145167Z","caller":"traceutil/trace.go:171","msg":"trace[1767189320] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"2.502114491s","start":"2026-07-06T23:11:51.643034Z","end":"2026-07-06T23:11:54.145149Z","steps":["trace[1767189320] 'process raft request' (duration: 2.502037968s)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:54.145209Z","caller":"traceutil/trace.go:171","msg":"trace[108141993] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"4.502392744s","start":"2026-07-06T23:11:49.642783Z","end":"2026-07-06T23:11:54.145176Z","steps":["trace[108141993] 'process raft request' (duration: 4.502175545s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:54.145266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:51.643002Z","time spent":"2.502215295s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:11:54.145372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:49.642762Z","time spent":"4.502528149s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:11:54.145508Z","caller":"traceutil/trace.go:171","msg":"trace[354527320] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1428; }","duration":"4.507606093s","start":"2026-07-06T23:11:49.637841Z","end":"2026-07-06T23:11:54.145448Z","steps":["trace[354527320] 'read index received' (duration: 4.473285001s)","trace[354527320] 'applied index is now lower than readState.Index' (duration: 34.319752ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:11:54.145612Z","caller":"traceutil/trace.go:171","msg":"trace[1894290438] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"839.706199ms","start":"2026-07-06T23:11:53.305894Z","end":"2026-07-06T23:11:54.1456Z","steps":["trace[1894290438] 'process raft request' (duration: 839.21817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:54.145671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:53.305873Z","time spent":"839.771311ms","remote":"127.0.0.1:32964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T23:11:54.201365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.198454362s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-06T23:11:54.201498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.640368367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T23:11:54.201579Z","caller":"traceutil/trace.go:171","msg":"trace[1906216009] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1374; }","duration":"2.640497082s","start":"2026-07-06T23:11:51.561064Z","end":"2026-07-06T23:11:54.201561Z","steps":["trace[1906216009] 'agreement among raft nodes before linearized reading' (duration: 2.584674598s)","trace[1906216009] 'count revisions from in-memory index tree' (duration: 55.71088ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:11:54.201594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.121736407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-06T23:11:54.201604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.847113293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-06T23:11:54.201632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:51.561047Z","time spent":"2.640571795s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-07-06T23:11:54.201667Z","caller":"traceutil/trace.go:171","msg":"trace[1478891920] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1374; }","duration":"3.121873192s","start":"2026-07-06T23:11:51.079774Z","end":"2026-07-06T23:11:54.201647Z","steps":["trace[1478891920] 'agreement among raft nodes before linearized reading' (duration: 3.065971435s)","trace[1478891920] 'count revisions from in-memory index tree' (duration: 55.801104ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:11:54.20168Z","caller":"traceutil/trace.go:171","msg":"trace[442722463] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1374; }","duration":"3.847237848s","start":"2026-07-06T23:11:50.354421Z","end":"2026-07-06T23:11:54.201659Z","steps":["trace[442722463] 'agreement among raft nodes before linearized reading' (duration: 3.791368682s)","trace[442722463] 'count revisions from in-memory index tree' (duration: 55.762472ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:11:54.201735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:50.354402Z","time spent":"3.847322041s","remote":"127.0.0.1:33168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-07-06T23:11:54.201742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:51.079752Z","time spent":"3.121973496s","remote":"127.0.0.1:33372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-06T23:11:54.201505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.629769556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-07-06T23:11:54.201908Z","caller":"traceutil/trace.go:171","msg":"trace[242907343] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:1374; }","duration":"3.630192393s","start":"2026-07-06T23:11:50.571652Z","end":"2026-07-06T23:11:54.201845Z","steps":["trace[242907343] 'agreement among raft nodes before linearized reading' (duration: 3.574120659s)","trace[242907343] 'range keys from in-memory index tree' (duration: 55.594525ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:11:54.201991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.712856961s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-06T23:11:54.202071Z","caller":"traceutil/trace.go:171","msg":"trace[1928296737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1374; }","duration":"3.712992315s","start":"2026-07-06T23:11:50.489049Z","end":"2026-07-06T23:11:54.202042Z","steps":["trace[1928296737] 'agreement among raft nodes before linearized reading' (duration: 3.656735035s)","trace[1928296737] 'range keys from in-memory index tree' (duration: 56.051593ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:11:54.201996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:50.571634Z","time spent":"3.630337688s","remote":"127.0.0.1:32920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.4\" "} {"level":"warn","ts":"2026-07-06T23:11:54.202092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.643461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:11:54.201924Z","caller":"traceutil/trace.go:171","msg":"trace[889953247] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1374; }","duration":"2.19865802s","start":"2026-07-06T23:11:52.002887Z","end":"2026-07-06T23:11:54.201545Z","steps":["trace[889953247] 'agreement among raft nodes before linearized reading' (duration: 2.142844076s)","trace[889953247] 'count revisions from in-memory index tree' (duration: 55.596706ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:11:54.202224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482241522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-06T23:11:54.202219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.972472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:11:54.202274Z","caller":"traceutil/trace.go:171","msg":"trace[1619887674] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1374; }","duration":"1.482328335s","start":"2026-07-06T23:11:52.719933Z","end":"2026-07-06T23:11:54.202261Z","steps":["trace[1619887674] 'agreement among raft nodes before linearized reading' (duration: 1.425785113s)","trace[1619887674] 'count revisions from in-memory index tree' (duration: 56.475749ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:11:54.202298Z","caller":"traceutil/trace.go:171","msg":"trace[282200636] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1374; }","duration":"928.105576ms","start":"2026-07-06T23:11:53.274169Z","end":"2026-07-06T23:11:54.202274Z","steps":["trace[282200636] 'agreement among raft nodes before linearized reading' (duration: 871.541884ms)","trace[282200636] 'count revisions from in-memory index tree' (duration: 56.474889ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:11:54.202317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:52.719912Z","time spent":"1.482396158s","remote":"127.0.0.1:33086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-07-06T23:11:54.202354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:53.274144Z","time spent":"928.1993ms","remote":"127.0.0.1:33054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-06T23:11:54.202136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:50.489029Z","time spent":"3.71309564s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-06T23:11:54.202443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.603657115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T23:11:54.202327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.309669893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:11:54.202521Z","caller":"traceutil/trace.go:171","msg":"trace[259153477] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1374; }","duration":"1.603776959s","start":"2026-07-06T23:11:52.598704Z","end":"2026-07-06T23:11:54.202481Z","steps":["trace[259153477] 'agreement among raft nodes before linearized reading' (duration: 1.547020199s)","trace[259153477] 'count revisions from in-memory index tree' (duration: 56.661736ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:11:54.202561Z","caller":"traceutil/trace.go:171","msg":"trace[884597131] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1374; }","duration":"4.310611018s","start":"2026-07-06T23:11:49.891905Z","end":"2026-07-06T23:11:54.202516Z","steps":["trace[884597131] 'agreement among raft nodes before linearized reading' (duration: 4.25389087s)","trace[884597131] 'range keys from in-memory index tree' (duration: 55.760482ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:11:54.201738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.356722253s","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-07-06T23:11:54.202602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:52.598689Z","time spent":"1.603901784s","remote":"127.0.0.1:33344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-07-06T23:11:54.202626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:49.891851Z","time spent":"4.310759354s","remote":"127.0.0.1:33064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-07-06T23:11:54.202655Z","caller":"traceutil/trace.go:171","msg":"trace[329630049] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1374; }","duration":"4.357730501s","start":"2026-07-06T23:11:49.844902Z","end":"2026-07-06T23:11:54.202632Z","steps":["trace[329630049] 'agreement among raft nodes before linearized reading' (duration: 4.300743132s)","trace[329630049] 'count revisions from in-memory index tree' (duration: 55.97276ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:11:54.202731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:49.844877Z","time spent":"4.357834415s","remote":"127.0.0.1:33426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-07-06T23:11:54.202252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:52.002843Z","time spent":"2.199389638s","remote":"127.0.0.1:33440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-07-06T23:11:54.202139Z","caller":"traceutil/trace.go:171","msg":"trace[414521791] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1374; }","duration":"743.691763ms","start":"2026-07-06T23:11:53.458433Z","end":"2026-07-06T23:11:54.202125Z","steps":["trace[414521791] 'agreement among raft nodes before linearized reading' (duration: 687.262035ms)","trace[414521791] 'count revisions from in-memory index tree' (duration: 56.368495ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:11:54.203021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:53.458378Z","time spent":"744.626719ms","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-07-06T23:11:54.203563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.771705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:11:54.203623Z","caller":"traceutil/trace.go:171","msg":"trace[83106880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1374; }","duration":"893.88128ms","start":"2026-07-06T23:11:53.309724Z","end":"2026-07-06T23:11:54.203606Z","steps":["trace[83106880] 'agreement among raft nodes before linearized reading' (duration: 835.979576ms)","trace[83106880] 'range keys from in-memory index tree' (duration: 57.818271ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:11:54.203664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:53.309712Z","time spent":"893.937751ms","remote":"127.0.0.1:32872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-06T23:11:54.432454Z","caller":"traceutil/trace.go:171","msg":"trace[220682340] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"224.804445ms","start":"2026-07-06T23:11:54.207626Z","end":"2026-07-06T23:11:54.43243Z","steps":["trace[220682340] 'process raft request' (duration: 224.608737ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:54.432654Z","caller":"traceutil/trace.go:171","msg":"trace[1527236174] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"210.318052ms","start":"2026-07-06T23:11:54.222322Z","end":"2026-07-06T23:11:54.43264Z","steps":["trace[1527236174] 'process raft request' (duration: 210.066681ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:54.432639Z","caller":"traceutil/trace.go:171","msg":"trace[763328552] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1434; }","duration":"224.605038ms","start":"2026-07-06T23:11:54.208009Z","end":"2026-07-06T23:11:54.432614Z","steps":["trace[763328552] 'read index received' (duration: 33.393507ms)","trace[763328552] 'applied index is now lower than readState.Index' (duration: 191.20411ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:11:54.432731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.701562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:11:54.432765Z","caller":"traceutil/trace.go:171","msg":"trace[267138969] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1376; }","duration":"224.778624ms","start":"2026-07-06T23:11:54.20798Z","end":"2026-07-06T23:11:54.432758Z","steps":["trace[267138969] 'agreement among raft nodes before linearized reading' (duration: 224.699461ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:54.615412Z","caller":"traceutil/trace.go:171","msg":"trace[421338870] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"372.970949ms","start":"2026-07-06T23:11:54.242412Z","end":"2026-07-06T23:11:54.615383Z","steps":["trace[421338870] 'process raft request' (duration: 370.357439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:11:54.61556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:11:54.242403Z","time spent":"373.080524ms","remote":"127.0.0.1:32920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:11:58.297546Z","caller":"traceutil/trace.go:171","msg":"trace[1488968849] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"117.148276ms","start":"2026-07-06T23:11:58.180377Z","end":"2026-07-06T23:11:58.297525Z","steps":["trace[1488968849] 'process raft request' (duration: 116.99408ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:58.297856Z","caller":"traceutil/trace.go:171","msg":"trace[461226558] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"115.518042ms","start":"2026-07-06T23:11:58.182313Z","end":"2026-07-06T23:11:58.297831Z","steps":["trace[461226558] 'process raft request' (duration: 115.18342ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:11:58.298062Z","caller":"traceutil/trace.go:171","msg":"trace[1683676198] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"114.517915ms","start":"2026-07-06T23:11:58.183514Z","end":"2026-07-06T23:11:58.298032Z","steps":["trace[1683676198] 'process raft request' (duration: 114.178642ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:12:02.470886Z","caller":"traceutil/trace.go:171","msg":"trace[1979579017] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"102.058971ms","start":"2026-07-06T23:12:02.368794Z","end":"2026-07-06T23:12:02.470853Z","steps":["trace[1979579017] 'process raft request' (duration: 19.324644ms)","trace[1979579017] 'compare' (duration: 82.582921ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:12:02.480935Z","caller":"traceutil/trace.go:171","msg":"trace[1451575349] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"112.058165ms","start":"2026-07-06T23:12:02.368842Z","end":"2026-07-06T23:12:02.4809Z","steps":["trace[1451575349] 'process raft request' (duration: 111.856928ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:12:02.481054Z","caller":"traceutil/trace.go:171","msg":"trace[519275800] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"108.701706ms","start":"2026-07-06T23:12:02.372335Z","end":"2026-07-06T23:12:02.481037Z","steps":["trace[519275800] 'process raft request' (duration: 108.522989ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:12:04.647143Z","caller":"traceutil/trace.go:171","msg":"trace[1333912542] linearizableReadLoop","detail":"{readStateIndex:1476; appliedIndex:1474; }","duration":"143.017507ms","start":"2026-07-06T23:12:04.504095Z","end":"2026-07-06T23:12:04.647113Z","steps":["trace[1333912542] 'read index received' (duration: 821.822µs)","trace[1333912542] 'applied index is now lower than readState.Index' (duration: 142.194415ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:12:04.647345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.072839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" ","response":"range_response_count:1 size:684"} {"level":"warn","ts":"2026-07-06T23:12:04.647377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.228125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" ","response":"range_response_count:1 size:726"} {"level":"info","ts":"2026-07-06T23:12:04.647452Z","caller":"traceutil/trace.go:171","msg":"trace[82144928] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver; range_end:; response_count:1; response_revision:1412; }","duration":"143.219724ms","start":"2026-07-06T23:12:04.504214Z","end":"2026-07-06T23:12:04.647433Z","steps":["trace[82144928] 'agreement among raft nodes before linearized reading' (duration: 143.056328ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:12:04.647505Z","caller":"traceutil/trace.go:171","msg":"trace[77840435] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver; range_end:; response_count:1; response_revision:1412; }","duration":"143.420492ms","start":"2026-07-06T23:12:04.504062Z","end":"2026-07-06T23:12:04.647482Z","steps":["trace[77840435] 'agreement among raft nodes before linearized reading' (duration: 143.150382ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:12:04.64788Z","caller":"traceutil/trace.go:171","msg":"trace[1082833248] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"143.822647ms","start":"2026-07-06T23:12:04.504028Z","end":"2026-07-06T23:12:04.647851Z","steps":["trace[1082833248] 'process raft request' (duration: 142.167183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:12:10.634069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.800005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-06T23:12:10.63418Z","caller":"traceutil/trace.go:171","msg":"trace[1481551910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1429; }","duration":"101.9275ms","start":"2026-07-06T23:12:10.53223Z","end":"2026-07-06T23:12:10.634157Z","steps":["trace[1481551910] 'range keys from in-memory index tree' (duration: 101.618678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:12:10.634482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.101408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:persistent-volume-provisioner\" ","response":"range_response_count:1 size:812"} {"level":"info","ts":"2026-07-06T23:12:10.634516Z","caller":"traceutil/trace.go:171","msg":"trace[337419302] range","detail":"{range_begin:/registry/clusterroles/system:persistent-volume-provisioner; range_end:; response_count:1; response_revision:1429; }","duration":"157.14764ms","start":"2026-07-06T23:12:10.477361Z","end":"2026-07-06T23:12:10.634508Z","steps":["trace[337419302] 'range keys from in-memory index tree' (duration: 157.057017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:12:10.634698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.551243ms","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-07-06T23:12:10.634713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.910658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:1 size:743"} {"level":"info","ts":"2026-07-06T23:12:10.634776Z","caller":"traceutil/trace.go:171","msg":"trace[335025323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1429; }","duration":"103.689428ms","start":"2026-07-06T23:12:10.531067Z","end":"2026-07-06T23:12:10.634756Z","steps":["trace[335025323] 'range keys from in-memory index tree' (duration: 103.360475ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:12:10.634778Z","caller":"traceutil/trace.go:171","msg":"trace[1565436836] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:1; response_revision:1429; }","duration":"160.012661ms","start":"2026-07-06T23:12:10.474749Z","end":"2026-07-06T23:12:10.634762Z","steps":["trace[1565436836] 'range keys from in-memory index tree' (duration: 159.852175ms)"],"step_count":1}