{"level":"warn","ts":"2026-06-27T05:37:52.650956Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-27T05:37:52.651053Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-06-27T05:37:52.651122Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-27T05:37:52.651138Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-06-27T05:37:52.65117Z","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-06-27T05:37:52.651996Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-06-27T05:37:52.65212Z","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.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-06-27T05:37:52.702234Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"49.702672ms"} {"level":"info","ts":"2026-06-27T05:37:52.75882Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-06-27T05:37:52.759088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-06-27T05:37:52.759116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-06-27T05:37:52.759127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-27T05:37:52.759139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-06-27T05:37:52.759184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-06-27T05:37:52.794474Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-27T05:37:52.803523Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-27T05:37:52.808274Z","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-06-27T05:37:52.81752Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-27T05:37:52.81805Z","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-06-27T05:37:52.818227Z","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-06-27T05:37:52.818257Z","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-06-27T05:37:52.818133Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-27T05:37:52.823076Z","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-06-27T05:37:52.823426Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-06-27T05:37:52.82345Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-06-27T05:37:52.823673Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-27T05:37:52.823732Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-27T05:37:52.82915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-06-27T05:37:52.829325Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-06-27T05:37:53.259438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-06-27T05:37:53.259549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-27T05:37:53.259762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-06-27T05:37:53.259813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-06-27T05:37:53.259897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-06-27T05:37:53.259931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-06-27T05:37:53.259956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-06-27T05:37:53.271845Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-06-27T05:37:53.271888Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-27T05:37:53.271886Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-27T05:37:53.272141Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-27T05:37:53.272359Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-27T05:37:53.272428Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-27T05:37:53.275406Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-06-27T05:37:53.276603Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-27T05:37:53.293391Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-06-27T05:37:53.293533Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-27T05:37:53.293572Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-27T05:37:56.029032Z","caller":"traceutil/trace.go:171","msg":"trace[1399925794] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"105.024229ms","start":"2026-06-27T05:37:55.923953Z","end":"2026-06-27T05:37:56.028977Z","steps":["trace[1399925794] 'process raft request' (duration: 104.877595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:37:56.322114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.929598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-27T05:37:56.322254Z","caller":"traceutil/trace.go:171","msg":"trace[182227728] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"204.731914ms","start":"2026-06-27T05:37:56.117502Z","end":"2026-06-27T05:37:56.322234Z","steps":["trace[182227728] 'compare' (duration: 192.795935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:37:57.592977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.428922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-27T05:37:57.59313Z","caller":"traceutil/trace.go:171","msg":"trace[1013592469] linearizableReadLoop","detail":"{readStateIndex:115; appliedIndex:114; }","duration":"1.172554862s","start":"2026-06-27T05:37:56.420562Z","end":"2026-06-27T05:37:57.593117Z","steps":["trace[1013592469] 'read index received' (duration: 248.809661ms)","trace[1013592469] 'applied index is now lower than readState.Index' (duration: 923.743951ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:37:57.593215Z","caller":"traceutil/trace.go:171","msg":"trace[725939089] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"1.227775785s","start":"2026-06-27T05:37:56.36543Z","end":"2026-06-27T05:37:57.593206Z","steps":["trace[725939089] 'process raft request' (duration: 304.066525ms)","trace[725939089] 'compare' (duration: 923.301808ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:37:57.593288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:37:56.365404Z","time spent":"1.227853998s","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-27T05:37:57.593353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172786358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpoint-controller\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-27T05:37:57.593434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.391315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-27T05:37:57.593472Z","caller":"traceutil/trace.go:171","msg":"trace[1621685850] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpoint-controller; range_end:; response_count:0; response_revision:111; }","duration":"1.172921962s","start":"2026-06-27T05:37:56.420515Z","end":"2026-06-27T05:37:57.593437Z","steps":["trace[1621685850] 'agreement among raft nodes before linearized reading' (duration: 1.172753617s)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:37:57.593504Z","caller":"traceutil/trace.go:171","msg":"trace[131779686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:111; }","duration":"987.493238ms","start":"2026-06-27T05:37:56.605994Z","end":"2026-06-27T05:37:57.593487Z","steps":["trace[131779686] 'agreement among raft nodes before linearized reading' (duration: 987.289592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:37:57.593525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:37:56.420497Z","time spent":"1.173014685s","remote":"127.0.0.1:36508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:controller:endpoint-controller\" "} {"level":"warn","ts":"2026-06-27T05:37:57.593553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:37:56.605969Z","time spent":"987.57318ms","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-27T05:37:57.593419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.651752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-27T05:37:57.59366Z","caller":"traceutil/trace.go:171","msg":"trace[1415824386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:111; }","duration":"868.909399ms","start":"2026-06-27T05:37:56.724689Z","end":"2026-06-27T05:37:57.593599Z","steps":["trace[1415824386] 'agreement among raft nodes before linearized reading' (duration: 868.648741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:37:57.593725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:37:56.724664Z","time spent":"869.043813ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-27T05:38:06.233581Z","caller":"traceutil/trace.go:171","msg":"trace[217451627] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"103.577715ms","start":"2026-06-27T05:38:06.129974Z","end":"2026-06-27T05:38:06.233552Z","steps":["trace[217451627] 'process raft request' (duration: 103.413869ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:11.845383Z","caller":"traceutil/trace.go:171","msg":"trace[463488957] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"110.609843ms","start":"2026-06-27T05:38:11.73473Z","end":"2026-06-27T05:38:11.84534Z","steps":["trace[463488957] 'process raft request' (duration: 110.427986ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:12.190399Z","caller":"traceutil/trace.go:171","msg":"trace[1927149913] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"340.309737ms","start":"2026-06-27T05:38:11.850058Z","end":"2026-06-27T05:38:12.190368Z","steps":["trace[1927149913] 'process raft request' (duration: 340.13943ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:12.19088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:11.850035Z","time spent":"340.531146ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:38:13.464977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.597412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:38:13.466681Z","caller":"traceutil/trace.go:171","msg":"trace[2079574512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:379; }","duration":"207.334204ms","start":"2026-06-27T05:38:13.25932Z","end":"2026-06-27T05:38:13.466654Z","steps":["trace[2079574512] 'range keys from in-memory index tree' (duration: 205.516998ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:13.464972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.697765ms","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-06-27T05:38:13.466839Z","caller":"traceutil/trace.go:171","msg":"trace[1532452344] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:379; }","duration":"660.609325ms","start":"2026-06-27T05:38:12.806211Z","end":"2026-06-27T05:38:13.466821Z","steps":["trace[1532452344] 'range keys from in-memory index tree' (duration: 658.57238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:13.466933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:12.806183Z","time spent":"660.72564ms","remote":"127.0.0.1:36420","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-06-27T05:38:21.67396Z","caller":"traceutil/trace.go:171","msg":"trace[1257984598] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"119.406275ms","start":"2026-06-27T05:38:21.554532Z","end":"2026-06-27T05:38:21.673938Z","steps":["trace[1257984598] 'process raft request' (duration: 70.160645ms)","trace[1257984598] 'compare' (duration: 49.131295ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:38:21.945951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.049915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-27T05:38:21.946202Z","caller":"traceutil/trace.go:171","msg":"trace[1608766051] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"266.307297ms","start":"2026-06-27T05:38:21.67987Z","end":"2026-06-27T05:38:21.946177Z","steps":["trace[1608766051] 'process raft request' (duration: 110.968338ms)","trace[1608766051] 'compare' (duration: 154.921639ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:38:21.94627Z","caller":"traceutil/trace.go:171","msg":"trace[1866919777] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"262.919637ms","start":"2026-06-27T05:38:21.68332Z","end":"2026-06-27T05:38:21.94624Z","steps":["trace[1866919777] 'process raft request' (duration: 262.808621ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:22.167716Z","caller":"traceutil/trace.go:171","msg":"trace[1152106332] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:428; }","duration":"188.563141ms","start":"2026-06-27T05:38:21.979103Z","end":"2026-06-27T05:38:22.167666Z","steps":["trace[1152106332] 'read index received' (duration: 11.035529ms)","trace[1152106332] 'applied index is now lower than readState.Index' (duration: 177.526542ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:38:22.168051Z","caller":"traceutil/trace.go:171","msg":"trace[173393280] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"189.599671ms","start":"2026-06-27T05:38:21.978436Z","end":"2026-06-27T05:38:22.168036Z","steps":["trace[173393280] 'process raft request' (duration: 173.324554ms)","trace[173393280] 'compare' (duration: 15.810345ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:38:22.168266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.14836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-06-27T05:38:22.1683Z","caller":"traceutil/trace.go:171","msg":"trace[463061345] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:413; }","duration":"189.210312ms","start":"2026-06-27T05:38:21.979078Z","end":"2026-06-27T05:38:22.168289Z","steps":["trace[463061345] 'agreement among raft nodes before linearized reading' (duration: 189.137609ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:22.16847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.715039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-6sfwc\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-06-27T05:38:22.16852Z","caller":"traceutil/trace.go:171","msg":"trace[1176603024] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-6sfwc; range_end:; response_count:1; response_revision:413; }","duration":"188.798873ms","start":"2026-06-27T05:38:21.979708Z","end":"2026-06-27T05:38:22.168507Z","steps":["trace[1176603024] 'agreement among raft nodes before linearized reading' (duration: 188.692468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:23.056226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.384547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-27T05:38:23.056355Z","caller":"traceutil/trace.go:171","msg":"trace[2025718072] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"878.987723ms","start":"2026-06-27T05:38:22.177348Z","end":"2026-06-27T05:38:23.056336Z","steps":["trace[2025718072] 'process raft request' (duration: 229.409606ms)","trace[2025718072] 'compare' (duration: 649.22206ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:38:23.056435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:22.17732Z","time spent":"879.071067ms","remote":"127.0.0.1:36616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:38:24.031852Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.625016414s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-27T05:38:24.256742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99952656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-27T05:38:24.256856Z","caller":"traceutil/trace.go:171","msg":"trace[776708091] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999696398s","start":"2026-06-27T05:38:22.257138Z","end":"2026-06-27T05:38:24.256834Z","steps":["trace[776708091] 'agreement among raft nodes before linearized reading' (duration: 1.999562581s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:24.256905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:22.257115Z","time spent":"1.999779792s","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-27T05:38:25.213914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181726467s","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:4039"} {"level":"info","ts":"2026-06-27T05:38:25.214133Z","caller":"traceutil/trace.go:171","msg":"trace[1142905981] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:431; }","duration":"2.985302811s","start":"2026-06-27T05:38:22.228812Z","end":"2026-06-27T05:38:25.214114Z","steps":["trace[1142905981] 'read index received' (duration: 178.011054ms)","trace[1142905981] 'applied index is now lower than readState.Index' (duration: 2.807290627s)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:38:25.214219Z","caller":"traceutil/trace.go:171","msg":"trace[1910327901] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"3.003747182s","start":"2026-06-27T05:38:22.210463Z","end":"2026-06-27T05:38:25.21421Z","steps":["trace[1910327901] 'process raft request' (duration: 3.003555502s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:25.214281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:22.210438Z","time spent":"3.003806065s","remote":"127.0.0.1:36236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":721,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-27T05:38:25.21428Z","caller":"traceutil/trace.go:171","msg":"trace[313593308] transaction","detail":"{read_only:false; number_of_response:1; response_revision:415; }","duration":"3.006806047s","start":"2026-06-27T05:38:22.207363Z","end":"2026-06-27T05:38:25.214169Z","steps":["trace[313593308] 'process raft request' (duration: 1.824826898s)","trace[313593308] 'compare' (duration: 1.181502155s)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:38:25.214409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:22.207343Z","time spent":"3.007014837s","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":4062,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-06-27T05:38:25.214717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.985895421s","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-06-27T05:38:25.214763Z","caller":"traceutil/trace.go:171","msg":"trace[1620649351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"2.985951744s","start":"2026-06-27T05:38:22.2288Z","end":"2026-06-27T05:38:25.214751Z","steps":["trace[1620649351] 'agreement among raft nodes before linearized reading' (duration: 2.985737743s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:25.214806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:22.228783Z","time spent":"2.986014587s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-27T05:38:25.653555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999804062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-27T05:38:25.653645Z","caller":"traceutil/trace.go:171","msg":"trace[595369244] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999952419s","start":"2026-06-27T05:38:23.653674Z","end":"2026-06-27T05:38:25.653627Z","steps":["trace[595369244] 'agreement among raft nodes before linearized reading' (duration: 1.999840814s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:25.653732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:23.653646Z","time spent":"2.000074165s","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/27 05:38:25 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-27T05:38:25.715357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154449,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:26.216518Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154449,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:26.257786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000096651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-27T05:38:26.257871Z","caller":"traceutil/trace.go:171","msg":"trace[1468278771] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000230188s","start":"2026-06-27T05:38:24.25762Z","end":"2026-06-27T05:38:26.257851Z","steps":["trace[1468278771] 'agreement among raft nodes before linearized reading' (duration: 2.000126272s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:26.257919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:24.257598Z","time spent":"2.000310401s","remote":"127.0.0.1:36150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/27 05:38:26 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-27T05:38:26.412483Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.380456685s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-27T05:38:26.57474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.473659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-27T05:38:26.574996Z","caller":"traceutil/trace.go:171","msg":"trace[652588609] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"3.513278165s","start":"2026-06-27T05:38:23.061655Z","end":"2026-06-27T05:38:26.574933Z","steps":["trace[652588609] 'process raft request' (duration: 3.351126762s)","trace[652588609] 'compare' (duration: 161.380434ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:38:26.575022Z","caller":"traceutil/trace.go:171","msg":"trace[542127106] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"3.513244203s","start":"2026-06-27T05:38:23.06169Z","end":"2026-06-27T05:38:26.574935Z","steps":["trace[542127106] 'process raft request' (duration: 3.513120647s)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:26.575081Z","caller":"traceutil/trace.go:171","msg":"trace[1757724119] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"3.512244865s","start":"2026-06-27T05:38:23.062797Z","end":"2026-06-27T05:38:26.575042Z","steps":["trace[1757724119] 'process raft request' (duration: 3.512053236s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:26.575139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:23.061632Z","time spent":"3.513432042s","remote":"127.0.0.1:36236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-27T05:38:26.575212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:23.062773Z","time spent":"3.512378112s","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-27T05:38:26.575243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:23.061674Z","time spent":"3.513447933s","remote":"127.0.0.1:36594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:38:26.892855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.959058ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-27T05:38:26.894593Z","caller":"traceutil/trace.go:171","msg":"trace[1052408851] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"313.667423ms","start":"2026-06-27T05:38:26.580907Z","end":"2026-06-27T05:38:26.894575Z","steps":["trace[1052408851] 'process raft request' (duration: 313.630552ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:26.894599Z","caller":"traceutil/trace.go:171","msg":"trace[537879984] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"1.672241064s","start":"2026-06-27T05:38:25.222328Z","end":"2026-06-27T05:38:26.894569Z","steps":["trace[537879984] 'process raft request' (duration: 1.6719628s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:26.894723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:26.58089Z","time spent":"313.759448ms","remote":"127.0.0.1:36236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-27T05:38:26.894757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:25.222316Z","time spent":"1.672348519s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:26.894931Z","caller":"traceutil/trace.go:171","msg":"trace[2095119120] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"1.241729149s","start":"2026-06-27T05:38:25.653188Z","end":"2026-06-27T05:38:26.894918Z","steps":["trace[2095119120] 'process raft request' (duration: 1.241275586s)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:26.894964Z","caller":"traceutil/trace.go:171","msg":"trace[429601067] transaction","detail":"{read_only:false; number_of_response:1; response_revision:420; }","duration":"1.678567346s","start":"2026-06-27T05:38:25.216386Z","end":"2026-06-27T05:38:26.894953Z","steps":["trace[429601067] 'process raft request' (duration: 1.676582207s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:26.89503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:25.216359Z","time spent":"1.67863298s","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-06-27T05:38:26.89509Z","caller":"traceutil/trace.go:171","msg":"trace[2051531397] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"1.239009314s","start":"2026-06-27T05:38:25.656068Z","end":"2026-06-27T05:38:26.895077Z","steps":["trace[2051531397] 'process raft request' (duration: 1.238431495s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:26.895163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:25.656045Z","time spent":"1.239085707s","remote":"127.0.0.1:36236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-27T05:38:26.895027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:25.653162Z","time spent":"1.241808852s","remote":"127.0.0.1:36420","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-06-27T05:38:26.895391Z","caller":"traceutil/trace.go:171","msg":"trace[101205104] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:434; }","duration":"1.681194538s","start":"2026-06-27T05:38:25.214182Z","end":"2026-06-27T05:38:26.895376Z","steps":["trace[101205104] 'read index received' (duration: 1.198544589s)","trace[101205104] 'applied index is now lower than readState.Index' (duration: 482.648458ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:38:26.895603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.932025128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-27T05:38:26.895656Z","caller":"traceutil/trace.go:171","msg":"trace[509492983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:424; }","duration":"2.932102063s","start":"2026-06-27T05:38:23.963541Z","end":"2026-06-27T05:38:26.895643Z","steps":["trace[509492983] 'agreement among raft nodes before linearized reading' (duration: 2.932005878s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:26.895696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:23.963527Z","time spent":"2.932159195s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-27T05:38:26.895722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.585651191s","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-06-27T05:38:26.895759Z","caller":"traceutil/trace.go:171","msg":"trace[1528237569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:424; }","duration":"4.585760257s","start":"2026-06-27T05:38:22.309988Z","end":"2026-06-27T05:38:26.895748Z","steps":["trace[1528237569] 'agreement among raft nodes before linearized reading' (duration: 4.585690214s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:26.895797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:22.309949Z","time spent":"4.585839951s","remote":"127.0.0.1:36420","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-06-27T05:38:26.89597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.337855154s","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-06-27T05:38:26.896006Z","caller":"traceutil/trace.go:171","msg":"trace[1114089480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:424; }","duration":"3.337937259s","start":"2026-06-27T05:38:23.55806Z","end":"2026-06-27T05:38:26.895997Z","steps":["trace[1114089480] 'agreement among raft nodes before linearized reading' (duration: 3.337877946s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:26.896034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:23.558025Z","time spent":"3.338002121s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-27T05:38:26.896112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.954877864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-27T05:38:26.896164Z","caller":"traceutil/trace.go:171","msg":"trace[2112029589] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:1; response_revision:424; }","duration":"3.954953727s","start":"2026-06-27T05:38:22.941197Z","end":"2026-06-27T05:38:26.896151Z","steps":["trace[2112029589] 'agreement among raft nodes before linearized reading' (duration: 3.954865993s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:26.896205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:22.941183Z","time spent":"3.955011901s","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" "} {"level":"info","ts":"2026-06-27T05:38:26.896501Z","caller":"traceutil/trace.go:171","msg":"trace[2068697274] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"315.287074ms","start":"2026-06-27T05:38:26.581203Z","end":"2026-06-27T05:38:26.89649Z","steps":["trace[2068697274] 'process raft request' (duration: 315.153228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:26.896585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.515317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-27T05:38:26.896584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:26.581178Z","time spent":"315.371048ms","remote":"127.0.0.1:36236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-27T05:38:26.896619Z","caller":"traceutil/trace.go:171","msg":"trace[640522016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"634.563929ms","start":"2026-06-27T05:38:26.262042Z","end":"2026-06-27T05:38:26.896606Z","steps":["trace[640522016] 'agreement among raft nodes before linearized reading' (duration: 634.498606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:26.89668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:26.262027Z","time spent":"634.648794ms","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-27T05:38:26.896848Z","caller":"traceutil/trace.go:171","msg":"trace[1610131958] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"314.443912ms","start":"2026-06-27T05:38:26.582397Z","end":"2026-06-27T05:38:26.896841Z","steps":["trace[1610131958] 'process raft request' (duration: 314.035131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:26.896898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:26.582384Z","time spent":"314.488204ms","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:26.896938Z","caller":"traceutil/trace.go:171","msg":"trace[326896851] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"311.769789ms","start":"2026-06-27T05:38:26.585146Z","end":"2026-06-27T05:38:26.896915Z","steps":["trace[326896851] 'process raft request' (duration: 311.348198ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:26.897037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:26.585136Z","time spent":"311.852812ms","remote":"127.0.0.1:36616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:26.897099Z","caller":"traceutil/trace.go:171","msg":"trace[2081180176] transaction","detail":"{read_only:false; number_of_response:1; response_revision:426; }","duration":"313.571948ms","start":"2026-06-27T05:38:26.58352Z","end":"2026-06-27T05:38:26.897092Z","steps":["trace[2081180176] 'process raft request' (duration: 312.948807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:26.897153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:26.583493Z","time spent":"313.630831ms","remote":"127.0.0.1:36594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":4229,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:27.176002Z","caller":"traceutil/trace.go:171","msg":"trace[955952609] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"271.649977ms","start":"2026-06-27T05:38:26.904343Z","end":"2026-06-27T05:38:27.175993Z","steps":["trace[955952609] 'process raft request' (duration: 271.527541ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:27.175988Z","caller":"traceutil/trace.go:171","msg":"trace[890111226] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"271.642796ms","start":"2026-06-27T05:38:26.904309Z","end":"2026-06-27T05:38:27.175951Z","steps":["trace[890111226] 'process raft request' (duration: 233.483646ms)","trace[890111226] 'compare' (duration: 37.910267ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:38:27.176294Z","caller":"traceutil/trace.go:171","msg":"trace[274406582] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"271.902389ms","start":"2026-06-27T05:38:26.904374Z","end":"2026-06-27T05:38:27.176277Z","steps":["trace[274406582] 'process raft request' (duration: 271.580833ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:27.176365Z","caller":"traceutil/trace.go:171","msg":"trace[1576305531] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"269.554032ms","start":"2026-06-27T05:38:26.906803Z","end":"2026-06-27T05:38:27.176357Z","steps":["trace[1576305531] 'process raft request' (duration: 269.323501ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:27.176449Z","caller":"traceutil/trace.go:171","msg":"trace[849005393] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"269.596845ms","start":"2026-06-27T05:38:26.906845Z","end":"2026-06-27T05:38:27.176441Z","steps":["trace[849005393] 'process raft request' (duration: 269.450007ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:27.176604Z","caller":"traceutil/trace.go:171","msg":"trace[1216733793] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:448; }","duration":"228.522499ms","start":"2026-06-27T05:38:26.948075Z","end":"2026-06-27T05:38:27.176597Z","steps":["trace[1216733793] 'read index received' (duration: 189.663314ms)","trace[1216733793] 'applied index is now lower than readState.Index' (duration: 38.858445ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:38:27.176763Z","caller":"traceutil/trace.go:171","msg":"trace[501663220] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"268.562772ms","start":"2026-06-27T05:38:26.908194Z","end":"2026-06-27T05:38:27.176757Z","steps":["trace[501663220] 'process raft request' (duration: 268.222985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:27.176934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.71264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-06-27T05:38:27.17696Z","caller":"traceutil/trace.go:171","msg":"trace[616234432] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:434; }","duration":"272.777983ms","start":"2026-06-27T05:38:26.904175Z","end":"2026-06-27T05:38:27.176953Z","steps":["trace[616234432] 'agreement among raft nodes before linearized reading' (duration: 272.721911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:27.177096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.582432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-6sfwc\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:38:27.177115Z","caller":"traceutil/trace.go:171","msg":"trace[1430229133] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-6sfwc; range_end:; response_count:0; response_revision:434; }","duration":"221.639275ms","start":"2026-06-27T05:38:26.95547Z","end":"2026-06-27T05:38:27.177109Z","steps":["trace[1430229133] 'agreement among raft nodes before linearized reading' (duration: 221.609104ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:29.63702Z","caller":"traceutil/trace.go:171","msg":"trace[429178625] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"456.117001ms","start":"2026-06-27T05:38:29.18088Z","end":"2026-06-27T05:38:29.636997Z","steps":["trace[429178625] 'read index received' (duration: 455.863548ms)","trace[429178625] 'applied index is now lower than readState.Index' (duration: 250.473µs)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:38:29.637164Z","caller":"traceutil/trace.go:171","msg":"trace[1584933401] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"727.035951ms","start":"2026-06-27T05:38:28.910088Z","end":"2026-06-27T05:38:29.637124Z","steps":["trace[1584933401] 'process raft request' (duration: 726.746106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:29.637243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.302171ms","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-06-27T05:38:29.637312Z","caller":"traceutil/trace.go:171","msg":"trace[123494539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:440; }","duration":"456.416987ms","start":"2026-06-27T05:38:29.180878Z","end":"2026-06-27T05:38:29.637295Z","steps":["trace[123494539] 'agreement among raft nodes before linearized reading' (duration: 456.299811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:29.637371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:29.180857Z","time spent":"456.500521ms","remote":"127.0.0.1:36420","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-06-27T05:38:29.63739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:28.910071Z","time spent":"727.190338ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:38:29.637537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.557055ms","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-06-27T05:38:29.637627Z","caller":"traceutil/trace.go:171","msg":"trace[585100953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:440; }","duration":"456.695972ms","start":"2026-06-27T05:38:29.18091Z","end":"2026-06-27T05:38:29.637606Z","steps":["trace[585100953] 'agreement among raft nodes before linearized reading' (duration: 456.47525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:29.637669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:29.18089Z","time spent":"456.769676ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-27T05:38:29.637243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.339533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-27T05:38:29.63784Z","caller":"traceutil/trace.go:171","msg":"trace[428439268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:440; }","duration":"456.978906ms","start":"2026-06-27T05:38:29.180847Z","end":"2026-06-27T05:38:29.637826Z","steps":["trace[428439268] 'agreement among raft nodes before linearized reading' (duration: 456.327693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:29.63789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:29.18083Z","time spent":"457.04732ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-27T05:38:29.782255Z","caller":"traceutil/trace.go:171","msg":"trace[70392057] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"140.08486ms","start":"2026-06-27T05:38:29.642148Z","end":"2026-06-27T05:38:29.782233Z","steps":["trace[70392057] 'process raft request' (duration: 138.954271ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:29.782982Z","caller":"traceutil/trace.go:171","msg":"trace[418991553] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"139.238396ms","start":"2026-06-27T05:38:29.643731Z","end":"2026-06-27T05:38:29.782969Z","steps":["trace[418991553] 'process raft request' (duration: 139.202514ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:29.783255Z","caller":"traceutil/trace.go:171","msg":"trace[627166787] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"140.778655ms","start":"2026-06-27T05:38:29.642464Z","end":"2026-06-27T05:38:29.783243Z","steps":["trace[627166787] 'process raft request' (duration: 140.398775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:33.970365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.101806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-27T05:38:33.970535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.018884ms","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-06-27T05:38:33.970528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.88986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:38:33.970592Z","caller":"traceutil/trace.go:171","msg":"trace[1161597494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:449; }","duration":"174.100569ms","start":"2026-06-27T05:38:33.796465Z","end":"2026-06-27T05:38:33.970566Z","steps":["trace[1161597494] 'range keys from in-memory index tree' (duration: 173.930449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:33.970644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.519328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-27T05:38:33.970692Z","caller":"traceutil/trace.go:171","msg":"trace[2084019160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:449; }","duration":"168.591813ms","start":"2026-06-27T05:38:33.802086Z","end":"2026-06-27T05:38:33.970677Z","steps":["trace[2084019160] 'range keys from in-memory index tree' (duration: 168.453645ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:33.970605Z","caller":"traceutil/trace.go:171","msg":"trace[1880553659] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:449; }","duration":"132.006415ms","start":"2026-06-27T05:38:33.838577Z","end":"2026-06-27T05:38:33.970584Z","steps":["trace[1880553659] 'range keys from in-memory index tree' (duration: 131.785334ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:33.971074Z","caller":"traceutil/trace.go:171","msg":"trace[1543104623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:449; }","duration":"168.247184ms","start":"2026-06-27T05:38:33.802189Z","end":"2026-06-27T05:38:33.970436Z","steps":["trace[1543104623] 'range keys from in-memory index tree' (duration: 167.963999ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:36.379089Z","caller":"traceutil/trace.go:171","msg":"trace[1282490529] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"350.901759ms","start":"2026-06-27T05:38:36.02815Z","end":"2026-06-27T05:38:36.379052Z","steps":["trace[1282490529] 'process raft request' (duration: 350.625304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:36.379261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.028137Z","time spent":"351.039657ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:36.379075Z","caller":"traceutil/trace.go:171","msg":"trace[1788311417] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"314.785145ms","start":"2026-06-27T05:38:36.064263Z","end":"2026-06-27T05:38:36.379048Z","steps":["trace[1788311417] 'read index received' (duration: 314.414414ms)","trace[1788311417] 'applied index is now lower than readState.Index' (duration: 369.061µs)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:38:36.379365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.786782ms","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-06-27T05:38:36.379472Z","caller":"traceutil/trace.go:171","msg":"trace[1406846652] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:489; }","duration":"344.990343ms","start":"2026-06-27T05:38:36.03446Z","end":"2026-06-27T05:38:36.379451Z","steps":["trace[1406846652] 'agreement among raft nodes before linearized reading' (duration: 344.714838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:36.379526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.034398Z","time spent":"345.116881ms","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-06-27T05:38:36.384669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.173519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:38:36.384748Z","caller":"traceutil/trace.go:171","msg":"trace[811558324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"128.293616ms","start":"2026-06-27T05:38:36.256436Z","end":"2026-06-27T05:38:36.38473Z","steps":["trace[811558324] 'agreement among raft nodes before linearized reading' (duration: 128.137517ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:36.385087Z","caller":"traceutil/trace.go:171","msg":"trace[1279974169] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"315.333325ms","start":"2026-06-27T05:38:36.069731Z","end":"2026-06-27T05:38:36.385064Z","steps":["trace[1279974169] 'process raft request' (duration: 314.69612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:36.385314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.069701Z","time spent":"315.483344ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:36.703946Z","caller":"traceutil/trace.go:171","msg":"trace[7652213] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"134.732852ms","start":"2026-06-27T05:38:36.569123Z","end":"2026-06-27T05:38:36.703856Z","steps":["trace[7652213] 'process raft request' (duration: 134.610295ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:36.75845Z","caller":"traceutil/trace.go:171","msg":"trace[1227514163] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"187.598621ms","start":"2026-06-27T05:38:36.570824Z","end":"2026-06-27T05:38:36.758423Z","steps":["trace[1227514163] 'process raft request' (duration: 187.447253ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:36.758568Z","caller":"traceutil/trace.go:171","msg":"trace[204134178] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"183.281062ms","start":"2026-06-27T05:38:36.575266Z","end":"2026-06-27T05:38:36.758547Z","steps":["trace[204134178] 'process raft request' (duration: 183.144525ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:36.758595Z","caller":"traceutil/trace.go:171","msg":"trace[211391824] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"187.667054ms","start":"2026-06-27T05:38:36.570923Z","end":"2026-06-27T05:38:36.75859Z","steps":["trace[211391824] 'process raft request' (duration: 187.453553ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:36.758643Z","caller":"traceutil/trace.go:171","msg":"trace[625574239] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"150.905964ms","start":"2026-06-27T05:38:36.607733Z","end":"2026-06-27T05:38:36.758639Z","steps":["trace[625574239] 'process raft request' (duration: 150.779387ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:36.758641Z","caller":"traceutil/trace.go:171","msg":"trace[1102801172] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"177.25965ms","start":"2026-06-27T05:38:36.581355Z","end":"2026-06-27T05:38:36.758614Z","steps":["trace[1102801172] 'process raft request' (duration: 177.125272ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:36.75869Z","caller":"traceutil/trace.go:171","msg":"trace[200128209] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"150.895184ms","start":"2026-06-27T05:38:36.60779Z","end":"2026-06-27T05:38:36.758686Z","steps":["trace[200128209] 'process raft request' (duration: 150.781167ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:36.758744Z","caller":"traceutil/trace.go:171","msg":"trace[1341881690] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"150.831881ms","start":"2026-06-27T05:38:36.607908Z","end":"2026-06-27T05:38:36.75874Z","steps":["trace[1341881690] 'process raft request' (duration: 150.748976ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:36.758855Z","caller":"traceutil/trace.go:171","msg":"trace[1923824780] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"130.424393ms","start":"2026-06-27T05:38:36.628426Z","end":"2026-06-27T05:38:36.758851Z","steps":["trace[1923824780] 'process raft request' (duration: 130.272914ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:36.758875Z","caller":"traceutil/trace.go:171","msg":"trace[425752174] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"105.756901ms","start":"2026-06-27T05:38:36.653104Z","end":"2026-06-27T05:38:36.758861Z","steps":["trace[425752174] 'process raft request' (duration: 105.643295ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:36.758931Z","caller":"traceutil/trace.go:171","msg":"trace[2144920123] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"179.245269ms","start":"2026-06-27T05:38:36.579661Z","end":"2026-06-27T05:38:36.758906Z","steps":["trace[2144920123] 'process raft request' (duration: 178.789864ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:37.053973Z","caller":"traceutil/trace.go:171","msg":"trace[1830990901] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"344.670489ms","start":"2026-06-27T05:38:36.709243Z","end":"2026-06-27T05:38:37.053914Z","steps":["trace[1830990901] 'process raft request' (duration: 275.786431ms)","trace[1830990901] 'compare' (duration: 68.594972ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:38:37.054018Z","caller":"traceutil/trace.go:171","msg":"trace[1820799288] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"343.897656ms","start":"2026-06-27T05:38:36.710094Z","end":"2026-06-27T05:38:37.053992Z","steps":["trace[1820799288] 'process raft request' (duration: 343.695985ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:37.054115Z","caller":"traceutil/trace.go:171","msg":"trace[445178003] transaction","detail":"{read_only:false; number_of_response:1; response_revision:503; }","duration":"342.928403ms","start":"2026-06-27T05:38:36.711166Z","end":"2026-06-27T05:38:37.054094Z","steps":["trace[445178003] 'process raft request' (duration: 342.782255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:37.054179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.709216Z","time spent":"344.87343ms","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-27T05:38:37.054188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.710079Z","time spent":"344.018003ms","remote":"127.0.0.1:36208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:38:37.054209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.711157Z","time spent":"343.000747ms","remote":"127.0.0.1:36208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:38:38.245861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154596,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:38.268709Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.28328553s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-27T05:38:38.415229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.184712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-27T05:38:38.415591Z","caller":"traceutil/trace.go:171","msg":"trace[467079614] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.645881033s","start":"2026-06-27T05:38:36.769664Z","end":"2026-06-27T05:38:38.415545Z","steps":["trace[467079614] 'process raft request' (duration: 1.645671312s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:38.415716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.76966Z","time spent":"1.64599757s","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-27T05:38:38.415857Z","caller":"traceutil/trace.go:171","msg":"trace[397060829] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.646017581s","start":"2026-06-27T05:38:36.769812Z","end":"2026-06-27T05:38:38.41583Z","steps":["trace[397060829] 'process raft request' (duration: 1.645626359s)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:38.416021Z","caller":"traceutil/trace.go:171","msg":"trace[907820227] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.646491767s","start":"2026-06-27T05:38:36.769514Z","end":"2026-06-27T05:38:38.416006Z","steps":["trace[907820227] 'process raft request' (duration: 1.499454018s)","trace[907820227] 'compare' (duration: 145.716525ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:38:38.41605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.769809Z","time spent":"1.646161449s","remote":"127.0.0.1:36208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:38.416121Z","caller":"traceutil/trace.go:171","msg":"trace[1733291013] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.646249864s","start":"2026-06-27T05:38:36.769859Z","end":"2026-06-27T05:38:38.416109Z","steps":["trace[1733291013] 'process raft request' (duration: 1.64563827s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:38.416232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.769855Z","time spent":"1.646309927s","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-27T05:38:38.416084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.769497Z","time spent":"1.646561101s","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-27T05:38:38.416376Z","caller":"traceutil/trace.go:171","msg":"trace[953612547] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.645676413s","start":"2026-06-27T05:38:36.770175Z","end":"2026-06-27T05:38:38.415852Z","steps":["trace[953612547] 'process raft request' (duration: 1.645365855s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:38.416444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.77017Z","time spent":"1.646233614s","remote":"127.0.0.1:36208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:38.415881Z","caller":"traceutil/trace.go:171","msg":"trace[246330469] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.646105056s","start":"2026-06-27T05:38:36.769725Z","end":"2026-06-27T05:38:38.41583Z","steps":["trace[246330469] 'process raft request' (duration: 1.645670752s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:38.416546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.769721Z","time spent":"1.646802635s","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-27T05:38:38.416615Z","caller":"traceutil/trace.go:171","msg":"trace[1794823551] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.63548664s","start":"2026-06-27T05:38:36.78109Z","end":"2026-06-27T05:38:38.416576Z","steps":["trace[1794823551] 'process raft request' (duration: 1.635184093s)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:38.416346Z","caller":"traceutil/trace.go:171","msg":"trace[91990805] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.636961712s","start":"2026-06-27T05:38:36.779375Z","end":"2026-06-27T05:38:38.416336Z","steps":["trace[91990805] 'process raft request' (duration: 1.636312875s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:38.416955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.781082Z","time spent":"1.635639008s","remote":"127.0.0.1:36208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-27T05:38:38.416904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.779354Z","time spent":"1.63747467s","remote":"127.0.0.1:36208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:38.417111Z","caller":"traceutil/trace.go:171","msg":"trace[1888678349] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.639568575s","start":"2026-06-27T05:38:36.776648Z","end":"2026-06-27T05:38:38.416216Z","steps":["trace[1888678349] 'process raft request' (duration: 1.638963091s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:38.417299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:36.776631Z","time spent":"1.640624264s","remote":"127.0.0.1:36208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:38.417686Z","caller":"traceutil/trace.go:171","msg":"trace[1988930096] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:526; }","duration":"672.566158ms","start":"2026-06-27T05:38:37.745108Z","end":"2026-06-27T05:38:38.417674Z","steps":["trace[1988930096] 'read index received' (duration: 523.872005ms)","trace[1988930096] 'applied index is now lower than readState.Index' (duration: 148.693373ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:38:38.417869Z","caller":"traceutil/trace.go:171","msg":"trace[438777635] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.39067219s","start":"2026-06-27T05:38:37.027187Z","end":"2026-06-27T05:38:38.417859Z","steps":["trace[438777635] 'process raft request' (duration: 1.390277398s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:38.417925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:37.027165Z","time spent":"1.390728993s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:38.418043Z","caller":"traceutil/trace.go:171","msg":"trace[1910568395] transaction","detail":"{read_only:false; number_of_response:1; response_revision:513; }","duration":"1.351756653s","start":"2026-06-27T05:38:37.066268Z","end":"2026-06-27T05:38:38.418025Z","steps":["trace[1910568395] 'process raft request' (duration: 1.351277556s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:38.418098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.996522ms","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-06-27T05:38:38.418135Z","caller":"traceutil/trace.go:171","msg":"trace[941211079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"673.067016ms","start":"2026-06-27T05:38:37.745057Z","end":"2026-06-27T05:38:38.418124Z","steps":["trace[941211079] 'agreement among raft nodes before linearized reading' (duration: 672.95402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:38.418141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:37.066249Z","time spent":"1.351835647s","remote":"127.0.0.1:36208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:38:38.418166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:37.745036Z","time spent":"673.121019ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-27T05:38:38.418319Z","caller":"traceutil/trace.go:171","msg":"trace[411190308] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.349161938s","start":"2026-06-27T05:38:37.069145Z","end":"2026-06-27T05:38:38.418307Z","steps":["trace[411190308] 'process raft request' (duration: 1.348442938s)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:38.418312Z","caller":"traceutil/trace.go:171","msg":"trace[1401551248] transaction","detail":"{read_only:false; number_of_response:0; response_revision:513; }","duration":"1.357775088s","start":"2026-06-27T05:38:37.060524Z","end":"2026-06-27T05:38:38.418299Z","steps":["trace[1401551248] 'process raft request' (duration: 1.356995094s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:38.41838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:37.069136Z","time spent":"1.349209041s","remote":"127.0.0.1:36208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:38:38.418409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.376705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-27T05:38:38.418097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.263859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-27T05:38:38.418442Z","caller":"traceutil/trace.go:171","msg":"trace[99336153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"161.434228ms","start":"2026-06-27T05:38:38.256999Z","end":"2026-06-27T05:38:38.418433Z","steps":["trace[99336153] 'agreement among raft nodes before linearized reading' (duration: 161.386315ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:38.418451Z","caller":"traceutil/trace.go:171","msg":"trace[1607493871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"349.649211ms","start":"2026-06-27T05:38:38.068794Z","end":"2026-06-27T05:38:38.418443Z","steps":["trace[1607493871] 'agreement among raft nodes before linearized reading' (duration: 349.243498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:38.418478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:38.068777Z","time spent":"349.692844ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-27T05:38:38.418581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.780779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-27T05:38:38.418612Z","caller":"traceutil/trace.go:171","msg":"trace[47975064] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:514; }","duration":"210.860054ms","start":"2026-06-27T05:38:38.207741Z","end":"2026-06-27T05:38:38.418601Z","steps":["trace[47975064] 'agreement among raft nodes before linearized reading' (duration: 210.808771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:38.418401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:37.060506Z","time spent":"1.357854912s","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-27T05:38:38.739882Z","caller":"traceutil/trace.go:171","msg":"trace[180260617] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"137.511644ms","start":"2026-06-27T05:38:38.60235Z","end":"2026-06-27T05:38:38.739862Z","steps":["trace[180260617] 'process raft request' (duration: 137.464671ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:38.740311Z","caller":"traceutil/trace.go:171","msg":"trace[1366671863] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"139.206539ms","start":"2026-06-27T05:38:38.601087Z","end":"2026-06-27T05:38:38.740293Z","steps":["trace[1366671863] 'process raft request' (duration: 97.250083ms)","trace[1366671863] 'compare' (duration: 41.329561ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:38:42.661796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.308534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-27T05:38:42.661849Z","caller":"traceutil/trace.go:171","msg":"trace[1280141183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"159.39342ms","start":"2026-06-27T05:38:42.502444Z","end":"2026-06-27T05:38:42.661838Z","steps":["trace[1280141183] 'range keys from in-memory index tree' (duration: 159.195039ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:42.661901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.39754ms","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-06-27T05:38:42.661949Z","caller":"traceutil/trace.go:171","msg":"trace[450948437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"159.447033ms","start":"2026-06-27T05:38:42.502485Z","end":"2026-06-27T05:38:42.661932Z","steps":["trace[450948437] 'range keys from in-memory index tree' (duration: 159.305876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:42.661977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.287155ms","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-06-27T05:38:42.66199Z","caller":"traceutil/trace.go:171","msg":"trace[1873730992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"159.311576ms","start":"2026-06-27T05:38:42.502675Z","end":"2026-06-27T05:38:42.661986Z","steps":["trace[1873730992] 'range keys from in-memory index tree' (duration: 159.248532ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:42.661797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.299804ms","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-06-27T05:38:42.662072Z","caller":"traceutil/trace.go:171","msg":"trace[500091625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"159.594021ms","start":"2026-06-27T05:38:42.502473Z","end":"2026-06-27T05:38:42.662067Z","steps":["trace[500091625] 'range keys from in-memory index tree' (duration: 159.130805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:43.213229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.148279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-27T05:38:43.213471Z","caller":"traceutil/trace.go:171","msg":"trace[370388592] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"546.875344ms","start":"2026-06-27T05:38:42.66656Z","end":"2026-06-27T05:38:43.213435Z","steps":["trace[370388592] 'process raft request' (duration: 260.420547ms)","trace[370388592] 'compare' (duration: 284.656552ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:38:43.213617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:42.666544Z","time spent":"547.002632ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:43.213673Z","caller":"traceutil/trace.go:171","msg":"trace[969091493] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"538.491829ms","start":"2026-06-27T05:38:42.675171Z","end":"2026-06-27T05:38:43.213663Z","steps":["trace[969091493] 'process raft request' (duration: 538.16845ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:43.213749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:42.675156Z","time spent":"538.554172ms","remote":"127.0.0.1:36420","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-06-27T05:38:43.213492Z","caller":"traceutil/trace.go:171","msg":"trace[2018024289] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"532.425578ms","start":"2026-06-27T05:38:42.681048Z","end":"2026-06-27T05:38:43.213473Z","steps":["trace[2018024289] 'process raft request' (duration: 532.378785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:43.213963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:42.681024Z","time spent":"532.911606ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:43.218111Z","caller":"traceutil/trace.go:171","msg":"trace[1760274103] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"501.74155ms","start":"2026-06-27T05:38:42.71635Z","end":"2026-06-27T05:38:43.218092Z","steps":["trace[1760274103] 'process raft request' (duration: 501.119064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:43.218326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:42.716331Z","time spent":"501.831125ms","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:38:46.381962Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:46.88231Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:47.382989Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:47.883445Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:48.384586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:48.884867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:49.255123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000576751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-27T05:38:49.255218Z","caller":"traceutil/trace.go:171","msg":"trace[2107487478] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000714739s","start":"2026-06-27T05:38:47.25448Z","end":"2026-06-27T05:38:49.255195Z","steps":["trace[2107487478] 'agreement among raft nodes before linearized reading' (duration: 2.000593132s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:49.255271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:47.254468Z","time spent":"2.000791924s","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/27 05:38:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-27T05:38:49.385818Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:49.885983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:50.386958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:50.724743Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.593244154s","expected-duration":"1s"} {"level":"info","ts":"2026-06-27T05:38:50.726158Z","caller":"traceutil/trace.go:171","msg":"trace[184879457] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"4.903773115s","start":"2026-06-27T05:38:45.822358Z","end":"2026-06-27T05:38:50.726131Z","steps":["trace[184879457] 'process raft request' (duration: 4.902945794s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:50.726346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:45.82233Z","time spent":"4.903921444s","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:38:51.255685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000096061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-27T05:38:51.255774Z","caller":"traceutil/trace.go:171","msg":"trace[199782186] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000340937s","start":"2026-06-27T05:38:49.255409Z","end":"2026-06-27T05:38:51.25575Z","steps":["trace[199782186] 'agreement among raft nodes before linearized reading' (duration: 2.00023384s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:51.255839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:49.255385Z","time spent":"2.000444003s","remote":"127.0.0.1:36150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/27 05:38:51 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-27T05:38:51.702352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998779167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-27T05:38:51.702454Z","caller":"traceutil/trace.go:171","msg":"trace[557035364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.998899665s","start":"2026-06-27T05:38:46.703531Z","end":"2026-06-27T05:38:51.702431Z","steps":["trace[557035364] 'agreement among raft nodes before linearized reading' (duration: 4.998777917s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:51.702501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:46.703435Z","time spent":"4.999056765s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/06/27 05:38:51 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-27T05:38:52.245848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999313094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-27T05:38:52.246008Z","caller":"traceutil/trace.go:171","msg":"trace[684587766] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999450302s","start":"2026-06-27T05:38:47.24647Z","end":"2026-06-27T05:38:52.245921Z","steps":["trace[684587766] 'agreement among raft nodes before linearized reading' (duration: 4.999327174s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:52.246069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:47.246457Z","time spent":"4.999600291s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-27T05:38:52.368769Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.643603787s","expected-duration":"1s"} {"level":"info","ts":"2026-06-27T05:38:52.369331Z","caller":"traceutil/trace.go:171","msg":"trace[1077692197] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.935273274s","start":"2026-06-27T05:38:50.434032Z","end":"2026-06-27T05:38:52.369305Z","steps":["trace[1077692197] 'process raft request' (duration: 1.935149446s)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:52.369404Z","caller":"traceutil/trace.go:171","msg":"trace[1034424595] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"3.584978036s","start":"2026-06-27T05:38:48.784284Z","end":"2026-06-27T05:38:52.369262Z","steps":["trace[1034424595] 'process raft request' (duration: 3.58471394s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:52.369547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:50.433997Z","time spent":"1.935415923s","remote":"127.0.0.1:36420","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-06-27T05:38:52.369544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:48.784264Z","time spent":"3.585179749s","remote":"127.0.0.1:36420","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-06-27T05:38:52.369335Z","caller":"traceutil/trace.go:171","msg":"trace[1473937665] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:631; }","duration":"6.487390887s","start":"2026-06-27T05:38:45.881871Z","end":"2026-06-27T05:38:52.369262Z","steps":["trace[1473937665] 'read index received' (duration: 4.843257327s)","trace[1473937665] 'applied index is now lower than readState.Index' (duration: 1.64413212s)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:38:52.369555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.487666204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cwmbt\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-27T05:38:52.370363Z","caller":"traceutil/trace.go:171","msg":"trace[18883846] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cwmbt; range_end:; response_count:1; response_revision:606; }","duration":"6.488004836s","start":"2026-06-27T05:38:45.881843Z","end":"2026-06-27T05:38:52.369848Z","steps":["trace[18883846] 'agreement among raft nodes before linearized reading' (duration: 6.487536967s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:52.371058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:45.881825Z","time spent":"6.488606693s","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-cwmbt\" "} {"level":"warn","ts":"2026-06-27T05:38:53.26252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000287389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-27T05:38:53.262623Z","caller":"traceutil/trace.go:171","msg":"trace[1395660847] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000441728s","start":"2026-06-27T05:38:51.262159Z","end":"2026-06-27T05:38:53.262601Z","steps":["trace[1395660847] 'agreement among raft nodes before linearized reading' (duration: 2.0003146s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:53.262673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:51.262142Z","time spent":"2.000519633s","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/27 05:38:53 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-27T05:38:54.62318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.22756938s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-27T05:38:54.623454Z","caller":"traceutil/trace.go:171","msg":"trace[1658821245] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"2.238282035s","start":"2026-06-27T05:38:52.385135Z","end":"2026-06-27T05:38:54.623417Z","steps":["trace[1658821245] 'process raft request' (duration: 2.238166078s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:54.623564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:52.385099Z","time spent":"2.238410014s","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:54.623687Z","caller":"traceutil/trace.go:171","msg":"trace[371964569] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"3.834694314s","start":"2026-06-27T05:38:50.788965Z","end":"2026-06-27T05:38:54.623659Z","steps":["trace[371964569] 'process raft request' (duration: 1.606573409s)","trace[371964569] 'compare' (duration: 2.227071929s)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:38:54.623714Z","caller":"traceutil/trace.go:171","msg":"trace[1619629784] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"2.254037203s","start":"2026-06-27T05:38:52.369649Z","end":"2026-06-27T05:38:54.623687Z","steps":["trace[1619629784] 'read index received' (duration: 25.871205ms)","trace[1619629784] 'applied index is now lower than readState.Index' (duration: 2.228163218s)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:38:54.623771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:50.788944Z","time spent":"3.834791651s","remote":"127.0.0.1:36402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-27T05:38:54.624003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.361633329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18bcd98250ea6753\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-06-27T05:38:54.624043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.378437592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-27T05:38:54.624077Z","caller":"traceutil/trace.go:171","msg":"trace[563109001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"7.378496215s","start":"2026-06-27T05:38:47.245571Z","end":"2026-06-27T05:38:54.624067Z","steps":["trace[563109001] 'agreement among raft nodes before linearized reading' (duration: 7.378375877s)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:54.624072Z","caller":"traceutil/trace.go:171","msg":"trace[114304296] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18bcd98250ea6753; range_end:; response_count:1; response_revision:608; }","duration":"3.361712884s","start":"2026-06-27T05:38:51.262339Z","end":"2026-06-27T05:38:54.624052Z","steps":["trace[114304296] 'agreement among raft nodes before linearized reading' (duration: 3.361456588s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:54.624032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.377939752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-27T05:38:54.624116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:47.245555Z","time spent":"7.378553488s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-27T05:38:54.624129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:51.262333Z","time spent":"3.361783279s","remote":"127.0.0.1:36236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18bcd98250ea6753\" "} {"level":"warn","ts":"2026-06-27T05:38:54.6241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.415521583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-27T05:38:54.624184Z","caller":"traceutil/trace.go:171","msg":"trace[311494583] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:608; }","duration":"6.415644561s","start":"2026-06-27T05:38:48.208525Z","end":"2026-06-27T05:38:54.62417Z","steps":["trace[311494583] 'agreement among raft nodes before linearized reading' (duration: 6.415425127s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:54.624222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:48.208507Z","time spent":"6.415706785s","remote":"127.0.0.1:36188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"info","ts":"2026-06-27T05:38:54.624196Z","caller":"traceutil/trace.go:171","msg":"trace[501388162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"7.378090211s","start":"2026-06-27T05:38:47.246054Z","end":"2026-06-27T05:38:54.624144Z","steps":["trace[501388162] 'agreement among raft nodes before linearized reading' (duration: 7.377826134s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:54.624292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:47.246048Z","time spent":"7.378234799s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-27T05:38:55.12443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154792,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:55.263225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000344498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-27T05:38:55.263302Z","caller":"traceutil/trace.go:171","msg":"trace[1203782155] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000468476s","start":"2026-06-27T05:38:53.262816Z","end":"2026-06-27T05:38:55.263284Z","steps":["trace[1203782155] 'agreement among raft nodes before linearized reading' (duration: 2.00036956s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:55.263361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:53.262791Z","time spent":"2.000559532s","remote":"127.0.0.1:36150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/27 05:38:55 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-27T05:38:55.625537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154792,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:55.65264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000494018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-27T05:38:55.652724Z","caller":"traceutil/trace.go:171","msg":"trace[493803380] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000660199s","start":"2026-06-27T05:38:53.652043Z","end":"2026-06-27T05:38:55.652703Z","steps":["trace[493803380] 'agreement among raft nodes before linearized reading' (duration: 2.000532761s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:55.652771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:53.65202Z","time spent":"2.000741255s","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/27 05:38:55 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-27T05:38:56.125849Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154792,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:38:56.185191Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.258609898s","expected-duration":"1s"} {"level":"info","ts":"2026-06-27T05:38:56.189512Z","caller":"traceutil/trace.go:171","msg":"trace[321052526] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"1.557963007s","start":"2026-06-27T05:38:54.631527Z","end":"2026-06-27T05:38:56.18949Z","steps":["trace[321052526] 'process raft request' (duration: 1.557906514s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:56.189561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:54.625823Z","time spent":"1.56373413s","remote":"127.0.0.1:36188","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-27T05:38:56.189554Z","caller":"traceutil/trace.go:171","msg":"trace[1727746748] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.560976427s","start":"2026-06-27T05:38:54.628537Z","end":"2026-06-27T05:38:56.189514Z","steps":["trace[1727746748] 'process raft request' (duration: 1.560833308s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:56.189637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:54.63151Z","time spent":"1.558051934s","remote":"127.0.0.1:36236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:38:56.189704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:54.628511Z","time spent":"1.561108796s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:38:56.189711Z","caller":"traceutil/trace.go:171","msg":"trace[2128906268] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:636; }","duration":"1.565923347s","start":"2026-06-27T05:38:54.623765Z","end":"2026-06-27T05:38:56.189688Z","steps":["trace[2128906268] 'read index received' (duration: 1.561625564s)","trace[2128906268] 'applied index is now lower than readState.Index' (duration: 4.296483ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:38:56.18994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.561069903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-06-27T05:38:56.189961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.485878212s","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-06-27T05:38:56.189984Z","caller":"traceutil/trace.go:171","msg":"trace[1231138390] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:612; }","duration":"1.561147298s","start":"2026-06-27T05:38:54.628826Z","end":"2026-06-27T05:38:56.189973Z","steps":["trace[1231138390] 'agreement among raft nodes before linearized reading' (duration: 1.561040411s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:56.189973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.868398057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-27T05:38:56.189998Z","caller":"traceutil/trace.go:171","msg":"trace[1210861958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:612; }","duration":"2.485950617s","start":"2026-06-27T05:38:53.704037Z","end":"2026-06-27T05:38:56.189988Z","steps":["trace[1210861958] 'agreement among raft nodes before linearized reading' (duration: 2.485817368s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:56.190014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.322619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-27T05:38:56.190026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:54.628814Z","time spent":"1.561202512s","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-06-27T05:38:56.190031Z","caller":"traceutil/trace.go:171","msg":"trace[1073160275] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:612; }","duration":"1.868504753s","start":"2026-06-27T05:38:54.321516Z","end":"2026-06-27T05:38:56.190021Z","steps":["trace[1073160275] 'agreement among raft nodes before linearized reading' (duration: 1.868336722s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:56.190035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:53.704016Z","time spent":"2.48601171s","remote":"127.0.0.1:36420","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-06-27T05:38:56.190042Z","caller":"traceutil/trace.go:171","msg":"trace[1903318764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"919.392694ms","start":"2026-06-27T05:38:55.270641Z","end":"2026-06-27T05:38:56.190034Z","steps":["trace[1903318764] 'agreement among raft nodes before linearized reading' (duration: 919.33765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:56.190066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:55.270617Z","time spent":"919.444797ms","remote":"127.0.0.1:36134","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-06-27T05:38:56.190064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:54.3215Z","time spent":"1.868554236s","remote":"127.0.0.1:36508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-06-27T05:38:56.190146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.928660438s","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-06-27T05:38:56.190182Z","caller":"traceutil/trace.go:171","msg":"trace[1402650299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"1.928732943s","start":"2026-06-27T05:38:54.261441Z","end":"2026-06-27T05:38:56.190174Z","steps":["trace[1402650299] 'agreement among raft nodes before linearized reading' (duration: 1.928608065s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:56.190512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:54.261423Z","time spent":"1.929078774s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-27T05:38:56.189942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.86866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-27T05:38:56.190603Z","caller":"traceutil/trace.go:171","msg":"trace[39741054] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:612; }","duration":"803.575625ms","start":"2026-06-27T05:38:55.387012Z","end":"2026-06-27T05:38:56.190587Z","steps":["trace[39741054] 'agreement among raft nodes before linearized reading' (duration: 802.86457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:56.190657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:55.386991Z","time spent":"803.655091ms","remote":"127.0.0.1:36526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-06-27T05:38:56.190254Z","caller":"traceutil/trace.go:171","msg":"trace[1291519082] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.55830314s","start":"2026-06-27T05:38:54.631941Z","end":"2026-06-27T05:38:56.190244Z","steps":["trace[1291519082] 'process raft request' (duration: 1.557525581s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:56.190774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:54.631927Z","time spent":"1.558797871s","remote":"127.0.0.1:36420","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-06-27T05:38:56.190581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.561373482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cwmbt\" ","response":"range_response_count:1 size:17145"} {"level":"info","ts":"2026-06-27T05:38:56.191887Z","caller":"traceutil/trace.go:171","msg":"trace[242079969] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cwmbt; range_end:; response_count:1; response_revision:612; }","duration":"1.562720318s","start":"2026-06-27T05:38:54.629134Z","end":"2026-06-27T05:38:56.191855Z","steps":["trace[242079969] 'agreement among raft nodes before linearized reading' (duration: 1.561270416s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:56.191941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:54.629126Z","time spent":"1.562800373s","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17168,"request content":"key:\"/registry/pods/kube-system/cilium-cwmbt\" "} {"level":"warn","ts":"2026-06-27T05:38:58.693473Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662154854,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-27T05:38:58.829868Z","caller":"traceutil/trace.go:171","msg":"trace[1984921306] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"698.009785ms","start":"2026-06-27T05:38:58.131826Z","end":"2026-06-27T05:38:58.829836Z","steps":["trace[1984921306] 'process raft request' (duration: 697.871857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:58.830057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:58.131802Z","time spent":"698.152985ms","remote":"127.0.0.1:36208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-27T05:38:58.834257Z","caller":"traceutil/trace.go:171","msg":"trace[784507534] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:676; }","duration":"640.820937ms","start":"2026-06-27T05:38:58.193414Z","end":"2026-06-27T05:38:58.834234Z","steps":["trace[784507534] 'read index received' (duration: 636.34295ms)","trace[784507534] 'applied index is now lower than readState.Index' (duration: 4.476927ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:38:58.834304Z","caller":"traceutil/trace.go:171","msg":"trace[1909061001] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"501.782798ms","start":"2026-06-27T05:38:58.332505Z","end":"2026-06-27T05:38:58.834288Z","steps":["trace[1909061001] 'process raft request' (duration: 501.515341ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:58.834289Z","caller":"traceutil/trace.go:171","msg":"trace[923324037] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"303.598745ms","start":"2026-06-27T05:38:58.530674Z","end":"2026-06-27T05:38:58.834273Z","steps":["trace[923324037] 'process raft request' (duration: 303.442235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:58.834395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:58.332471Z","time spent":"501.867053ms","remote":"127.0.0.1:36208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-27T05:38:58.83445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:58.530654Z","time spent":"303.739284ms","remote":"127.0.0.1:36208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-27T05:38:58.834511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.236ms","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-06-27T05:38:58.834524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.72889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:38:58.834551Z","caller":"traceutil/trace.go:171","msg":"trace[1620015492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"577.755421ms","start":"2026-06-27T05:38:58.256787Z","end":"2026-06-27T05:38:58.834542Z","steps":["trace[1620015492] 'agreement among raft nodes before linearized reading' (duration: 577.712388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:58.834546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.040501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-27T05:38:58.834581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:58.256751Z","time spent":"577.823456ms","remote":"127.0.0.1:36134","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-06-27T05:38:58.834539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.896609ms","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-06-27T05:38:58.834625Z","caller":"traceutil/trace.go:171","msg":"trace[805919399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"641.244494ms","start":"2026-06-27T05:38:58.19336Z","end":"2026-06-27T05:38:58.834604Z","steps":["trace[805919399] 'agreement among raft nodes before linearized reading' (duration: 640.980787ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:38:58.834652Z","caller":"traceutil/trace.go:171","msg":"trace[1499911733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"616.049278ms","start":"2026-06-27T05:38:58.218577Z","end":"2026-06-27T05:38:58.834627Z","steps":["trace[1499911733] 'agreement among raft nodes before linearized reading' (duration: 615.817083ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:58.834684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:58.193333Z","time spent":"641.33646ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-27T05:38:58.834453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.71091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-27T05:38:58.834701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:58.218565Z","time spent":"616.124353ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-27T05:38:58.834737Z","caller":"traceutil/trace.go:171","msg":"trace[1294597613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"641.02499ms","start":"2026-06-27T05:38:58.1937Z","end":"2026-06-27T05:38:58.834725Z","steps":["trace[1294597613] 'agreement among raft nodes before linearized reading' (duration: 640.669447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:58.834778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:58.193685Z","time spent":"641.084264ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-27T05:38:58.834552Z","caller":"traceutil/trace.go:171","msg":"trace[565304161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"616.283913ms","start":"2026-06-27T05:38:58.218253Z","end":"2026-06-27T05:38:58.834537Z","steps":["trace[565304161] 'agreement among raft nodes before linearized reading' (duration: 616.223659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:58.834858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:58.218247Z","time spent":"616.598194ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-27T05:38:58.834597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.686196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-27T05:38:58.834949Z","caller":"traceutil/trace.go:171","msg":"trace[1284741240] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:649; }","duration":"626.057671ms","start":"2026-06-27T05:38:58.20888Z","end":"2026-06-27T05:38:58.834937Z","steps":["trace[1284741240] 'agreement among raft nodes before linearized reading' (duration: 625.688617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:38:58.834983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:38:58.208869Z","time spent":"626.105113ms","remote":"127.0.0.1:36188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"info","ts":"2026-06-27T05:39:10.610287Z","caller":"traceutil/trace.go:171","msg":"trace[1258829753] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"598.040811ms","start":"2026-06-27T05:39:10.012222Z","end":"2026-06-27T05:39:10.610263Z","steps":["trace[1258829753] 'process raft request' (duration: 597.88096ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:10.610542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:10.012202Z","time spent":"598.261396ms","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5034,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:12.890126Z","caller":"traceutil/trace.go:171","msg":"trace[1074773004] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"108.860487ms","start":"2026-06-27T05:39:12.781231Z","end":"2026-06-27T05:39:12.890091Z","steps":["trace[1074773004] 'process raft request' (duration: 108.686745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:14.154285Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662155219,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:39:14.655508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662155219,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:39:14.811604Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.192657875s","expected-duration":"1s"} {"level":"info","ts":"2026-06-27T05:39:14.812041Z","caller":"traceutil/trace.go:171","msg":"trace[1530721518] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"1.193148989s","start":"2026-06-27T05:39:13.618873Z","end":"2026-06-27T05:39:14.812022Z","steps":["trace[1530721518] 'process raft request' (duration: 1.19302058s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:14.812166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:13.618863Z","time spent":"1.193244015s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:14.829391Z","caller":"traceutil/trace.go:171","msg":"trace[533998057] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:818; }","duration":"1.175385334s","start":"2026-06-27T05:39:13.653981Z","end":"2026-06-27T05:39:14.829367Z","steps":["trace[533998057] 'read index received' (duration: 1.157943881s)","trace[533998057] 'applied index is now lower than readState.Index' (duration: 17.440263ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:14.829479Z","caller":"traceutil/trace.go:171","msg":"trace[442028019] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"799.074702ms","start":"2026-06-27T05:39:14.030378Z","end":"2026-06-27T05:39:14.829453Z","steps":["trace[442028019] 'process raft request' (duration: 798.848266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:14.82957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.275583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-27T05:39:14.829563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175562486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:39:14.82963Z","caller":"traceutil/trace.go:171","msg":"trace[745537419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"573.357589ms","start":"2026-06-27T05:39:14.256262Z","end":"2026-06-27T05:39:14.82962Z","steps":["trace[745537419] 'agreement among raft nodes before linearized reading' (duration: 573.279414ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:14.829657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:14.25625Z","time spent":"573.400302ms","remote":"127.0.0.1:36150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-27T05:39:14.82965Z","caller":"traceutil/trace.go:171","msg":"trace[874210272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"1.175695125s","start":"2026-06-27T05:39:13.65394Z","end":"2026-06-27T05:39:14.829635Z","steps":["trace[874210272] 'agreement among raft nodes before linearized reading' (duration: 1.175572467s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:14.829662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:14.030352Z","time spent":"799.245584ms","remote":"127.0.0.1:36402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:39:14.829695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:13.653886Z","time spent":"1.175799052s","remote":"127.0.0.1:36134","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-06-27T05:39:19.589954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.595259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" ","response":"range_response_count:1 size:452"} {"level":"info","ts":"2026-06-27T05:39:19.590014Z","caller":"traceutil/trace.go:171","msg":"trace[980656138] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-oidc-hmac; range_end:; response_count:1; response_revision:837; }","duration":"127.697576ms","start":"2026-06-27T05:39:19.462303Z","end":"2026-06-27T05:39:19.590001Z","steps":["trace[980656138] 'range keys from in-memory index tree' (duration: 127.484741ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:19.847455Z","caller":"traceutil/trace.go:171","msg":"trace[1446911678] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"253.558613ms","start":"2026-06-27T05:39:19.593879Z","end":"2026-06-27T05:39:19.847437Z","steps":["trace[1446911678] 'process raft request' (duration: 253.452995ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:19.960745Z","caller":"traceutil/trace.go:171","msg":"trace[890307150] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"109.198678ms","start":"2026-06-27T05:39:19.851522Z","end":"2026-06-27T05:39:19.96072Z","steps":["trace[890307150] 'process raft request' (duration: 109.056248ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:19.96776Z","caller":"traceutil/trace.go:171","msg":"trace[853944150] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:877; }","duration":"108.264842ms","start":"2026-06-27T05:39:19.859466Z","end":"2026-06-27T05:39:19.967731Z","steps":["trace[853944150] 'read index received' (duration: 101.126711ms)","trace[853944150] 'applied index is now lower than readState.Index' (duration: 7.137351ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:19.967967Z","caller":"traceutil/trace.go:171","msg":"trace[1950839764] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"114.227251ms","start":"2026-06-27T05:39:19.853725Z","end":"2026-06-27T05:39:19.967953Z","steps":["trace[1950839764] 'process raft request' (duration: 113.910158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:19.968211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.723744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-06-27T05:39:19.968284Z","caller":"traceutil/trace.go:171","msg":"trace[203231400] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:840; }","duration":"108.814391ms","start":"2026-06-27T05:39:19.859452Z","end":"2026-06-27T05:39:19.968266Z","steps":["trace[203231400] 'agreement among raft nodes before linearized reading' (duration: 108.580044ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:21.063478Z","caller":"traceutil/trace.go:171","msg":"trace[1933639869] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:884; }","duration":"105.013264ms","start":"2026-06-27T05:39:20.958428Z","end":"2026-06-27T05:39:21.063441Z","steps":["trace[1933639869] 'read index received' (duration: 104.713402ms)","trace[1933639869] 'applied index is now lower than readState.Index' (duration: 295.561µs)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:21.063592Z","caller":"traceutil/trace.go:171","msg":"trace[1400396632] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"176.993265ms","start":"2026-06-27T05:39:20.886554Z","end":"2026-06-27T05:39:21.063547Z","steps":["trace[1400396632] 'process raft request' (duration: 176.681743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:21.064043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.574103ms","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-06-27T05:39:21.064226Z","caller":"traceutil/trace.go:171","msg":"trace[1334060726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:846; }","duration":"105.834332ms","start":"2026-06-27T05:39:20.958373Z","end":"2026-06-27T05:39:21.064208Z","steps":["trace[1334060726] 'agreement among raft nodes before linearized reading' (duration: 105.254781ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:21.740694Z","caller":"traceutil/trace.go:171","msg":"trace[498741969] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"671.582446ms","start":"2026-06-27T05:39:21.069081Z","end":"2026-06-27T05:39:21.740663Z","steps":["trace[498741969] 'process raft request' (duration: 580.685661ms)","trace[498741969] 'compare' (duration: 90.53055ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:21.740853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:21.069058Z","time spent":"671.707296ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:21.747533Z","caller":"traceutil/trace.go:171","msg":"trace[1001958333] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:885; }","duration":"490.332503ms","start":"2026-06-27T05:39:21.257183Z","end":"2026-06-27T05:39:21.747516Z","steps":["trace[1001958333] 'read index received' (duration: 392.589953ms)","trace[1001958333] 'applied index is now lower than readState.Index' (duration: 97.74163ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:21.747682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.508245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:39:21.747742Z","caller":"traceutil/trace.go:171","msg":"trace[108755516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:850; }","duration":"490.599491ms","start":"2026-06-27T05:39:21.257123Z","end":"2026-06-27T05:39:21.747722Z","steps":["trace[108755516] 'agreement among raft nodes before linearized reading' (duration: 490.489554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:21.747808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:21.257104Z","time spent":"490.686758ms","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-27T05:39:21.748368Z","caller":"traceutil/trace.go:171","msg":"trace[1651555406] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"676.116156ms","start":"2026-06-27T05:39:21.072227Z","end":"2026-06-27T05:39:21.748343Z","steps":["trace[1651555406] 'process raft request' (duration: 675.217943ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:21.74849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:21.072217Z","time spent":"676.206793ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:21.748594Z","caller":"traceutil/trace.go:171","msg":"trace[97623282] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"675.473572ms","start":"2026-06-27T05:39:21.073101Z","end":"2026-06-27T05:39:21.748574Z","steps":["trace[97623282] 'process raft request' (duration: 674.383135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:21.748792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:21.073093Z","time spent":"675.643363ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:21.748787Z","caller":"traceutil/trace.go:171","msg":"trace[351868359] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"677.973778ms","start":"2026-06-27T05:39:21.070767Z","end":"2026-06-27T05:39:21.748741Z","steps":["trace[351868359] 'process raft request' (duration: 676.573199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:21.749061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:21.070739Z","time spent":"678.243358ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:21.983029Z","caller":"traceutil/trace.go:171","msg":"trace[1522704354] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"144.455071ms","start":"2026-06-27T05:39:21.838557Z","end":"2026-06-27T05:39:21.983012Z","steps":["trace[1522704354] 'process raft request' (duration: 144.349824ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:22.116267Z","caller":"traceutil/trace.go:171","msg":"trace[805656809] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"126.401083ms","start":"2026-06-27T05:39:21.989851Z","end":"2026-06-27T05:39:22.116252Z","steps":["trace[805656809] 'process raft request' (duration: 126.366071ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:22.116333Z","caller":"traceutil/trace.go:171","msg":"trace[741312916] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"127.654611ms","start":"2026-06-27T05:39:21.988641Z","end":"2026-06-27T05:39:22.116296Z","steps":["trace[741312916] 'process raft request' (duration: 111.780496ms)","trace[741312916] 'compare' (duration: 15.672221ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:22.116383Z","caller":"traceutil/trace.go:171","msg":"trace[257189782] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"127.598158ms","start":"2026-06-27T05:39:21.988756Z","end":"2026-06-27T05:39:22.116354Z","steps":["trace[257189782] 'process raft request' (duration: 127.435406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:22.492083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.229513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-27T05:39:22.492418Z","caller":"traceutil/trace.go:171","msg":"trace[243948638] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"369.837101ms","start":"2026-06-27T05:39:22.12256Z","end":"2026-06-27T05:39:22.492397Z","steps":["trace[243948638] 'process raft request' (duration: 103.229851ms)","trace[243948638] 'compare' (duration: 266.104755ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:22.492572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:22.122543Z","time spent":"369.998413ms","remote":"127.0.0.1:36236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":719,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-27T05:39:22.52539Z","caller":"traceutil/trace.go:171","msg":"trace[1775066732] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"137.498053ms","start":"2026-06-27T05:39:22.387874Z","end":"2026-06-27T05:39:22.525372Z","steps":["trace[1775066732] 'read index received' (duration: 137.45483ms)","trace[1775066732] 'applied index is now lower than readState.Index' (duration: 42.313µs)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:22.525511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.615941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-06-27T05:39:22.525527Z","caller":"traceutil/trace.go:171","msg":"trace[201334417] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:857; }","duration":"137.686646ms","start":"2026-06-27T05:39:22.387835Z","end":"2026-06-27T05:39:22.525522Z","steps":["trace[201334417] 'agreement among raft nodes before linearized reading' (duration: 137.625021ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:22.525549Z","caller":"traceutil/trace.go:171","msg":"trace[1319512604] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"394.47736ms","start":"2026-06-27T05:39:22.130974Z","end":"2026-06-27T05:39:22.525451Z","steps":["trace[1319512604] 'process raft request' (duration: 394.255184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:22.52572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:22.130956Z","time spent":"394.672573ms","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4761,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:39:22.568694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.90669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-06-27T05:39:22.568767Z","caller":"traceutil/trace.go:171","msg":"trace[1195790864] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:858; }","duration":"179.004427ms","start":"2026-06-27T05:39:22.389748Z","end":"2026-06-27T05:39:22.568752Z","steps":["trace[1195790864] 'agreement among raft nodes before linearized reading' (duration: 178.834835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:22.568743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.83924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-27T05:39:22.568816Z","caller":"traceutil/trace.go:171","msg":"trace[284400335] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:858; }","duration":"119.959978ms","start":"2026-06-27T05:39:22.448839Z","end":"2026-06-27T05:39:22.568799Z","steps":["trace[284400335] 'agreement among raft nodes before linearized reading' (duration: 119.742883ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:22.904231Z","caller":"traceutil/trace.go:171","msg":"trace[327015356] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:897; }","duration":"243.932882ms","start":"2026-06-27T05:39:22.660274Z","end":"2026-06-27T05:39:22.904207Z","steps":["trace[327015356] 'read index received' (duration: 212.628481ms)","trace[327015356] 'applied index is now lower than readState.Index' (duration: 31.303261ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:22.904357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.058431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-27T05:39:22.904388Z","caller":"traceutil/trace.go:171","msg":"trace[1268027645] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:859; }","duration":"244.142226ms","start":"2026-06-27T05:39:22.660237Z","end":"2026-06-27T05:39:22.904379Z","steps":["trace[1268027645] 'agreement among raft nodes before linearized reading' (duration: 244.062641ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:22.904402Z","caller":"traceutil/trace.go:171","msg":"trace[660040035] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"327.495538ms","start":"2026-06-27T05:39:22.576857Z","end":"2026-06-27T05:39:22.904353Z","steps":["trace[660040035] 'process raft request' (duration: 296.046127ms)","trace[660040035] 'compare' (duration: 31.174872ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:22.904663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:22.576833Z","time spent":"327.681422ms","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4761,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:39:23.242253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.838898ms","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-06-27T05:39:23.242313Z","caller":"traceutil/trace.go:171","msg":"trace[134506284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:859; }","duration":"171.945995ms","start":"2026-06-27T05:39:23.070354Z","end":"2026-06-27T05:39:23.2423Z","steps":["trace[134506284] 'range keys from in-memory index tree' (duration: 171.694568ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:23.242322Z","caller":"traceutil/trace.go:171","msg":"trace[158179293] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"170.667164ms","start":"2026-06-27T05:39:23.071632Z","end":"2026-06-27T05:39:23.242299Z","steps":["trace[158179293] 'process raft request' (duration: 115.011444ms)","trace[158179293] 'compare' (duration: 55.540672ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:23.323981Z","caller":"traceutil/trace.go:171","msg":"trace[169813023] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"112.339043ms","start":"2026-06-27T05:39:23.211613Z","end":"2026-06-27T05:39:23.323952Z","steps":["trace[169813023] 'process raft request' (duration: 112.165411ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:23.460853Z","caller":"traceutil/trace.go:171","msg":"trace[1303324721] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:899; }","duration":"204.637721ms","start":"2026-06-27T05:39:23.256191Z","end":"2026-06-27T05:39:23.460829Z","steps":["trace[1303324721] 'read index received' (duration: 67.621532ms)","trace[1303324721] 'applied index is now lower than readState.Index' (duration: 137.015029ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:23.460884Z","caller":"traceutil/trace.go:171","msg":"trace[313718150] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"214.503494ms","start":"2026-06-27T05:39:23.246348Z","end":"2026-06-27T05:39:23.460851Z","steps":["trace[313718150] 'process raft request' (duration: 184.693033ms)","trace[313718150] 'compare' (duration: 29.675511ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:23.461028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.823745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:39:23.461317Z","caller":"traceutil/trace.go:171","msg":"trace[418775078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:862; }","duration":"204.89438ms","start":"2026-06-27T05:39:23.256156Z","end":"2026-06-27T05:39:23.46105Z","steps":["trace[418775078] 'agreement among raft nodes before linearized reading' (duration: 204.820565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:23.843247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.256405ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-27T05:39:23.843415Z","caller":"traceutil/trace.go:171","msg":"trace[184113751] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"230.798514ms","start":"2026-06-27T05:39:23.612604Z","end":"2026-06-27T05:39:23.843403Z","steps":["trace[184113751] 'process raft request' (duration: 230.715248ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:23.843499Z","caller":"traceutil/trace.go:171","msg":"trace[2047220313] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"381.022953ms","start":"2026-06-27T05:39:23.462422Z","end":"2026-06-27T05:39:23.843445Z","steps":["trace[2047220313] 'read index received' (duration: 89.632198ms)","trace[2047220313] 'applied index is now lower than readState.Index' (duration: 291.384084ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:23.843681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.476599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-27T05:39:23.843694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.240299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:39:23.843707Z","caller":"traceutil/trace.go:171","msg":"trace[1971461985] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:863; }","duration":"332.543674ms","start":"2026-06-27T05:39:23.511155Z","end":"2026-06-27T05:39:23.843698Z","steps":["trace[1971461985] 'agreement among raft nodes before linearized reading' (duration: 332.474209ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:23.84372Z","caller":"traceutil/trace.go:171","msg":"trace[1873824654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:863; }","duration":"381.333635ms","start":"2026-06-27T05:39:23.462378Z","end":"2026-06-27T05:39:23.843712Z","steps":["trace[1873824654] 'agreement among raft nodes before linearized reading' (duration: 381.228617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:23.843729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:23.511136Z","time spent":"332.586726ms","remote":"127.0.0.1:36390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-27T05:39:23.843748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:23.462362Z","time spent":"381.380778ms","remote":"127.0.0.1:36150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-27T05:39:23.979278Z","caller":"traceutil/trace.go:171","msg":"trace[2126384622] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"130.787976ms","start":"2026-06-27T05:39:23.848463Z","end":"2026-06-27T05:39:23.979251Z","steps":["trace[2126384622] 'process raft request' (duration: 112.352405ms)","trace[2126384622] 'compare' (duration: 18.311672ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:23.993704Z","caller":"traceutil/trace.go:171","msg":"trace[464828338] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"145.143278ms","start":"2026-06-27T05:39:23.84854Z","end":"2026-06-27T05:39:23.993683Z","steps":["trace[464828338] 'process raft request' (duration: 144.955305ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:23.993816Z","caller":"traceutil/trace.go:171","msg":"trace[604771921] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"145.259637ms","start":"2026-06-27T05:39:23.848543Z","end":"2026-06-27T05:39:23.993803Z","steps":["trace[604771921] 'process raft request' (duration: 144.978537ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:23.994094Z","caller":"traceutil/trace.go:171","msg":"trace[327363694] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"145.601301ms","start":"2026-06-27T05:39:23.848484Z","end":"2026-06-27T05:39:23.994086Z","steps":["trace[327363694] 'process raft request' (duration: 144.911892ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:24.739723Z","caller":"traceutil/trace.go:171","msg":"trace[1812044622] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"173.580251ms","start":"2026-06-27T05:39:24.56612Z","end":"2026-06-27T05:39:24.7397Z","steps":["trace[1812044622] 'process raft request' (duration: 173.42855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:25.852044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.424611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-27T05:39:25.852151Z","caller":"traceutil/trace.go:171","msg":"trace[1003348821] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"608.046951ms","start":"2026-06-27T05:39:25.244083Z","end":"2026-06-27T05:39:25.85213Z","steps":["trace[1003348821] 'process raft request' (duration: 464.459588ms)","trace[1003348821] 'compare' (duration: 143.305212ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:25.852206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:25.244058Z","time spent":"608.121965ms","remote":"127.0.0.1:36324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1012,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:26.106351Z","caller":"traceutil/trace.go:171","msg":"trace[1337254926] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:919; }","duration":"852.212897ms","start":"2026-06-27T05:39:25.254123Z","end":"2026-06-27T05:39:26.106336Z","steps":["trace[1337254926] 'read index received' (duration: 454.474053ms)","trace[1337254926] 'applied index is now lower than readState.Index' (duration: 397.738264ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:26.106501Z","caller":"traceutil/trace.go:171","msg":"trace[1075127325] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"858.274731ms","start":"2026-06-27T05:39:25.248219Z","end":"2026-06-27T05:39:26.106494Z","steps":["trace[1075127325] 'process raft request' (duration: 843.249383ms)","trace[1075127325] 'compare' (duration: 14.698765ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:26.106578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:25.248211Z","time spent":"858.313074ms","remote":"127.0.0.1:36616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:39:26.106797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.650729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:39:26.106879Z","caller":"traceutil/trace.go:171","msg":"trace[972900237] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"857.249087ms","start":"2026-06-27T05:39:25.249622Z","end":"2026-06-27T05:39:26.106871Z","steps":["trace[972900237] 'process raft request' (duration: 856.681417ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:26.106877Z","caller":"traceutil/trace.go:171","msg":"trace[784659146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"852.745255ms","start":"2026-06-27T05:39:25.254112Z","end":"2026-06-27T05:39:26.106857Z","steps":["trace[784659146] 'agreement among raft nodes before linearized reading' (duration: 852.567293ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:26.106929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:25.249605Z","time spent":"857.298021ms","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4889,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:39:26.107011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:25.254083Z","time spent":"852.915778ms","remote":"127.0.0.1:36134","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-06-27T05:39:26.13952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.62668ms","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-06-27T05:39:26.139577Z","caller":"traceutil/trace.go:171","msg":"trace[114474601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:883; }","duration":"140.705446ms","start":"2026-06-27T05:39:25.998859Z","end":"2026-06-27T05:39:26.139564Z","steps":["trace[114474601] 'agreement among raft nodes before linearized reading' (duration: 140.583467ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:26.139688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.208221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-27T05:39:26.139707Z","caller":"traceutil/trace.go:171","msg":"trace[1388085341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:883; }","duration":"141.259865ms","start":"2026-06-27T05:39:25.998443Z","end":"2026-06-27T05:39:26.139703Z","steps":["trace[1388085341] 'agreement among raft nodes before linearized reading' (duration: 141.208661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:26.139807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.524223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-27T05:39:26.139825Z","caller":"traceutil/trace.go:171","msg":"trace[599348529] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"675.576656ms","start":"2026-06-27T05:39:25.464244Z","end":"2026-06-27T05:39:26.139821Z","steps":["trace[599348529] 'agreement among raft nodes before linearized reading' (duration: 675.530333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:26.139842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:25.464225Z","time spent":"675.612549ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-27T05:39:26.139905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.652648ms","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-06-27T05:39:26.139972Z","caller":"traceutil/trace.go:171","msg":"trace[1886956179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:883; }","duration":"156.762005ms","start":"2026-06-27T05:39:25.983198Z","end":"2026-06-27T05:39:26.13996Z","steps":["trace[1886956179] 'agreement among raft nodes before linearized reading' (duration: 156.537389ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:26.140149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.708307ms","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-06-27T05:39:26.140185Z","caller":"traceutil/trace.go:171","msg":"trace[1637165917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"141.772852ms","start":"2026-06-27T05:39:25.998402Z","end":"2026-06-27T05:39:26.140175Z","steps":["trace[1637165917] 'agreement among raft nodes before linearized reading' (duration: 141.685675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:26.447505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.321456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-27T05:39:26.447847Z","caller":"traceutil/trace.go:171","msg":"trace[1149784518] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"294.640394ms","start":"2026-06-27T05:39:26.153194Z","end":"2026-06-27T05:39:26.447835Z","steps":["trace[1149784518] 'process raft request' (duration: 294.610722ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:26.447975Z","caller":"traceutil/trace.go:171","msg":"trace[1731721770] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:923; }","duration":"308.513241ms","start":"2026-06-27T05:39:26.139425Z","end":"2026-06-27T05:39:26.447938Z","steps":["trace[1731721770] 'read index received' (duration: 152.632094ms)","trace[1731721770] 'applied index is now lower than readState.Index' (duration: 155.879057ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:26.448091Z","caller":"traceutil/trace.go:171","msg":"trace[240046226] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"332.58373ms","start":"2026-06-27T05:39:26.115492Z","end":"2026-06-27T05:39:26.448076Z","steps":["trace[240046226] 'process raft request' (duration: 176.621707ms)","trace[240046226] 'compare' (duration: 155.218859ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:26.448113Z","caller":"traceutil/trace.go:171","msg":"trace[1246130741] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"301.980532ms","start":"2026-06-27T05:39:26.146126Z","end":"2026-06-27T05:39:26.448106Z","steps":["trace[1246130741] 'process raft request' (duration: 301.595794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:26.448153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:26.115469Z","time spent":"332.651905ms","remote":"127.0.0.1:36426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:26.448184Z","caller":"traceutil/trace.go:171","msg":"trace[1279927495] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"330.700524ms","start":"2026-06-27T05:39:26.117465Z","end":"2026-06-27T05:39:26.448166Z","steps":["trace[1279927495] 'process raft request' (duration: 330.204048ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:26.44825Z","caller":"traceutil/trace.go:171","msg":"trace[509010975] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"298.267275ms","start":"2026-06-27T05:39:26.149976Z","end":"2026-06-27T05:39:26.448244Z","steps":["trace[509010975] 'process raft request' (duration: 297.772269ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:26.448318Z","caller":"traceutil/trace.go:171","msg":"trace[343531178] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"298.213781ms","start":"2026-06-27T05:39:26.150097Z","end":"2026-06-27T05:39:26.44831Z","steps":["trace[343531178] 'process raft request' (duration: 297.692594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:26.448337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:26.117457Z","time spent":"330.796781ms","remote":"127.0.0.1:36616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:26.448252Z","caller":"traceutil/trace.go:171","msg":"trace[104244186] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"328.812719ms","start":"2026-06-27T05:39:26.119429Z","end":"2026-06-27T05:39:26.448242Z","steps":["trace[104244186] 'process raft request' (duration: 328.267529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:26.448204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:26.146108Z","time spent":"302.058427ms","remote":"127.0.0.1:36420","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-06-27T05:39:26.448462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:26.119421Z","time spent":"329.007653ms","remote":"127.0.0.1:36616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:26.448429Z","caller":"traceutil/trace.go:171","msg":"trace[132816690] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"298.346691ms","start":"2026-06-27T05:39:26.15005Z","end":"2026-06-27T05:39:26.448397Z","steps":["trace[132816690] 'process raft request' (duration: 297.720836ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:26.448202Z","caller":"traceutil/trace.go:171","msg":"trace[846225581] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"332.041341ms","start":"2026-06-27T05:39:26.116148Z","end":"2026-06-27T05:39:26.448189Z","steps":["trace[846225581] 'process raft request' (duration: 331.481621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:26.448913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:26.116139Z","time spent":"332.675487ms","remote":"127.0.0.1:36324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1012,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:39:26.448119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.006993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:39:26.449199Z","caller":"traceutil/trace.go:171","msg":"trace[1616816821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"341.132524ms","start":"2026-06-27T05:39:26.108057Z","end":"2026-06-27T05:39:26.44919Z","steps":["trace[1616816821] 'agreement among raft nodes before linearized reading' (duration: 339.96712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:26.449278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:26.108047Z","time spent":"341.22321ms","remote":"127.0.0.1:36150","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-06-27T05:39:26.961952Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662155412,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-27T05:39:27.214637Z","caller":"traceutil/trace.go:171","msg":"trace[2013354301] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"753.685782ms","start":"2026-06-27T05:39:26.460926Z","end":"2026-06-27T05:39:27.214612Z","steps":["trace[2013354301] 'process raft request' (duration: 726.713428ms)","trace[2013354301] 'compare' (duration: 26.862386ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:27.214764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:26.46091Z","time spent":"753.792019ms","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4868,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:27.268272Z","caller":"traceutil/trace.go:171","msg":"trace[1579210946] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:932; }","duration":"806.576562ms","start":"2026-06-27T05:39:26.461673Z","end":"2026-06-27T05:39:27.268249Z","steps":["trace[1579210946] 'read index received' (duration: 725.977585ms)","trace[1579210946] 'applied index is now lower than readState.Index' (duration: 80.598097ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:27.268371Z","caller":"traceutil/trace.go:171","msg":"trace[151001551] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"803.526643ms","start":"2026-06-27T05:39:26.464835Z","end":"2026-06-27T05:39:27.268361Z","steps":["trace[151001551] 'process raft request' (duration: 803.353821ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:27.268385Z","caller":"traceutil/trace.go:171","msg":"trace[1952782371] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"803.75992ms","start":"2026-06-27T05:39:26.464596Z","end":"2026-06-27T05:39:27.268356Z","steps":["trace[1952782371] 'process raft request' (duration: 803.48005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:27.268492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.791657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:39:27.268654Z","caller":"traceutil/trace.go:171","msg":"trace[185420358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"806.980871ms","start":"2026-06-27T05:39:26.461653Z","end":"2026-06-27T05:39:27.268634Z","steps":["trace[185420358] 'agreement among raft nodes before linearized reading' (duration: 806.752644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:27.268704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:26.461647Z","time spent":"807.048606ms","remote":"127.0.0.1:36134","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-06-27T05:39:27.268667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:26.46458Z","time spent":"803.872108ms","remote":"127.0.0.1:36236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-27T05:39:27.268939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:26.464828Z","time spent":"803.566576ms","remote":"127.0.0.1:36594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:27.387621Z","caller":"traceutil/trace.go:171","msg":"trace[1492123057] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"168.176817ms","start":"2026-06-27T05:39:27.219418Z","end":"2026-06-27T05:39:27.387595Z","steps":["trace[1492123057] 'process raft request' (duration: 168.057878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:27.387714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.903655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:39:27.387744Z","caller":"traceutil/trace.go:171","msg":"trace[416040649] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:900; }","duration":"430.96758ms","start":"2026-06-27T05:39:26.956766Z","end":"2026-06-27T05:39:27.387734Z","steps":["trace[416040649] 'agreement among raft nodes before linearized reading' (duration: 430.901515ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:27.387764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:26.95675Z","time spent":"431.008133ms","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"info","ts":"2026-06-27T05:39:27.387902Z","caller":"traceutil/trace.go:171","msg":"trace[1682220939] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"168.018175ms","start":"2026-06-27T05:39:27.219877Z","end":"2026-06-27T05:39:27.387896Z","steps":["trace[1682220939] 'process raft request' (duration: 167.647059ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:27.388162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.120543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-ftz2j\" ","response":"range_response_count:1 size:4883"} {"level":"info","ts":"2026-06-27T05:39:27.388203Z","caller":"traceutil/trace.go:171","msg":"trace[2074380549] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"167.126751ms","start":"2026-06-27T05:39:27.221069Z","end":"2026-06-27T05:39:27.388196Z","steps":["trace[2074380549] 'process raft request' (duration: 166.483605ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:27.388392Z","caller":"traceutil/trace.go:171","msg":"trace[1324155298] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"165.628974ms","start":"2026-06-27T05:39:27.222755Z","end":"2026-06-27T05:39:27.388384Z","steps":["trace[1324155298] 'process raft request' (duration: 164.832126ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:27.388412Z","caller":"traceutil/trace.go:171","msg":"trace[1710184969] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"169.413836ms","start":"2026-06-27T05:39:27.218986Z","end":"2026-06-27T05:39:27.3884Z","steps":["trace[1710184969] 'process raft request' (duration: 168.301076ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:27.387645Z","caller":"traceutil/trace.go:171","msg":"trace[1429310370] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:935; }","duration":"119.300406ms","start":"2026-06-27T05:39:27.268334Z","end":"2026-06-27T05:39:27.387634Z","steps":["trace[1429310370] 'read index received' (duration: 118.935699ms)","trace[1429310370] 'applied index is now lower than readState.Index' (duration: 363.827µs)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:27.389664Z","caller":"traceutil/trace.go:171","msg":"trace[1455360551] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-ftz2j; range_end:; response_count:1; response_revision:900; }","duration":"168.213669ms","start":"2026-06-27T05:39:27.219996Z","end":"2026-06-27T05:39:27.38821Z","steps":["trace[1455360551] 'agreement among raft nodes before linearized reading' (duration: 168.041837ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:27.455871Z","caller":"traceutil/trace.go:171","msg":"trace[1813985207] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"176.737904ms","start":"2026-06-27T05:39:27.279104Z","end":"2026-06-27T05:39:27.455842Z","steps":["trace[1813985207] 'process raft request' (duration: 176.224827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:27.456379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.598374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:39:27.456437Z","caller":"traceutil/trace.go:171","msg":"trace[892871730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"186.697042ms","start":"2026-06-27T05:39:27.269725Z","end":"2026-06-27T05:39:27.456422Z","steps":["trace[892871730] 'agreement among raft nodes before linearized reading' (duration: 186.608795ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:27.632053Z","caller":"traceutil/trace.go:171","msg":"trace[1755179652] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:941; }","duration":"176.474904ms","start":"2026-06-27T05:39:27.455563Z","end":"2026-06-27T05:39:27.632038Z","steps":["trace[1755179652] 'read index received' (duration: 158.954191ms)","trace[1755179652] 'applied index is now lower than readState.Index' (duration: 17.519883ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:27.632212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.287962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-27T05:39:27.632241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.36563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:39:27.632248Z","caller":"traceutil/trace.go:171","msg":"trace[1876188165] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:0; response_revision:903; }","duration":"240.376369ms","start":"2026-06-27T05:39:27.391864Z","end":"2026-06-27T05:39:27.63224Z","steps":["trace[1876188165] 'agreement among raft nodes before linearized reading' (duration: 240.279972ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:27.632265Z","caller":"traceutil/trace.go:171","msg":"trace[1782435887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"168.420655ms","start":"2026-06-27T05:39:27.463836Z","end":"2026-06-27T05:39:27.632257Z","steps":["trace[1782435887] 'agreement among raft nodes before linearized reading' (duration: 168.305586ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:27.632275Z","caller":"traceutil/trace.go:171","msg":"trace[604014734] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"235.377648ms","start":"2026-06-27T05:39:27.396869Z","end":"2026-06-27T05:39:27.632246Z","steps":["trace[604014734] 'process raft request' (duration: 235.12387ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:27.632444Z","caller":"traceutil/trace.go:171","msg":"trace[2057223461] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"239.454482ms","start":"2026-06-27T05:39:27.39296Z","end":"2026-06-27T05:39:27.632415Z","steps":["trace[2057223461] 'process raft request' (duration: 221.564873ms)","trace[2057223461] 'compare' (duration: 17.361941ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:32.244429Z","caller":"traceutil/trace.go:171","msg":"trace[1429403265] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"136.302891ms","start":"2026-06-27T05:39:32.108076Z","end":"2026-06-27T05:39:32.244379Z","steps":["trace[1429403265] 'process raft request' (duration: 136.074274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:33.440967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.033809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-27T05:39:33.44107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.783166ms","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-06-27T05:39:33.441087Z","caller":"traceutil/trace.go:171","msg":"trace[1118827627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"161.194751ms","start":"2026-06-27T05:39:33.279867Z","end":"2026-06-27T05:39:33.441062Z","steps":["trace[1118827627] 'range keys from in-memory index tree' (duration: 160.957334ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:33.441127Z","caller":"traceutil/trace.go:171","msg":"trace[506145954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:953; }","duration":"950.861322ms","start":"2026-06-27T05:39:32.490251Z","end":"2026-06-27T05:39:33.441112Z","steps":["trace[506145954] 'range keys from in-memory index tree' (duration: 950.618874ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:33.441192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:32.490241Z","time spent":"950.918626ms","remote":"127.0.0.1:36420","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-06-27T05:39:33.441518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.375911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-27T05:39:33.441617Z","caller":"traceutil/trace.go:171","msg":"trace[62654299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:953; }","duration":"948.49782ms","start":"2026-06-27T05:39:32.493102Z","end":"2026-06-27T05:39:33.4416Z","steps":["trace[62654299] 'range keys from in-memory index tree' (duration: 948.266233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:33.441677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:32.493092Z","time spent":"948.570395ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-27T05:39:33.441718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.079381ms","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-06-27T05:39:33.441779Z","caller":"traceutil/trace.go:171","msg":"trace[254773969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:953; }","duration":"955.164907ms","start":"2026-06-27T05:39:32.4866Z","end":"2026-06-27T05:39:33.441765Z","steps":["trace[254773969] 'range keys from in-memory index tree' (duration: 954.992214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:33.441839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:32.486583Z","time spent":"955.233742ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-27T05:39:33.441526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.932197ms","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":"warn","ts":"2026-06-27T05:39:33.442154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.043832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-zlzcs\" ","response":"range_response_count:1 size:3610"} {"level":"info","ts":"2026-06-27T05:39:33.442183Z","caller":"traceutil/trace.go:171","msg":"trace[508668813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"967.616627ms","start":"2026-06-27T05:39:32.474548Z","end":"2026-06-27T05:39:33.442165Z","steps":["trace[508668813] 'range keys from in-memory index tree' (duration: 966.759504ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:33.442205Z","caller":"traceutil/trace.go:171","msg":"trace[521344410] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-zlzcs; range_end:; response_count:1; response_revision:953; }","duration":"256.132658ms","start":"2026-06-27T05:39:33.186057Z","end":"2026-06-27T05:39:33.44219Z","steps":["trace[521344410] 'range keys from in-memory index tree' (duration: 255.832587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:33.442248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:32.474534Z","time spent":"967.697373ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-27T05:39:33.441628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.222171ms","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-06-27T05:39:33.44241Z","caller":"traceutil/trace.go:171","msg":"trace[1133295117] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"956.0248ms","start":"2026-06-27T05:39:32.486358Z","end":"2026-06-27T05:39:33.442383Z","steps":["trace[1133295117] 'range keys from in-memory index tree' (duration: 955.032518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:33.442497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:32.48634Z","time spent":"956.130028ms","remote":"127.0.0.1:36420","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-06-27T05:39:33.442502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.552061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-27T05:39:33.442582Z","caller":"traceutil/trace.go:171","msg":"trace[550980349] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:953; }","duration":"843.674659ms","start":"2026-06-27T05:39:32.598888Z","end":"2026-06-27T05:39:33.442563Z","steps":["trace[550980349] 'count revisions from in-memory index tree' (duration: 843.477116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:33.442631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:32.598872Z","time spent":"843.747074ms","remote":"127.0.0.1:36644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":102,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-06-27T05:39:33.96634Z","caller":"traceutil/trace.go:171","msg":"trace[1153350234] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"520.584047ms","start":"2026-06-27T05:39:33.445717Z","end":"2026-06-27T05:39:33.966301Z","steps":["trace[1153350234] 'process raft request' (duration: 520.404453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:33.96657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:33.445703Z","time spent":"520.746098ms","remote":"127.0.0.1:36420","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-06-27T05:39:34.62636Z","caller":"traceutil/trace.go:171","msg":"trace[1327823882] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.179913009s","start":"2026-06-27T05:39:33.446419Z","end":"2026-06-27T05:39:34.626332Z","steps":["trace[1327823882] 'process raft request' (duration: 1.179655681s)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:34.626465Z","caller":"traceutil/trace.go:171","msg":"trace[1935359077] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"1.179385651s","start":"2026-06-27T05:39:33.447048Z","end":"2026-06-27T05:39:34.626434Z","steps":["trace[1935359077] 'process raft request' (duration: 1.179200717s)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:34.626687Z","caller":"traceutil/trace.go:171","msg":"trace[50388835] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"1.1786891s","start":"2026-06-27T05:39:33.447786Z","end":"2026-06-27T05:39:34.626476Z","steps":["trace[50388835] 'process raft request' (duration: 1.178540699s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:34.626787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:33.447036Z","time spent":"1.179675862s","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3702,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:39:34.626833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:33.447776Z","time spent":"1.178978111s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:34.626819Z","caller":"traceutil/trace.go:171","msg":"trace[55719434] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"1.178857062s","start":"2026-06-27T05:39:33.447925Z","end":"2026-06-27T05:39:34.626782Z","steps":["trace[55719434] 'process raft request' (duration: 1.178431241s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:34.626775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:33.446402Z","time spent":"1.180023397s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:39:34.627003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:33.447906Z","time spent":"1.178994952s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:34.626462Z","caller":"traceutil/trace.go:171","msg":"trace[1285108756] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"1.178760055s","start":"2026-06-27T05:39:33.447674Z","end":"2026-06-27T05:39:34.626434Z","steps":["trace[1285108756] 'process raft request' (duration: 1.178621425s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:34.62711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:33.447659Z","time spent":"1.179408852s","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:34.720771Z","caller":"traceutil/trace.go:171","msg":"trace[839375590] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"1.081871084s","start":"2026-06-27T05:39:33.638835Z","end":"2026-06-27T05:39:34.720707Z","steps":["trace[839375590] 'process raft request' (duration: 1.081809059s)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:34.720832Z","caller":"traceutil/trace.go:171","msg":"trace[1071002952] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"1.169642875s","start":"2026-06-27T05:39:33.551152Z","end":"2026-06-27T05:39:34.720794Z","steps":["trace[1071002952] 'process raft request' (duration: 1.169365225s)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:34.720894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:33.638811Z","time spent":"1.082024354s","remote":"127.0.0.1:36420","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-06-27T05:39:34.720931Z","caller":"traceutil/trace.go:171","msg":"trace[1992900419] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"456.388621ms","start":"2026-06-27T05:39:34.264522Z","end":"2026-06-27T05:39:34.720911Z","steps":["trace[1992900419] 'process raft request' (duration: 456.156654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:34.721009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:33.551124Z","time spent":"1.169789956s","remote":"127.0.0.1:36420","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-06-27T05:39:34.721098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:34.264492Z","time spent":"456.486408ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:39:35.614697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.83964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-27T05:39:35.614789Z","caller":"traceutil/trace.go:171","msg":"trace[841818829] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"355.656098ms","start":"2026-06-27T05:39:35.259117Z","end":"2026-06-27T05:39:35.614773Z","steps":["trace[841818829] 'read index received' (duration: 54.984µs)","trace[841818829] 'applied index is now lower than readState.Index' (duration: 355.600124ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:35.61489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.782547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:39:35.614913Z","caller":"traceutil/trace.go:171","msg":"trace[1911956485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"355.845241ms","start":"2026-06-27T05:39:35.25906Z","end":"2026-06-27T05:39:35.614905Z","steps":["trace[1911956485] 'agreement among raft nodes before linearized reading' (duration: 355.756995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:35.614947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:35.259032Z","time spent":"355.908476ms","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-27T05:39:35.614999Z","caller":"traceutil/trace.go:171","msg":"trace[533319621] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"888.827948ms","start":"2026-06-27T05:39:34.726139Z","end":"2026-06-27T05:39:35.614967Z","steps":["trace[533319621] 'process raft request' (duration: 352.61685ms)","trace[533319621] 'compare' (duration: 535.591882ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:35.615135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:34.726119Z","time spent":"888.942666ms","remote":"127.0.0.1:36616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:36.473036Z","caller":"traceutil/trace.go:171","msg":"trace[469884358] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"274.046818ms","start":"2026-06-27T05:39:36.198966Z","end":"2026-06-27T05:39:36.473013Z","steps":["trace[469884358] 'read index received' (duration: 273.791029ms)","trace[469884358] 'applied index is now lower than readState.Index' (duration: 254.739µs)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:36.473307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.327189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-ftz2j\" ","response":"range_response_count:1 size:5071"} {"level":"info","ts":"2026-06-27T05:39:36.473343Z","caller":"traceutil/trace.go:171","msg":"trace[1208161523] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-ftz2j; range_end:; response_count:1; response_revision:968; }","duration":"274.409624ms","start":"2026-06-27T05:39:36.198922Z","end":"2026-06-27T05:39:36.473331Z","steps":["trace[1208161523] 'agreement among raft nodes before linearized reading' (duration: 274.192378ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:36.473401Z","caller":"traceutil/trace.go:171","msg":"trace[1429594167] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"499.793602ms","start":"2026-06-27T05:39:35.973581Z","end":"2026-06-27T05:39:36.473374Z","steps":["trace[1429594167] 'process raft request' (duration: 499.270353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:36.473529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:35.973562Z","time spent":"499.8935ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T05:39:37.132576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662155554,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-27T05:39:37.152383Z","caller":"traceutil/trace.go:171","msg":"trace[266959088] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"669.505949ms","start":"2026-06-27T05:39:36.482855Z","end":"2026-06-27T05:39:37.152361Z","steps":["trace[266959088] 'process raft request' (duration: 664.050815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:37.152531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:36.482838Z","time spent":"669.614797ms","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5056,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T05:39:37.160731Z","caller":"traceutil/trace.go:171","msg":"trace[2024203096] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1010; }","duration":"528.512939ms","start":"2026-06-27T05:39:36.632194Z","end":"2026-06-27T05:39:37.160707Z","steps":["trace[2024203096] 'read index received' (duration: 514.757171ms)","trace[2024203096] 'applied index is now lower than readState.Index' (duration: 13.754568ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:37.161391Z","caller":"traceutil/trace.go:171","msg":"trace[1950749041] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"434.14968ms","start":"2026-06-27T05:39:36.727034Z","end":"2026-06-27T05:39:37.161183Z","steps":["trace[1950749041] 'process raft request' (duration: 433.516793ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:37.161654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:36.727015Z","time spent":"434.512257ms","remote":"127.0.0.1:36420","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-06-27T05:39:37.161918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.692646ms","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-06-27T05:39:37.162019Z","caller":"traceutil/trace.go:171","msg":"trace[1090258213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:970; }","duration":"529.817445ms","start":"2026-06-27T05:39:36.632166Z","end":"2026-06-27T05:39:37.161984Z","steps":["trace[1090258213] 'agreement among raft nodes before linearized reading' (duration: 528.650579ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:37.162069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:36.632152Z","time spent":"529.906052ms","remote":"127.0.0.1:36420","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-06-27T05:39:37.17127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.008996ms","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-06-27T05:39:37.171334Z","caller":"traceutil/trace.go:171","msg":"trace[206535819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:971; }","duration":"539.084552ms","start":"2026-06-27T05:39:36.632234Z","end":"2026-06-27T05:39:37.171319Z","steps":["trace[206535819] 'agreement among raft nodes before linearized reading' (duration: 538.878847ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:37.17136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:36.632232Z","time spent":"539.123134ms","remote":"127.0.0.1:36420","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-06-27T05:39:37.171367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.97309ms","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-06-27T05:39:37.171425Z","caller":"traceutil/trace.go:171","msg":"trace[958303337] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:971; }","duration":"538.068637ms","start":"2026-06-27T05:39:36.633344Z","end":"2026-06-27T05:39:37.171413Z","steps":["trace[958303337] 'agreement among raft nodes before linearized reading' (duration: 537.889094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:37.171459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:36.633338Z","time spent":"538.11377ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-27T05:39:37.17149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.193339ms","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-06-27T05:39:37.171508Z","caller":"traceutil/trace.go:171","msg":"trace[1863063436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:971; }","duration":"539.224351ms","start":"2026-06-27T05:39:36.632277Z","end":"2026-06-27T05:39:37.171502Z","steps":["trace[1863063436] 'agreement among raft nodes before linearized reading' (duration: 539.169077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:37.171532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:39:36.63227Z","time spent":"539.258974ms","remote":"127.0.0.1:36420","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-06-27T05:39:37.363003Z","caller":"traceutil/trace.go:171","msg":"trace[820349159] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"187.848927ms","start":"2026-06-27T05:39:37.175122Z","end":"2026-06-27T05:39:37.362971Z","steps":["trace[820349159] 'process raft request' (duration: 179.91338ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:37.363518Z","caller":"traceutil/trace.go:171","msg":"trace[1609998234] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1014; }","duration":"106.814368ms","start":"2026-06-27T05:39:37.256675Z","end":"2026-06-27T05:39:37.363489Z","steps":["trace[1609998234] 'read index received' (duration: 98.301607ms)","trace[1609998234] 'applied index is now lower than readState.Index' (duration: 8.511331ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:37.363662Z","caller":"traceutil/trace.go:171","msg":"trace[16157587] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"188.178272ms","start":"2026-06-27T05:39:37.175473Z","end":"2026-06-27T05:39:37.363652Z","steps":["trace[16157587] 'process raft request' (duration: 187.560026ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:37.363754Z","caller":"traceutil/trace.go:171","msg":"trace[1384528720] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"187.836716ms","start":"2026-06-27T05:39:37.175891Z","end":"2026-06-27T05:39:37.363728Z","steps":["trace[1384528720] 'process raft request' (duration: 187.544365ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:37.364002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.297534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:39:37.364139Z","caller":"traceutil/trace.go:171","msg":"trace[681509876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"107.488518ms","start":"2026-06-27T05:39:37.256632Z","end":"2026-06-27T05:39:37.364121Z","steps":["trace[681509876] 'agreement among raft nodes before linearized reading' (duration: 107.040314ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:39.492684Z","caller":"traceutil/trace.go:171","msg":"trace[422062849] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"122.807766ms","start":"2026-06-27T05:39:39.369854Z","end":"2026-06-27T05:39:39.492662Z","steps":["trace[422062849] 'process raft request' (duration: 122.665486ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:39.523602Z","caller":"traceutil/trace.go:171","msg":"trace[2077459524] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"153.300884ms","start":"2026-06-27T05:39:39.370279Z","end":"2026-06-27T05:39:39.52358Z","steps":["trace[2077459524] 'process raft request' (duration: 153.223059ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:39.523606Z","caller":"traceutil/trace.go:171","msg":"trace[189842998] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"153.666852ms","start":"2026-06-27T05:39:39.369907Z","end":"2026-06-27T05:39:39.523574Z","steps":["trace[189842998] 'process raft request' (duration: 153.472638ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:41.291045Z","caller":"traceutil/trace.go:171","msg":"trace[1870231284] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"106.016544ms","start":"2026-06-27T05:39:41.185007Z","end":"2026-06-27T05:39:41.291024Z","steps":["trace[1870231284] 'process raft request' (duration: 105.831501ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:41.406904Z","caller":"traceutil/trace.go:171","msg":"trace[574362952] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"112.968874ms","start":"2026-06-27T05:39:41.293913Z","end":"2026-06-27T05:39:41.406882Z","steps":["trace[574362952] 'process raft request' (duration: 30.268323ms)","trace[574362952] 'compare' (duration: 82.589673ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:41.739509Z","caller":"traceutil/trace.go:171","msg":"trace[1916413277] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"212.18919ms","start":"2026-06-27T05:39:41.527302Z","end":"2026-06-27T05:39:41.739491Z","steps":["trace[1916413277] 'read index received' (duration: 212.04946ms)","trace[1916413277] 'applied index is now lower than readState.Index' (duration: 138.85µs)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:41.739605Z","caller":"traceutil/trace.go:171","msg":"trace[540962535] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"238.849863ms","start":"2026-06-27T05:39:41.500747Z","end":"2026-06-27T05:39:41.739597Z","steps":["trace[540962535] 'process raft request' (duration: 238.625526ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:41.739818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.504104ms","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-06-27T05:39:41.739846Z","caller":"traceutil/trace.go:171","msg":"trace[1000887755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:986; }","duration":"212.58806ms","start":"2026-06-27T05:39:41.527252Z","end":"2026-06-27T05:39:41.73984Z","steps":["trace[1000887755] 'agreement among raft nodes before linearized reading' (duration: 212.467051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:41.739842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.465321ms","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-06-27T05:39:41.739907Z","caller":"traceutil/trace.go:171","msg":"trace[208377181] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:986; }","duration":"212.605261ms","start":"2026-06-27T05:39:41.527286Z","end":"2026-06-27T05:39:41.739892Z","steps":["trace[208377181] 'agreement among raft nodes before linearized reading' (duration: 212.381654ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:48.271092Z","caller":"traceutil/trace.go:171","msg":"trace[85971074] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"144.753655ms","start":"2026-06-27T05:39:48.126318Z","end":"2026-06-27T05:39:48.271072Z","steps":["trace[85971074] 'read index received' (duration: 144.593393ms)","trace[85971074] 'applied index is now lower than readState.Index' (duration: 159.392µs)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:39:48.271198Z","caller":"traceutil/trace.go:171","msg":"trace[948460108] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"186.357232ms","start":"2026-06-27T05:39:48.084829Z","end":"2026-06-27T05:39:48.271186Z","steps":["trace[948460108] 'process raft request' (duration: 186.080381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:39:48.271367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.034516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:39:48.271448Z","caller":"traceutil/trace.go:171","msg":"trace[1379688642] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1010; }","duration":"145.165426ms","start":"2026-06-27T05:39:48.126267Z","end":"2026-06-27T05:39:48.271433Z","steps":["trace[1379688642] 'agreement among raft nodes before linearized reading' (duration: 145.041147ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:50.151306Z","caller":"traceutil/trace.go:171","msg":"trace[837620439] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"163.106405ms","start":"2026-06-27T05:39:49.988172Z","end":"2026-06-27T05:39:50.151279Z","steps":["trace[837620439] 'process raft request' (duration: 161.518644ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:39:50.151246Z","caller":"traceutil/trace.go:171","msg":"trace[691306488] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"159.173496ms","start":"2026-06-27T05:39:49.992052Z","end":"2026-06-27T05:39:50.151226Z","steps":["trace[691306488] 'read index received' (duration: 157.613117ms)","trace[691306488] 'applied index is now lower than readState.Index' (duration: 1.547998ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:39:50.151507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.442557ms","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-06-27T05:39:50.151553Z","caller":"traceutil/trace.go:171","msg":"trace[471943328] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1018; }","duration":"159.522562ms","start":"2026-06-27T05:39:49.992019Z","end":"2026-06-27T05:39:50.151541Z","steps":["trace[471943328] 'agreement among raft nodes before linearized reading' (duration: 159.387172ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:40:02.642243Z","caller":"traceutil/trace.go:171","msg":"trace[1542815649] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"137.383022ms","start":"2026-06-27T05:40:02.504835Z","end":"2026-06-27T05:40:02.642218Z","steps":["trace[1542815649] 'process raft request' (duration: 137.204928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:40:04.092081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.210453ms","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-06-27T05:40:04.092209Z","caller":"traceutil/trace.go:171","msg":"trace[1601923785] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1061; }","duration":"353.794819ms","start":"2026-06-27T05:40:03.738393Z","end":"2026-06-27T05:40:04.092188Z","steps":["trace[1601923785] 'range keys from in-memory index tree' (duration: 352.970274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:40:04.092259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:40:03.738327Z","time spent":"353.917888ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-27T05:40:04.316717Z","caller":"traceutil/trace.go:171","msg":"trace[574450135] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"139.197318ms","start":"2026-06-27T05:40:04.177494Z","end":"2026-06-27T05:40:04.316691Z","steps":["trace[574450135] 'process raft request' (duration: 138.671877ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:40:04.444794Z","caller":"traceutil/trace.go:171","msg":"trace[465291470] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"123.688676ms","start":"2026-06-27T05:40:04.321082Z","end":"2026-06-27T05:40:04.444771Z","steps":["trace[465291470] 'process raft request' (duration: 93.827474ms)","trace[465291470] 'compare' (duration: 29.32338ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:40:05.593887Z","caller":"traceutil/trace.go:171","msg":"trace[1180308481] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"141.125305ms","start":"2026-06-27T05:40:05.452742Z","end":"2026-06-27T05:40:05.593867Z","steps":["trace[1180308481] 'process raft request' (duration: 141.004376ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:40:05.767866Z","caller":"traceutil/trace.go:171","msg":"trace[1638334557] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"116.841195ms","start":"2026-06-27T05:40:05.651005Z","end":"2026-06-27T05:40:05.767846Z","steps":["trace[1638334557] 'process raft request' (duration: 116.716255ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:40:07.247985Z","caller":"traceutil/trace.go:171","msg":"trace[1189702063] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"151.964007ms","start":"2026-06-27T05:40:07.095996Z","end":"2026-06-27T05:40:07.24796Z","steps":["trace[1189702063] 'process raft request' (duration: 151.788503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:40:10.704314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.424828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-27T05:40:10.704399Z","caller":"traceutil/trace.go:171","msg":"trace[2085009718] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"174.791546ms","start":"2026-06-27T05:40:10.529593Z","end":"2026-06-27T05:40:10.704385Z","steps":["trace[2085009718] 'process raft request' (duration: 65.234758ms)","trace[2085009718] 'compare' (duration: 109.32999ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:40:10.712035Z","caller":"traceutil/trace.go:171","msg":"trace[158722569] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"180.978774ms","start":"2026-06-27T05:40:10.531033Z","end":"2026-06-27T05:40:10.712012Z","steps":["trace[158722569] 'process raft request' (duration: 180.850464ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:40:12.898268Z","caller":"traceutil/trace.go:171","msg":"trace[335999099] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"150.065551ms","start":"2026-06-27T05:40:12.748182Z","end":"2026-06-27T05:40:12.898248Z","steps":["trace[335999099] 'process raft request' (duration: 148.185223ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:40:13.590272Z","caller":"traceutil/trace.go:171","msg":"trace[1605641640] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"290.957649ms","start":"2026-06-27T05:40:13.299287Z","end":"2026-06-27T05:40:13.590245Z","steps":["trace[1605641640] 'process raft request' (duration: 290.792125ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:40:15.068833Z","caller":"traceutil/trace.go:171","msg":"trace[185494990] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"161.548199ms","start":"2026-06-27T05:40:14.907257Z","end":"2026-06-27T05:40:15.068805Z","steps":["trace[185494990] 'process raft request' (duration: 161.386246ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:40:17.71085Z","caller":"traceutil/trace.go:171","msg":"trace[1800842980] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"102.474084ms","start":"2026-06-27T05:40:17.608319Z","end":"2026-06-27T05:40:17.710794Z","steps":["trace[1800842980] 'process raft request' (duration: 101.977295ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:40:18.899451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.771004ms","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-06-27T05:40:18.899542Z","caller":"traceutil/trace.go:171","msg":"trace[1200766484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1111; }","duration":"113.878533ms","start":"2026-06-27T05:40:18.785642Z","end":"2026-06-27T05:40:18.899521Z","steps":["trace[1200766484] 'range keys from in-memory index tree' (duration: 113.561167ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:40:19.005667Z","caller":"traceutil/trace.go:171","msg":"trace[1641117621] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"102.53385ms","start":"2026-06-27T05:40:18.903107Z","end":"2026-06-27T05:40:19.005641Z","steps":["trace[1641117621] 'process raft request' (duration: 102.398059ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:40:19.266549Z","caller":"traceutil/trace.go:171","msg":"trace[552821257] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"168.037136ms","start":"2026-06-27T05:40:19.098483Z","end":"2026-06-27T05:40:19.26652Z","steps":["trace[552821257] 'process raft request' (duration: 124.306722ms)","trace[552821257] 'compare' (duration: 43.524538ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:40:20.170657Z","caller":"traceutil/trace.go:171","msg":"trace[639935717] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"123.192236ms","start":"2026-06-27T05:40:20.047448Z","end":"2026-06-27T05:40:20.170641Z","steps":["trace[639935717] 'process raft request' (duration: 123.100609ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:40:57.679414Z","caller":"traceutil/trace.go:171","msg":"trace[1966857418] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"116.748698ms","start":"2026-06-27T05:40:57.56264Z","end":"2026-06-27T05:40:57.679389Z","steps":["trace[1966857418] 'process raft request' (duration: 48.568651ms)","trace[1966857418] 'compare' (duration: 68.083979ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:40:57.846078Z","caller":"traceutil/trace.go:171","msg":"trace[1570348145] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"150.320144ms","start":"2026-06-27T05:40:57.695724Z","end":"2026-06-27T05:40:57.846044Z","steps":["trace[1570348145] 'process raft request' (duration: 108.095375ms)","trace[1570348145] 'compare' (duration: 42.080417ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:40:58.275126Z","caller":"traceutil/trace.go:171","msg":"trace[276614282] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"245.256048ms","start":"2026-06-27T05:40:58.029832Z","end":"2026-06-27T05:40:58.275088Z","steps":["trace[276614282] 'process raft request' (duration: 245.016908ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:41:06.435384Z","caller":"traceutil/trace.go:171","msg":"trace[1191834187] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"127.456936ms","start":"2026-06-27T05:41:06.3079Z","end":"2026-06-27T05:41:06.435357Z","steps":["trace[1191834187] 'process raft request' (duration: 127.240368ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:41:14.122073Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104027662156511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-27T05:41:14.21327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.272492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T05:41:14.213384Z","caller":"traceutil/trace.go:171","msg":"trace[1926856393] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1327; }","duration":"671.429666ms","start":"2026-06-27T05:41:13.541928Z","end":"2026-06-27T05:41:14.213357Z","steps":["trace[1926856393] 'count revisions from in-memory index tree' (duration: 671.185856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:41:14.213446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:41:13.541908Z","time spent":"671.514943ms","remote":"127.0.0.1:37738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-27T05:41:14.237754Z","caller":"traceutil/trace.go:171","msg":"trace[2031255027] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"616.539885ms","start":"2026-06-27T05:41:13.621182Z","end":"2026-06-27T05:41:14.237722Z","steps":["trace[2031255027] 'read index received' (duration: 573.502629ms)","trace[2031255027] 'applied index is now lower than readState.Index' (duration: 43.035166ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:41:14.238068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.690069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-27T05:41:14.238158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.955478ms","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-06-27T05:41:14.23819Z","caller":"traceutil/trace.go:171","msg":"trace[378601913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"583.892081ms","start":"2026-06-27T05:41:13.65427Z","end":"2026-06-27T05:41:14.238162Z","steps":["trace[378601913] 'agreement among raft nodes before linearized reading' (duration: 583.67853ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:41:14.238213Z","caller":"traceutil/trace.go:171","msg":"trace[883813580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1327; }","duration":"617.070114ms","start":"2026-06-27T05:41:13.62113Z","end":"2026-06-27T05:41:14.2382Z","steps":["trace[883813580] 'agreement among raft nodes before linearized reading' (duration: 616.753076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:41:14.238246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:41:13.654247Z","time spent":"583.989557ms","remote":"127.0.0.1:36134","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-06-27T05:41:14.238321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:41:13.621106Z","time spent":"617.198329ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-27T05:41:14.238369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.009758ms","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-06-27T05:41:14.238476Z","caller":"traceutil/trace.go:171","msg":"trace[1266026392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1327; }","duration":"467.177222ms","start":"2026-06-27T05:41:13.771275Z","end":"2026-06-27T05:41:14.238452Z","steps":["trace[1266026392] 'agreement among raft nodes before linearized reading' (duration: 466.868894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:41:14.238552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T05:41:13.771255Z","time spent":"467.286247ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-27T05:41:21.165362Z","caller":"traceutil/trace.go:171","msg":"trace[1479677191] linearizableReadLoop","detail":"{readStateIndex:1424; appliedIndex:1423; }","duration":"101.074182ms","start":"2026-06-27T05:41:21.064264Z","end":"2026-06-27T05:41:21.165339Z","steps":["trace[1479677191] 'read index received' (duration: 100.895688ms)","trace[1479677191] 'applied index is now lower than readState.Index' (duration: 177.434µs)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:41:21.165658Z","caller":"traceutil/trace.go:171","msg":"trace[1395705004] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"102.234944ms","start":"2026-06-27T05:41:21.063411Z","end":"2026-06-27T05:41:21.165646Z","steps":["trace[1395705004] 'process raft request' (duration: 101.804558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:41:21.165764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.479419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:1 size:840"} {"level":"info","ts":"2026-06-27T05:41:21.165815Z","caller":"traceutil/trace.go:171","msg":"trace[1506682573] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:1; response_revision:1357; }","duration":"101.577176ms","start":"2026-06-27T05:41:21.064224Z","end":"2026-06-27T05:41:21.165801Z","steps":["trace[1506682573] 'agreement among raft nodes before linearized reading' (duration: 101.44846ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:41:22.797121Z","caller":"traceutil/trace.go:171","msg":"trace[2095071699] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1428; }","duration":"168.46239ms","start":"2026-06-27T05:41:22.628629Z","end":"2026-06-27T05:41:22.797091Z","steps":["trace[2095071699] 'read index received' (duration: 168.252997ms)","trace[2095071699] 'applied index is now lower than readState.Index' (duration: 208.063µs)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:41:22.797244Z","caller":"traceutil/trace.go:171","msg":"trace[524132668] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"220.360109ms","start":"2026-06-27T05:41:22.576851Z","end":"2026-06-27T05:41:22.797211Z","steps":["trace[524132668] 'process raft request' (duration: 220.051169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:41:22.797337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.655274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-06-27T05:41:22.797374Z","caller":"traceutil/trace.go:171","msg":"trace[365208057] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:1362; }","duration":"168.782681ms","start":"2026-06-27T05:41:22.628581Z","end":"2026-06-27T05:41:22.797363Z","steps":["trace[365208057] 'agreement among raft nodes before linearized reading' (duration: 168.657945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:41:22.79738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.462045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:disruption-controller\" ","response":"range_response_count:1 size:972"} {"level":"info","ts":"2026-06-27T05:41:22.797435Z","caller":"traceutil/trace.go:171","msg":"trace[458782833] range","detail":"{range_begin:/registry/clusterroles/system:controller:disruption-controller; range_end:; response_count:1; response_revision:1362; }","duration":"166.541113ms","start":"2026-06-27T05:41:22.630872Z","end":"2026-06-27T05:41:22.797413Z","steps":["trace[458782833] 'agreement among raft nodes before linearized reading' (duration: 166.388188ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:41:24.735174Z","caller":"traceutil/trace.go:171","msg":"trace[354643771] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"198.932895ms","start":"2026-06-27T05:41:24.536219Z","end":"2026-06-27T05:41:24.735152Z","steps":["trace[354643771] 'process raft request' (duration: 184.769281ms)","trace[354643771] 'compare' (duration: 13.238103ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:41:24.735424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.234831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-06-27T05:41:24.735528Z","caller":"traceutil/trace.go:171","msg":"trace[1763715082] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation; range_end:; response_count:1; response_revision:1367; }","duration":"129.419925ms","start":"2026-06-27T05:41:24.606092Z","end":"2026-06-27T05:41:24.735512Z","steps":["trace[1763715082] 'agreement among raft nodes before linearized reading' (duration: 129.159993ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:41:24.735191Z","caller":"traceutil/trace.go:171","msg":"trace[1837723842] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1434; }","duration":"128.979688ms","start":"2026-06-27T05:41:24.606135Z","end":"2026-06-27T05:41:24.735115Z","steps":["trace[1837723842] 'read index received' (duration: 114.743957ms)","trace[1837723842] 'applied index is now lower than readState.Index' (duration: 14.233981ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T05:41:24.736127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.632417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-27T05:41:24.736162Z","caller":"traceutil/trace.go:171","msg":"trace[49435117] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-proxier; range_end:; response_count:1; response_revision:1367; }","duration":"119.709024ms","start":"2026-06-27T05:41:24.616445Z","end":"2026-06-27T05:41:24.736154Z","steps":["trace[49435117] 'agreement among raft nodes before linearized reading' (duration: 119.621657ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:41:24.917192Z","caller":"traceutil/trace.go:171","msg":"trace[959935169] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1435; }","duration":"102.322369ms","start":"2026-06-27T05:41:24.814842Z","end":"2026-06-27T05:41:24.917165Z","steps":["trace[959935169] 'read index received' (duration: 99.503006ms)","trace[959935169] 'applied index is now lower than readState.Index' (duration: 2.818043ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T05:41:24.917284Z","caller":"traceutil/trace.go:171","msg":"trace[870764283] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"114.995969ms","start":"2026-06-27T05:41:24.802258Z","end":"2026-06-27T05:41:24.917254Z","steps":["trace[870764283] 'process raft request' (duration: 112.115858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T05:41:24.917368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.520613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-27T05:41:24.91742Z","caller":"traceutil/trace.go:171","msg":"trace[935817667] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-proxier; range_end:; response_count:1; response_revision:1368; }","duration":"102.606561ms","start":"2026-06-27T05:41:24.814801Z","end":"2026-06-27T05:41:24.917408Z","steps":["trace[935817667] 'agreement among raft nodes before linearized reading' (duration: 102.518143ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:41:27.049549Z","caller":"traceutil/trace.go:171","msg":"trace[1360230255] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"126.278011ms","start":"2026-06-27T05:41:26.923249Z","end":"2026-06-27T05:41:27.049527Z","steps":["trace[1360230255] 'process raft request' (duration: 126.098146ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:41:28.574915Z","caller":"traceutil/trace.go:171","msg":"trace[2078381114] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"137.347916ms","start":"2026-06-27T05:41:28.437342Z","end":"2026-06-27T05:41:28.57469Z","steps":["trace[2078381114] 'process raft request' (duration: 137.141512ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T05:41:29.642248Z","caller":"traceutil/trace.go:171","msg":"trace[373092497] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"110.453905ms","start":"2026-06-27T05:41:29.531751Z","end":"2026-06-27T05:41:29.642205Z","steps":["trace[373092497] 'process raft request' (duration: 110.26006ms)"],"step_count":1}