{"level":"warn","ts":"2026-04-21T17:40:11.038968Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T17:40:11.039062Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-21T17:40:11.039132Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T17:40:11.039144Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-21T17:40:11.039161Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-21T17:40:11.040097Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-04-21T17:40:11.040229Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-21T17:40:11.05248Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.935532ms"} {"level":"info","ts":"2026-04-21T17:40:11.0612Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-04-21T17:40:11.061334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T17:40:11.061376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-04-21T17:40:11.061417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T17:40:11.061446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-04-21T17:40:11.061507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-04-21T17:40:11.073034Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T17:40:11.074528Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T17:40:11.075911Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-21T17:40:11.078061Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T17:40:11.078259Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T17:40:11.078507Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T17:40:11.078635Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T17:40:11.07865Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T17:40:11.08045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-04-21T17:40:11.081429Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-21T17:40:11.083569Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-21T17:40:11.083742Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-21T17:40:11.08413Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-21T17:40:11.084216Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T17:40:11.08404Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T17:40:11.364103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T17:40:11.364226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T17:40:11.364316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-04-21T17:40:11.364361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-04-21T17:40:11.364382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-21T17:40:11.364405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-04-21T17:40:11.364427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-21T17:40:11.365185Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T17:40:11.365309Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:40:11.365349Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T17:40:11.365352Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T17:40:11.365528Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T17:40:11.365626Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T17:40:11.366116Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:40:11.366223Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:40:11.366311Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:40:11.369126Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T17:40:11.376187Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"warn","ts":"2026-04-21T17:40:16.408349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.9827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-04-21T17:40:16.408511Z","caller":"traceutil/trace.go:171","msg":"trace[2000282918] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:210; }","duration":"637.204935ms","start":"2026-04-21T17:40:15.771284Z","end":"2026-04-21T17:40:16.408489Z","steps":["trace[2000282918] 'range keys from in-memory index tree' (duration: 636.879088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:16.408599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:15.771263Z","time spent":"637.321238ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3732,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-21T17:40:16.7543Z","caller":"traceutil/trace.go:171","msg":"trace[192199723] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"337.582502ms","start":"2026-04-21T17:40:16.416685Z","end":"2026-04-21T17:40:16.754268Z","steps":["trace[192199723] 'process raft request' (duration: 337.430348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:16.754984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:16.416658Z","time spent":"337.728995ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:40:41.370689Z","caller":"traceutil/trace.go:171","msg":"trace[387836116] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"322.926429ms","start":"2026-04-21T17:40:41.047738Z","end":"2026-04-21T17:40:41.370664Z","steps":["trace[387836116] 'read index received' (duration: 322.788746ms)","trace[387836116] 'applied index is now lower than readState.Index' (duration: 136.543µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:40:41.370766Z","caller":"traceutil/trace.go:171","msg":"trace[1719046456] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"340.122597ms","start":"2026-04-21T17:40:41.030598Z","end":"2026-04-21T17:40:41.370721Z","steps":["trace[1719046456] 'process raft request' (duration: 339.938953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:41.37086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.771189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:40:41.370889Z","caller":"traceutil/trace.go:171","msg":"trace[2023269125] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:464; }","duration":"231.82886ms","start":"2026-04-21T17:40:41.139051Z","end":"2026-04-21T17:40:41.37088Z","steps":["trace[2023269125] 'agreement among raft nodes before linearized reading' (duration: 231.750929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:41.370904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.129533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T17:40:41.370981Z","caller":"traceutil/trace.go:171","msg":"trace[803236448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"323.261615ms","start":"2026-04-21T17:40:41.0477Z","end":"2026-04-21T17:40:41.370962Z","steps":["trace[803236448] 'agreement among raft nodes before linearized reading' (duration: 323.096312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:41.370975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:41.030574Z","time spent":"340.30976ms","remote":"127.0.0.1:59008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:40:41.371032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:41.047681Z","time spent":"323.339077ms","remote":"127.0.0.1:59008","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-04-21T17:40:41.371137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.462508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-21T17:40:41.371162Z","caller":"traceutil/trace.go:171","msg":"trace[1107705238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:464; }","duration":"131.57701ms","start":"2026-04-21T17:40:41.239579Z","end":"2026-04-21T17:40:41.371156Z","steps":["trace[1107705238] 'agreement among raft nodes before linearized reading' (duration: 131.528159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:41.675526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.700242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:40:41.675594Z","caller":"traceutil/trace.go:171","msg":"trace[1146013417] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:466; }","duration":"150.842166ms","start":"2026-04-21T17:40:41.524735Z","end":"2026-04-21T17:40:41.675577Z","steps":["trace[1146013417] 'range keys from in-memory index tree' (duration: 150.522738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:41.675649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.368806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:40:41.675701Z","caller":"traceutil/trace.go:171","msg":"trace[407745477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"180.453058ms","start":"2026-04-21T17:40:41.495232Z","end":"2026-04-21T17:40:41.675685Z","steps":["trace[407745477] 'range keys from in-memory index tree' (duration: 180.282024ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:40:42.557585Z","caller":"traceutil/trace.go:171","msg":"trace[213436698] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"108.998473ms","start":"2026-04-21T17:40:42.448537Z","end":"2026-04-21T17:40:42.557535Z","steps":["trace[213436698] 'process raft request' (duration: 100.531546ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:40:44.809794Z","caller":"traceutil/trace.go:171","msg":"trace[1142764017] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"390.773424ms","start":"2026-04-21T17:40:44.418996Z","end":"2026-04-21T17:40:44.809769Z","steps":["trace[1142764017] 'process raft request' (duration: 356.754293ms)","trace[1142764017] 'compare' (duration: 33.732644ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:40:44.810022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:44.418982Z","time spent":"390.967169ms","remote":"127.0.0.1:58750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:40:48.677499Z","caller":"traceutil/trace.go:171","msg":"trace[421815196] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"329.512236ms","start":"2026-04-21T17:40:48.347959Z","end":"2026-04-21T17:40:48.677472Z","steps":["trace[421815196] 'read index received' (duration: 328.532022ms)","trace[421815196] 'applied index is now lower than readState.Index' (duration: 978.984µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:40:48.677579Z","caller":"traceutil/trace.go:171","msg":"trace[1035263794] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"361.498238ms","start":"2026-04-21T17:40:48.316046Z","end":"2026-04-21T17:40:48.677544Z","steps":["trace[1035263794] 'process raft request' (duration: 360.841602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:48.677749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:48.315992Z","time spent":"361.662242ms","remote":"127.0.0.1:58814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:40:48.677873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.247625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:40:48.677919Z","caller":"traceutil/trace.go:171","msg":"trace[478905822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"183.50339ms","start":"2026-04-21T17:40:48.494403Z","end":"2026-04-21T17:40:48.677906Z","steps":["trace[478905822] 'agreement among raft nodes before linearized reading' (duration: 183.275185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:48.684689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.320141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rsxs5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-21T17:40:48.684889Z","caller":"traceutil/trace.go:171","msg":"trace[1699392207] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rsxs5; range_end:; response_count:1; response_revision:574; }","duration":"336.947238ms","start":"2026-04-21T17:40:48.347918Z","end":"2026-04-21T17:40:48.684866Z","steps":["trace[1699392207] 'agreement among raft nodes before linearized reading' (duration: 329.823464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:48.684971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:48.347905Z","time spent":"337.034661ms","remote":"127.0.0.1:58928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-rsxs5\" "} {"level":"info","ts":"2026-04-21T17:40:48.946075Z","caller":"traceutil/trace.go:171","msg":"trace[79008290] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"249.222376ms","start":"2026-04-21T17:40:48.696826Z","end":"2026-04-21T17:40:48.946049Z","steps":["trace[79008290] 'process raft request' (duration: 249.002751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:50.190098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180046037s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:40:50.190317Z","caller":"traceutil/trace.go:171","msg":"trace[1440761776] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"1.443320787s","start":"2026-04-21T17:40:48.746955Z","end":"2026-04-21T17:40:50.190276Z","steps":["trace[1440761776] 'process raft request' (duration: 262.87188ms)","trace[1440761776] 'compare' (duration: 1.179882983s)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:40:50.190472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:48.746901Z","time spent":"1.4434894s","remote":"127.0.0.1:59218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T17:40:50.190524Z","caller":"traceutil/trace.go:171","msg":"trace[2052164893] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"1.237586582s","start":"2026-04-21T17:40:48.95286Z","end":"2026-04-21T17:40:50.190447Z","steps":["trace[2052164893] 'read index received' (duration: 56.92317ms)","trace[2052164893] 'applied index is now lower than readState.Index' (duration: 1.180659462s)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:40:50.205164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.784728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-21T17:40:50.205186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.685697ms","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-04-21T17:40:50.205162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.779258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:40:50.205222Z","caller":"traceutil/trace.go:171","msg":"trace[423114339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"755.867829ms","start":"2026-04-21T17:40:49.449341Z","end":"2026-04-21T17:40:50.205209Z","steps":["trace[423114339] 'agreement among raft nodes before linearized reading' (duration: 755.699925ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:40:50.205246Z","caller":"traceutil/trace.go:171","msg":"trace[144160613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"710.900792ms","start":"2026-04-21T17:40:49.494326Z","end":"2026-04-21T17:40:50.205226Z","steps":["trace[144160613] 'agreement among raft nodes before linearized reading' (duration: 710.753198ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:40:50.205246Z","caller":"traceutil/trace.go:171","msg":"trace[546055916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"785.78218ms","start":"2026-04-21T17:40:49.419434Z","end":"2026-04-21T17:40:50.205216Z","steps":["trace[546055916] 'agreement among raft nodes before linearized reading' (duration: 785.624657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:50.205258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:49.449327Z","time spent":"755.92359ms","remote":"127.0.0.1:59008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T17:40:50.205279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:49.419404Z","time spent":"785.867732ms","remote":"127.0.0.1:59008","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-04-21T17:40:50.205176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.466584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-21T17:40:50.205302Z","caller":"traceutil/trace.go:171","msg":"trace[505173801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"756.641499ms","start":"2026-04-21T17:40:49.448654Z","end":"2026-04-21T17:40:50.205295Z","steps":["trace[505173801] 'agreement among raft nodes before linearized reading' (duration: 756.401893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:50.205278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:49.494314Z","time spent":"710.956423ms","remote":"127.0.0.1:58706","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-04-21T17:40:50.205324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:49.448628Z","time spent":"756.68976ms","remote":"127.0.0.1:59008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T17:40:50.205522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252661411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T17:40:50.205626Z","caller":"traceutil/trace.go:171","msg":"trace[1120383901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"1.252808316s","start":"2026-04-21T17:40:48.952801Z","end":"2026-04-21T17:40:50.205609Z","steps":["trace[1120383901] 'agreement among raft nodes before linearized reading' (duration: 1.237950972s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:50.205675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:48.952778Z","time spent":"1.252887537s","remote":"127.0.0.1:59008","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-04-21T17:41:06.002866Z","caller":"traceutil/trace.go:171","msg":"trace[1453093026] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"467.547222ms","start":"2026-04-21T17:41:05.535286Z","end":"2026-04-21T17:41:06.002834Z","steps":["trace[1453093026] 'process raft request' (duration: 467.368167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:06.003051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:05.53527Z","time spent":"467.687845ms","remote":"127.0.0.1:59008","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-04-21T17:41:40.09844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.12545ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-21T17:41:40.098855Z","caller":"traceutil/trace.go:171","msg":"trace[2100101220] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:990; }","duration":"347.047503ms","start":"2026-04-21T17:41:39.751787Z","end":"2026-04-21T17:41:40.098835Z","steps":["trace[2100101220] 'read index received' (duration: 161.13302ms)","trace[2100101220] 'applied index is now lower than readState.Index' (duration: 185.913523ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:41:40.098942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.147545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T17:41:40.09897Z","caller":"traceutil/trace.go:171","msg":"trace[895586565] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:954; }","duration":"347.186446ms","start":"2026-04-21T17:41:39.75177Z","end":"2026-04-21T17:41:40.098957Z","steps":["trace[895586565] 'agreement among raft nodes before linearized reading' (duration: 347.112164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:40.098998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:39.751741Z","time spent":"347.249558ms","remote":"127.0.0.1:59066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-04-21T17:41:40.100228Z","caller":"traceutil/trace.go:171","msg":"trace[1506860963] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"412.007873ms","start":"2026-04-21T17:41:39.687002Z","end":"2026-04-21T17:41:40.09901Z","steps":["trace[1506860963] 'process raft request' (duration: 411.684985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:40.100416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:39.68698Z","time spent":"413.348036ms","remote":"127.0.0.1:59008","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-04-21T17:42:05.447378Z","caller":"traceutil/trace.go:171","msg":"trace[251132651] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"431.962897ms","start":"2026-04-21T17:42:05.015392Z","end":"2026-04-21T17:42:05.447355Z","steps":["trace[251132651] 'process raft request' (duration: 431.817113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:05.448318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:05.01536Z","time spent":"432.86301ms","remote":"127.0.0.1:59008","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-04-21T17:42:20.193849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.389504ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-21T17:42:20.194542Z","caller":"traceutil/trace.go:171","msg":"trace[1540241989] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"341.530803ms","start":"2026-04-21T17:42:19.852978Z","end":"2026-04-21T17:42:20.194509Z","steps":["trace[1540241989] 'process raft request' (duration: 341.317588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:20.194769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:19.852953Z","time spent":"341.716918ms","remote":"127.0.0.1:59008","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-04-21T17:42:36.645781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.162335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:42:36.645875Z","caller":"traceutil/trace.go:171","msg":"trace[378074151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1209; }","duration":"152.286408ms","start":"2026-04-21T17:42:36.493571Z","end":"2026-04-21T17:42:36.645858Z","steps":["trace[378074151] 'range keys from in-memory index tree' (duration: 152.056952ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:42:36.712018Z","caller":"traceutil/trace.go:171","msg":"trace[1405921690] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"215.20775ms","start":"2026-04-21T17:42:36.496779Z","end":"2026-04-21T17:42:36.711986Z","steps":["trace[1405921690] 'process raft request' (duration: 214.258067ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:42:36.838566Z","caller":"traceutil/trace.go:171","msg":"trace[1996163832] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"121.433223ms","start":"2026-04-21T17:42:36.717112Z","end":"2026-04-21T17:42:36.838546Z","steps":["trace[1996163832] 'process raft request' (duration: 121.250708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:37.624701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.184741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:42:37.624843Z","caller":"traceutil/trace.go:171","msg":"trace[1754635137] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"347.58258ms","start":"2026-04-21T17:42:37.277238Z","end":"2026-04-21T17:42:37.624821Z","steps":["trace[1754635137] 'compare' (duration: 338.002857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:37.624906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:37.277214Z","time spent":"347.671963ms","remote":"127.0.0.1:59008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T17:42:38.154401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959987445579667,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T17:42:38.165381Z","caller":"traceutil/trace.go:171","msg":"trace[1132212597] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"526.316447ms","start":"2026-04-21T17:42:37.639035Z","end":"2026-04-21T17:42:38.165352Z","steps":["trace[1132212597] 'process raft request' (duration: 526.136902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:38.16556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:37.639021Z","time spent":"526.453471ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T17:42:38.525436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.67817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:42:38.525964Z","caller":"traceutil/trace.go:171","msg":"trace[298181124] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"110.421193ms","start":"2026-04-21T17:42:38.415516Z","end":"2026-04-21T17:42:38.525938Z","steps":["trace[298181124] 'process raft request' (duration: 110.28631ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:42:38.526037Z","caller":"traceutil/trace.go:171","msg":"trace[1983151307] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"886.788754ms","start":"2026-04-21T17:42:37.639202Z","end":"2026-04-21T17:42:38.525991Z","steps":["trace[1983151307] 'process raft request' (duration: 726.430516ms)","trace[1983151307] 'compare' (duration: 159.382613ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:42:38.525984Z","caller":"traceutil/trace.go:171","msg":"trace[1416821400] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1271; }","duration":"872.105624ms","start":"2026-04-21T17:42:37.653851Z","end":"2026-04-21T17:42:38.525957Z","steps":["trace[1416821400] 'read index received' (duration: 511.365712ms)","trace[1416821400] 'applied index is now lower than readState.Index' (duration: 360.738142ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:42:38.526126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.246538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:42:38.52617Z","caller":"traceutil/trace.go:171","msg":"trace[88561018] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1223; }","duration":"872.35417ms","start":"2026-04-21T17:42:37.6538Z","end":"2026-04-21T17:42:38.526155Z","steps":["trace[88561018] 'agreement among raft nodes before linearized reading' (duration: 872.236078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:38.526203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:37.653784Z","time spent":"872.411442ms","remote":"127.0.0.1:46034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-21T17:42:38.526227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:37.639166Z","time spent":"886.954318ms","remote":"127.0.0.1:59008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:42:39.271998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.027353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:42:39.272408Z","caller":"traceutil/trace.go:171","msg":"trace[1639380395] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"375.635758ms","start":"2026-04-21T17:42:38.896742Z","end":"2026-04-21T17:42:39.272378Z","steps":["trace[1639380395] 'process raft request' (duration: 167.118703ms)","trace[1639380395] 'compare' (duration: 207.344147ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:42:39.272442Z","caller":"traceutil/trace.go:171","msg":"trace[874484774] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"373.376602ms","start":"2026-04-21T17:42:38.899027Z","end":"2026-04-21T17:42:39.272404Z","steps":["trace[874484774] 'process raft request' (duration: 373.280929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:39.272503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:38.896732Z","time spent":"375.72629ms","remote":"127.0.0.1:59008","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-04-21T17:42:39.272542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:38.899016Z","time spent":"373.471845ms","remote":"127.0.0.1:59008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:42:39.734221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.980716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:42:39.734315Z","caller":"traceutil/trace.go:171","msg":"trace[149481193] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"240.082739ms","start":"2026-04-21T17:42:39.494216Z","end":"2026-04-21T17:42:39.734299Z","steps":["trace[149481193] 'read index received' (duration: 54.261µs)","trace[149481193] 'applied index is now lower than readState.Index' (duration: 240.027568ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:42:39.734375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.174431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:42:39.73439Z","caller":"traceutil/trace.go:171","msg":"trace[1096579396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"240.235783ms","start":"2026-04-21T17:42:39.494147Z","end":"2026-04-21T17:42:39.734383Z","steps":["trace[1096579396] 'agreement among raft nodes before linearized reading' (duration: 240.189302ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:42:39.734394Z","caller":"traceutil/trace.go:171","msg":"trace[1962758070] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"810.523766ms","start":"2026-04-21T17:42:38.923832Z","end":"2026-04-21T17:42:39.734355Z","steps":["trace[1962758070] 'process raft request' (duration: 492.315174ms)","trace[1962758070] 'compare' (duration: 317.6958ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:42:39.734586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:38.923797Z","time spent":"810.68262ms","remote":"127.0.0.1:59008","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-04-21T17:42:51.213601Z","caller":"traceutil/trace.go:171","msg":"trace[1339873086] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"179.987098ms","start":"2026-04-21T17:42:51.033584Z","end":"2026-04-21T17:42:51.213571Z","steps":["trace[1339873086] 'process raft request' (duration: 179.822084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:51.825981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959987445579837,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T17:42:52.111286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.581815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:42:52.111436Z","caller":"traceutil/trace.go:171","msg":"trace[679478680] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"1.077316759s","start":"2026-04-21T17:42:51.034094Z","end":"2026-04-21T17:42:52.111411Z","steps":["trace[679478680] 'process raft request' (duration: 913.476798ms)","trace[679478680] 'compare' (duration: 163.133744ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:42:52.111506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:51.034073Z","time spent":"1.077400601s","remote":"127.0.0.1:59008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:42:52.111959Z","caller":"traceutil/trace.go:171","msg":"trace[619653846] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"695.367136ms","start":"2026-04-21T17:42:51.416574Z","end":"2026-04-21T17:42:52.111941Z","steps":["trace[619653846] 'process raft request' (duration: 695.065419ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:42:52.111965Z","caller":"traceutil/trace.go:171","msg":"trace[883623140] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1359; }","duration":"786.609781ms","start":"2026-04-21T17:42:51.325307Z","end":"2026-04-21T17:42:52.111917Z","steps":["trace[883623140] 'read index received' (duration: 622.332129ms)","trace[883623140] 'applied index is now lower than readState.Index' (duration: 164.276722ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:42:52.112038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:51.416546Z","time spent":"695.452929ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6801,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:42:52.112204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.898618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-21T17:42:52.112242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.244433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-21T17:42:52.112269Z","caller":"traceutil/trace.go:171","msg":"trace[1201436945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1307; }","duration":"787.006491ms","start":"2026-04-21T17:42:51.32524Z","end":"2026-04-21T17:42:52.112247Z","steps":["trace[1201436945] 'agreement among raft nodes before linearized reading' (duration: 786.771485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:52.112295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.239047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-21T17:42:52.112296Z","caller":"traceutil/trace.go:171","msg":"trace[1909784302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1307; }","duration":"784.334724ms","start":"2026-04-21T17:42:51.327947Z","end":"2026-04-21T17:42:52.112281Z","steps":["trace[1909784302] 'agreement among raft nodes before linearized reading' (duration: 784.14178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:52.112303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:51.32517Z","time spent":"787.125774ms","remote":"127.0.0.1:59008","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-04-21T17:42:52.112321Z","caller":"traceutil/trace.go:171","msg":"trace[901990688] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1307; }","duration":"192.307448ms","start":"2026-04-21T17:42:51.920003Z","end":"2026-04-21T17:42:52.11231Z","steps":["trace[901990688] 'agreement among raft nodes before linearized reading' (duration: 192.245937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:52.11233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:51.327936Z","time spent":"784.385496ms","remote":"127.0.0.1:59008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T17:42:52.112185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.97163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:42:52.112392Z","caller":"traceutil/trace.go:171","msg":"trace[1219682574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"270.247237ms","start":"2026-04-21T17:42:51.842138Z","end":"2026-04-21T17:42:52.112385Z","steps":["trace[1219682574] 'agreement among raft nodes before linearized reading' (duration: 269.987791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:52.112471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.975976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:42:52.112497Z","caller":"traceutil/trace.go:171","msg":"trace[916420446] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1307; }","duration":"327.052127ms","start":"2026-04-21T17:42:51.785436Z","end":"2026-04-21T17:42:52.112488Z","steps":["trace[916420446] 'agreement among raft nodes before linearized reading' (duration: 327.009446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:52.112499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.458502ms","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-04-21T17:42:52.112514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:51.785411Z","time spent":"327.098348ms","remote":"127.0.0.1:46060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-04-21T17:42:52.112539Z","caller":"traceutil/trace.go:171","msg":"trace[1060326879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1307; }","duration":"319.525893ms","start":"2026-04-21T17:42:51.792999Z","end":"2026-04-21T17:42:52.112525Z","steps":["trace[1060326879] 'agreement among raft nodes before linearized reading' (duration: 319.348019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:52.112574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:51.792985Z","time spent":"319.580685ms","remote":"127.0.0.1:59008","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-04-21T17:46:14.076459Z","caller":"traceutil/trace.go:171","msg":"trace[1025141845] transaction","detail":"{read_only:false; response_revision:2265; number_of_response:1; }","duration":"100.825535ms","start":"2026-04-21T17:46:13.975608Z","end":"2026-04-21T17:46:14.076433Z","steps":["trace[1025141845] 'process raft request' (duration: 100.665842ms)"],"step_count":1}