{"level":"warn","ts":"2026-07-07T13:03:02.674436Z","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-07T13:03:02.674538Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-07T13:03:02.674605Z","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-07T13:03:02.674618Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-07-07T13:03:02.674637Z","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-07T13:03:02.675342Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-07-07T13:03:02.67542Z","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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-07T13:03:02.686983Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.278858ms"} {"level":"info","ts":"2026-07-07T13:03:02.699348Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-07-07T13:03:02.699473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-07-07T13:03:02.699512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-07-07T13:03:02.699637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-07T13:03:02.699713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-07-07T13:03:02.699942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-07-07T13:03:02.70869Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-07T13:03:02.712499Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-07T13:03:02.71453Z","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-07T13:03:02.717569Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-07T13:03:02.717915Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-07T13:03:02.718235Z","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-07T13:03:02.718413Z","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-07T13:03:02.718438Z","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-07T13:03:02.71935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-07-07T13:03:02.719602Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-07-07T13:03:02.721578Z","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-07T13:03:02.721828Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-07T13:03:02.721858Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-07T13:03:02.723021Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-07-07T13:03:02.723092Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-07-07T13:03:03.301266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-07-07T13:03:03.301327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-07T13:03:03.301349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-07-07T13:03:03.301362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-07-07T13:03:03.301367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-07-07T13:03:03.301375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-07-07T13:03:03.301383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-07-07T13:03:03.302831Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-07T13:03:03.30287Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T13:03:03.302917Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T13:03:03.303169Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T13:03:03.303597Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-07T13:03:03.303678Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-07T13:03:03.304667Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T13:03:03.304839Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T13:03:03.30489Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T13:03:03.308791Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-07T13:03:03.318983Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-07-07T13:03:07.296954Z","caller":"traceutil/trace.go:171","msg":"trace[981999214] transaction","detail":"{read_only:false; response_revision:172; number_of_response:1; }","duration":"160.634161ms","start":"2026-07-07T13:03:07.136286Z","end":"2026-07-07T13:03:07.296921Z","steps":["trace[981999214] 'process raft request' (duration: 103.318409ms)","trace[981999214] 'compare' (duration: 57.135678ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T13:03:08.028786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.443904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T13:03:08.028883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.224808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-07T13:03:08.028919Z","caller":"traceutil/trace.go:171","msg":"trace[2113664257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:186; }","duration":"130.619417ms","start":"2026-07-07T13:03:07.898276Z","end":"2026-07-07T13:03:08.028895Z","steps":["trace[2113664257] 'range keys from in-memory index tree' (duration: 130.336912ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:08.028981Z","caller":"traceutil/trace.go:171","msg":"trace[716162793] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:186; }","duration":"159.368772ms","start":"2026-07-07T13:03:07.869586Z","end":"2026-07-07T13:03:08.028955Z","steps":["trace[716162793] 'range keys from in-memory index tree' (duration: 159.079305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:09.114492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.084629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T13:03:09.114843Z","caller":"traceutil/trace.go:171","msg":"trace[790754935] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"332.046327ms","start":"2026-07-07T13:03:08.782774Z","end":"2026-07-07T13:03:09.11482Z","steps":["trace[790754935] 'process raft request' (duration: 331.963615ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:09.115112Z","caller":"traceutil/trace.go:171","msg":"trace[2008298267] linearizableReadLoop","detail":"{readStateIndex:226; appliedIndex:225; }","duration":"415.441928ms","start":"2026-07-07T13:03:08.699639Z","end":"2026-07-07T13:03:09.115081Z","steps":["trace[2008298267] 'read index received' (duration: 270.034ms)","trace[2008298267] 'applied index is now lower than readState.Index' (duration: 145.404878ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T13:03:09.115191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:03:08.782731Z","time spent":"332.388414ms","remote":"127.0.0.1:59674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T13:03:09.115423Z","caller":"traceutil/trace.go:171","msg":"trace[757254348] transaction","detail":"{read_only:false; response_revision:220; number_of_response:1; }","duration":"422.043879ms","start":"2026-07-07T13:03:08.693363Z","end":"2026-07-07T13:03:09.115407Z","steps":["trace[757254348] 'process raft request' (duration: 276.288333ms)","trace[757254348] 'compare' (duration: 143.959006ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T13:03:09.115406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.712395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T13:03:09.115577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:03:08.693339Z","time spent":"422.175681ms","remote":"127.0.0.1:59552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T13:03:09.115754Z","caller":"traceutil/trace.go:171","msg":"trace[693193786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:221; }","duration":"416.117684ms","start":"2026-07-07T13:03:08.699606Z","end":"2026-07-07T13:03:09.115723Z","steps":["trace[693193786] 'agreement among raft nodes before linearized reading' (duration: 415.592802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:09.115832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:03:08.699592Z","time spent":"416.226686ms","remote":"127.0.0.1:59752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-07T13:03:09.297245Z","caller":"traceutil/trace.go:171","msg":"trace[1519009479] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"110.390433ms","start":"2026-07-07T13:03:09.186805Z","end":"2026-07-07T13:03:09.297196Z","steps":["trace[1519009479] 'process raft request' (duration: 29.275084ms)","trace[1519009479] 'compare' (duration: 80.837143ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:03:09.297338Z","caller":"traceutil/trace.go:171","msg":"trace[1416137287] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"105.169985ms","start":"2026-07-07T13:03:09.192143Z","end":"2026-07-07T13:03:09.297313Z","steps":["trace[1416137287] 'process raft request' (duration: 104.94561ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:09.297399Z","caller":"traceutil/trace.go:171","msg":"trace[1518929303] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"104.889098ms","start":"2026-07-07T13:03:09.192486Z","end":"2026-07-07T13:03:09.297375Z","steps":["trace[1518929303] 'process raft request' (duration: 104.675283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:09.297622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.462356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-07-07T13:03:09.297238Z","caller":"traceutil/trace.go:171","msg":"trace[708743589] linearizableReadLoop","detail":"{readStateIndex:247; appliedIndex:244; }","duration":"103.076687ms","start":"2026-07-07T13:03:09.194132Z","end":"2026-07-07T13:03:09.297209Z","steps":["trace[708743589] 'read index received' (duration: 21.965108ms)","trace[708743589] 'applied index is now lower than readState.Index' (duration: 81.110259ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:03:09.297919Z","caller":"traceutil/trace.go:171","msg":"trace[967748986] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:241; }","duration":"103.792184ms","start":"2026-07-07T13:03:09.194104Z","end":"2026-07-07T13:03:09.297896Z","steps":["trace[967748986] 'agreement among raft nodes before linearized reading' (duration: 103.348454ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:09.424763Z","caller":"traceutil/trace.go:171","msg":"trace[1597499698] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"119.904539ms","start":"2026-07-07T13:03:09.304834Z","end":"2026-07-07T13:03:09.424739Z","steps":["trace[1597499698] 'process raft request' (duration: 115.5551ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:09.424893Z","caller":"traceutil/trace.go:171","msg":"trace[133017610] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"106.660788ms","start":"2026-07-07T13:03:09.318194Z","end":"2026-07-07T13:03:09.424855Z","steps":["trace[133017610] 'process raft request' (duration: 106.222598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:10.208173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.945972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T13:03:10.208365Z","caller":"traceutil/trace.go:171","msg":"trace[110012471] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:262; }","duration":"683.213468ms","start":"2026-07-07T13:03:09.525131Z","end":"2026-07-07T13:03:10.208344Z","steps":["trace[110012471] 'range keys from in-memory index tree' (duration: 682.828199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:10.208441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:03:09.525099Z","time spent":"683.330681ms","remote":"127.0.0.1:59694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"warn","ts":"2026-07-07T13:03:10.208525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.994957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-07T13:03:10.208736Z","caller":"traceutil/trace.go:171","msg":"trace[1295090870] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:268; }","duration":"372.784709ms","start":"2026-07-07T13:03:09.835927Z","end":"2026-07-07T13:03:10.208712Z","steps":["trace[1295090870] 'read index received' (duration: 37.351µs)","trace[1295090870] 'applied index is now lower than readState.Index' (duration: 372.745148ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:03:10.208823Z","caller":"traceutil/trace.go:171","msg":"trace[664561990] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"682.870219ms","start":"2026-07-07T13:03:09.525912Z","end":"2026-07-07T13:03:10.208782Z","steps":["trace[664561990] 'process raft request' (duration: 72.279649ms)","trace[664561990] 'compare' (duration: 609.798153ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T13:03:10.209018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:03:09.525862Z","time spent":"683.053494ms","remote":"127.0.0.1:59552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T13:03:10.209115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.138787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3498"} {"level":"info","ts":"2026-07-07T13:03:10.209221Z","caller":"traceutil/trace.go:171","msg":"trace[1775242383] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:263; }","duration":"373.310001ms","start":"2026-07-07T13:03:09.835851Z","end":"2026-07-07T13:03:10.209161Z","steps":["trace[1775242383] 'agreement among raft nodes before linearized reading' (duration: 372.991283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:10.209312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:03:09.835817Z","time spent":"373.479665ms","remote":"127.0.0.1:59658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3521,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-07T13:03:10.388153Z","caller":"traceutil/trace.go:171","msg":"trace[211214219] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"110.915452ms","start":"2026-07-07T13:03:10.277199Z","end":"2026-07-07T13:03:10.388115Z","steps":["trace[211214219] 'process raft request' (duration: 42.479151ms)","trace[211214219] 'compare' (duration: 68.240856ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:03:10.897085Z","caller":"traceutil/trace.go:171","msg":"trace[1611794808] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"100.758622ms","start":"2026-07-07T13:03:10.796221Z","end":"2026-07-07T13:03:10.896979Z","steps":["trace[1611794808] 'process raft request' (duration: 32.016856ms)","trace[1611794808] 'compare' (duration: 68.244005ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:03:11.172406Z","caller":"traceutil/trace.go:171","msg":"trace[1559434867] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"107.579504ms","start":"2026-07-07T13:03:11.064795Z","end":"2026-07-07T13:03:11.172374Z","steps":["trace[1559434867] 'process raft request' (duration: 62.3297ms)","trace[1559434867] 'compare' (duration: 44.936217ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T13:03:11.414305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.478435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T13:03:11.414717Z","caller":"traceutil/trace.go:171","msg":"trace[1459949803] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"231.820345ms","start":"2026-07-07T13:03:11.182836Z","end":"2026-07-07T13:03:11.414656Z","steps":["trace[1459949803] 'process raft request' (duration: 231.688072ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:11.414734Z","caller":"traceutil/trace.go:171","msg":"trace[33805420] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"235.376726ms","start":"2026-07-07T13:03:11.17932Z","end":"2026-07-07T13:03:11.414697Z","steps":["trace[33805420] 'process raft request' (duration: 122.304928ms)","trace[33805420] 'compare' (duration: 112.329602ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:03:11.414719Z","caller":"traceutil/trace.go:171","msg":"trace[841067364] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"233.094894ms","start":"2026-07-07T13:03:11.181539Z","end":"2026-07-07T13:03:11.414633Z","steps":["trace[841067364] 'process raft request' (duration: 232.883369ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:11.414917Z","caller":"traceutil/trace.go:171","msg":"trace[1067695413] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"196.449365ms","start":"2026-07-07T13:03:11.218417Z","end":"2026-07-07T13:03:11.414867Z","steps":["trace[1067695413] 'process raft request' (duration: 196.165779ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:11.414885Z","caller":"traceutil/trace.go:171","msg":"trace[1232358991] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:312; }","duration":"198.300597ms","start":"2026-07-07T13:03:11.216536Z","end":"2026-07-07T13:03:11.414836Z","steps":["trace[1232358991] 'read index received' (duration: 85.113567ms)","trace[1232358991] 'applied index is now lower than readState.Index' (duration: 113.17848ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T13:03:11.415105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.370727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-07T13:03:11.41516Z","caller":"traceutil/trace.go:171","msg":"trace[1624402778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:306; }","duration":"236.502001ms","start":"2026-07-07T13:03:11.178643Z","end":"2026-07-07T13:03:11.415145Z","steps":["trace[1624402778] 'agreement among raft nodes before linearized reading' (duration: 236.306887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:11.415211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.285136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-07T13:03:11.415225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.45883ms","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-07T13:03:11.415282Z","caller":"traceutil/trace.go:171","msg":"trace[226375048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:306; }","duration":"225.539893ms","start":"2026-07-07T13:03:11.18972Z","end":"2026-07-07T13:03:11.41526Z","steps":["trace[226375048] 'agreement among raft nodes before linearized reading' (duration: 225.42235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:11.415283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.804241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-07T13:03:11.415259Z","caller":"traceutil/trace.go:171","msg":"trace[2015744737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:306; }","duration":"236.358108ms","start":"2026-07-07T13:03:11.178889Z","end":"2026-07-07T13:03:11.415247Z","steps":["trace[2015744737] 'agreement among raft nodes before linearized reading' (duration: 236.259135ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:11.415364Z","caller":"traceutil/trace.go:171","msg":"trace[1974017421] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:306; }","duration":"195.879453ms","start":"2026-07-07T13:03:11.21946Z","end":"2026-07-07T13:03:11.41534Z","steps":["trace[1974017421] 'agreement among raft nodes before linearized reading' (duration: 195.75197ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:11.554405Z","caller":"traceutil/trace.go:171","msg":"trace[51144779] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"133.91559ms","start":"2026-07-07T13:03:11.420459Z","end":"2026-07-07T13:03:11.554374Z","steps":["trace[51144779] 'process raft request' (duration: 122.329378ms)","trace[51144779] 'compare' (duration: 11.360587ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:03:11.555307Z","caller":"traceutil/trace.go:171","msg":"trace[457145952] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"133.988392ms","start":"2026-07-07T13:03:11.421288Z","end":"2026-07-07T13:03:11.555276Z","steps":["trace[457145952] 'process raft request' (duration: 133.03308ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:11.555486Z","caller":"traceutil/trace.go:171","msg":"trace[927333666] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:316; }","duration":"134.339459ms","start":"2026-07-07T13:03:11.421129Z","end":"2026-07-07T13:03:11.555468Z","steps":["trace[927333666] 'read index received' (duration: 72.96121ms)","trace[927333666] 'applied index is now lower than readState.Index' (duration: 61.376569ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:03:11.555558Z","caller":"traceutil/trace.go:171","msg":"trace[1159282672] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"134.015052ms","start":"2026-07-07T13:03:11.421513Z","end":"2026-07-07T13:03:11.555528Z","steps":["trace[1159282672] 'process raft request' (duration: 133.819798ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:11.555656Z","caller":"traceutil/trace.go:171","msg":"trace[828003480] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"134.009272ms","start":"2026-07-07T13:03:11.42159Z","end":"2026-07-07T13:03:11.555599Z","steps":["trace[828003480] 'process raft request' (duration: 133.834988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:11.555811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.660546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3806"} {"level":"info","ts":"2026-07-07T13:03:11.555851Z","caller":"traceutil/trace.go:171","msg":"trace[734382956] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:312; }","duration":"134.754538ms","start":"2026-07-07T13:03:11.421087Z","end":"2026-07-07T13:03:11.555841Z","steps":["trace[734382956] 'agreement among raft nodes before linearized reading' (duration: 134.662946ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:18.305536Z","caller":"traceutil/trace.go:171","msg":"trace[478213124] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"101.082712ms","start":"2026-07-07T13:03:18.204424Z","end":"2026-07-07T13:03:18.305507Z","steps":["trace[478213124] 'process raft request' (duration: 60.928729ms)","trace[478213124] 'compare' (duration: 39.993189ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:03:33.839361Z","caller":"traceutil/trace.go:171","msg":"trace[563598322] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"147.843438ms","start":"2026-07-07T13:03:33.691489Z","end":"2026-07-07T13:03:33.839333Z","steps":["trace[563598322] 'process raft request' (duration: 147.456438ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:47.206498Z","caller":"traceutil/trace.go:171","msg":"trace[2099616789] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"481.314419ms","start":"2026-07-07T13:03:46.72516Z","end":"2026-07-07T13:03:47.206474Z","steps":["trace[2099616789] 'read index received' (duration: 481.093473ms)","trace[2099616789] 'applied index is now lower than readState.Index' (duration: 219.646µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:03:47.206581Z","caller":"traceutil/trace.go:171","msg":"trace[156107487] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"493.641597ms","start":"2026-07-07T13:03:46.712918Z","end":"2026-07-07T13:03:47.20656Z","steps":["trace[156107487] 'process raft request' (duration: 493.344109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:47.206765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:03:46.712888Z","time spent":"493.792022ms","remote":"127.0.0.1:59536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20842,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T13:03:47.20677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.552486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T13:03:47.206997Z","caller":"traceutil/trace.go:171","msg":"trace[1920927414] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:620; }","duration":"481.844203ms","start":"2026-07-07T13:03:46.725119Z","end":"2026-07-07T13:03:47.206963Z","steps":["trace[1920927414] 'agreement among raft nodes before linearized reading' (duration: 481.469562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:47.207097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:03:46.725095Z","time spent":"481.987357ms","remote":"127.0.0.1:59536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-07-07T13:03:47.216947Z","caller":"traceutil/trace.go:171","msg":"trace[1112194445] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"466.234291ms","start":"2026-07-07T13:03:46.750674Z","end":"2026-07-07T13:03:47.216909Z","steps":["trace[1112194445] 'process raft request' (duration: 465.38718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:47.217372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:03:46.750646Z","time spent":"466.629761ms","remote":"127.0.0.1:59536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T13:03:47.221308Z","caller":"traceutil/trace.go:171","msg":"trace[1900473725] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"325.056857ms","start":"2026-07-07T13:03:46.896217Z","end":"2026-07-07T13:03:47.221274Z","steps":["trace[1900473725] 'process raft request' (duration: 320.426747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:47.221449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:03:46.896178Z","time spent":"325.208161ms","remote":"127.0.0.1:59536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T13:03:47.221613Z","caller":"traceutil/trace.go:171","msg":"trace[1793307290] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"101.465957ms","start":"2026-07-07T13:03:47.120125Z","end":"2026-07-07T13:03:47.221591Z","steps":["trace[1793307290] 'process raft request' (duration: 101.087547ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:47.221803Z","caller":"traceutil/trace.go:171","msg":"trace[1434029996] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"301.712667ms","start":"2026-07-07T13:03:46.920079Z","end":"2026-07-07T13:03:47.221792Z","steps":["trace[1434029996] 'process raft request' (duration: 301.084151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:47.221904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:03:46.92007Z","time spent":"301.768258ms","remote":"127.0.0.1:59536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T13:03:53.93719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.421948ms","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-07T13:03:53.937287Z","caller":"traceutil/trace.go:171","msg":"trace[2140371377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:703; }","duration":"159.586032ms","start":"2026-07-07T13:03:53.777682Z","end":"2026-07-07T13:03:53.937268Z","steps":["trace[2140371377] 'range keys from in-memory index tree' (duration: 159.190752ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:03:54.573993Z","caller":"traceutil/trace.go:171","msg":"trace[1662372745] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:735; }","duration":"306.869498ms","start":"2026-07-07T13:03:54.267087Z","end":"2026-07-07T13:03:54.573957Z","steps":["trace[1662372745] 'read index received' (duration: 306.514479ms)","trace[1662372745] 'applied index is now lower than readState.Index' (duration: 353.459µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:03:54.574175Z","caller":"traceutil/trace.go:171","msg":"trace[1103853773] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"401.897995ms","start":"2026-07-07T13:03:54.172146Z","end":"2026-07-07T13:03:54.574044Z","steps":["trace[1103853773] 'process raft request' (duration: 401.553776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:54.574276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.154855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T13:03:54.574324Z","caller":"traceutil/trace.go:171","msg":"trace[1537297962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"307.287989ms","start":"2026-07-07T13:03:54.267023Z","end":"2026-07-07T13:03:54.574311Z","steps":["trace[1537297962] 'agreement among raft nodes before linearized reading' (duration: 307.124554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:54.574371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:03:54.266995Z","time spent":"307.367021ms","remote":"127.0.0.1:59494","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-07T13:03:54.574506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.978051ms","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-07T13:03:54.574619Z","caller":"traceutil/trace.go:171","msg":"trace[123077035] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:708; }","duration":"120.159386ms","start":"2026-07-07T13:03:54.454437Z","end":"2026-07-07T13:03:54.574597Z","steps":["trace[123077035] 'agreement among raft nodes before linearized reading' (duration: 119.806356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:54.574527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:03:54.172109Z","time spent":"402.229694ms","remote":"127.0.0.1:59674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T13:03:56.048197Z","caller":"traceutil/trace.go:171","msg":"trace[23685015] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"304.650636ms","start":"2026-07-07T13:03:55.743505Z","end":"2026-07-07T13:03:56.048156Z","steps":["trace[23685015] 'process raft request' (duration: 304.360818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:03:56.04845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:03:55.743468Z","time spent":"304.83415ms","remote":"127.0.0.1:59752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T13:04:11.892619Z","caller":"traceutil/trace.go:171","msg":"trace[1728147101] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"621.202324ms","start":"2026-07-07T13:04:11.271393Z","end":"2026-07-07T13:04:11.892595Z","steps":["trace[1728147101] 'process raft request' (duration: 621.01821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:04:11.892812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:04:11.271354Z","time spent":"621.362558ms","remote":"127.0.0.1:59674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T13:04:11.906609Z","caller":"traceutil/trace.go:171","msg":"trace[911127562] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"318.789019ms","start":"2026-07-07T13:04:11.587789Z","end":"2026-07-07T13:04:11.906578Z","steps":["trace[911127562] 'process raft request' (duration: 316.653236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:04:11.906807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:04:11.587687Z","time spent":"319.001525ms","remote":"127.0.0.1:59752","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-07T13:04:19.770994Z","caller":"traceutil/trace.go:171","msg":"trace[889115895] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"132.013309ms","start":"2026-07-07T13:04:19.638954Z","end":"2026-07-07T13:04:19.770968Z","steps":["trace[889115895] 'process raft request' (duration: 131.869885ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:04:19.776103Z","caller":"traceutil/trace.go:171","msg":"trace[25500425] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"127.576021ms","start":"2026-07-07T13:04:19.648491Z","end":"2026-07-07T13:04:19.776067Z","steps":["trace[25500425] 'process raft request' (duration: 127.410837ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:04:20.549008Z","caller":"traceutil/trace.go:171","msg":"trace[886857140] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"189.791003ms","start":"2026-07-07T13:04:20.359196Z","end":"2026-07-07T13:04:20.548987Z","steps":["trace[886857140] 'process raft request' (duration: 189.634759ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:04:20.551664Z","caller":"traceutil/trace.go:171","msg":"trace[2094446180] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"110.614874ms","start":"2026-07-07T13:04:20.441014Z","end":"2026-07-07T13:04:20.551629Z","steps":["trace[2094446180] 'process raft request' (duration: 110.330766ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:04:26.871915Z","caller":"traceutil/trace.go:171","msg":"trace[140320188] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"299.064021ms","start":"2026-07-07T13:04:26.572784Z","end":"2026-07-07T13:04:26.871848Z","steps":["trace[140320188] 'process raft request' (duration: 298.23086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:04:26.874912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T13:04:26.572747Z","time spent":"302.018733ms","remote":"127.0.0.1:59752","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-07T13:04:35.213034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.150494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T13:04:35.213274Z","caller":"traceutil/trace.go:171","msg":"trace[1442444787] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"250.522059ms","start":"2026-07-07T13:04:34.962664Z","end":"2026-07-07T13:04:35.213186Z","steps":["trace[1442444787] 'process raft request' (duration: 19.107869ms)","trace[1442444787] 'compare' (duration: 230.97143ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:04:40.697974Z","caller":"traceutil/trace.go:171","msg":"trace[1713793003] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1087; }","duration":"228.556354ms","start":"2026-07-07T13:04:40.469387Z","end":"2026-07-07T13:04:40.697943Z","steps":["trace[1713793003] 'read index received' (duration: 228.287618ms)","trace[1713793003] 'applied index is now lower than readState.Index' (duration: 267.456µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T13:04:40.69829Z","caller":"traceutil/trace.go:171","msg":"trace[1989473617] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"283.620317ms","start":"2026-07-07T13:04:40.414656Z","end":"2026-07-07T13:04:40.698276Z","steps":["trace[1989473617] 'process raft request' (duration: 283.095295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:04:40.698644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.22423ms","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-07T13:04:40.698689Z","caller":"traceutil/trace.go:171","msg":"trace[278470801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1048; }","duration":"229.309133ms","start":"2026-07-07T13:04:40.469368Z","end":"2026-07-07T13:04:40.698677Z","steps":["trace[278470801] 'agreement among raft nodes before linearized reading' (duration: 229.117468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:04:40.698922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.407275ms","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-07T13:04:40.69896Z","caller":"traceutil/trace.go:171","msg":"trace[1932534059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1048; }","duration":"229.495197ms","start":"2026-07-07T13:04:40.469453Z","end":"2026-07-07T13:04:40.698949Z","steps":["trace[1932534059] 'agreement among raft nodes before linearized reading' (duration: 229.391705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T13:04:40.699206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.286832ms","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-07T13:04:40.69924Z","caller":"traceutil/trace.go:171","msg":"trace[1955166893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1048; }","duration":"192.388645ms","start":"2026-07-07T13:04:40.506843Z","end":"2026-07-07T13:04:40.699232Z","steps":["trace[1955166893] 'agreement among raft nodes before linearized reading' (duration: 192.299993ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:04:53.222325Z","caller":"traceutil/trace.go:171","msg":"trace[1657812134] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"120.669243ms","start":"2026-07-07T13:04:53.101635Z","end":"2026-07-07T13:04:53.222304Z","steps":["trace[1657812134] 'process raft request' (duration: 120.5302ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:04:57.37706Z","caller":"traceutil/trace.go:171","msg":"trace[54306230] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"116.932237ms","start":"2026-07-07T13:04:57.260078Z","end":"2026-07-07T13:04:57.377011Z","steps":["trace[54306230] 'process raft request' (duration: 116.20054ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T13:06:02.020283Z","caller":"traceutil/trace.go:171","msg":"trace[325925261] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"112.556116ms","start":"2026-07-07T13:06:01.907703Z","end":"2026-07-07T13:06:02.020259Z","steps":["trace[325925261] 'process raft request' (duration: 112.415883ms)"],"step_count":1}