{"level":"warn","ts":"2026-07-23T18:34:57.651155Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T18:34:57.651225Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.40: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.19.213.40:2380","--initial-cluster=instance=https://199.19.213.40:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.40:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.40:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-23T18:34:57.651281Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T18:34:57.65129Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.40:2380"]} {"level":"info","ts":"2026-07-23T18:34:57.651309Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-23T18:34:57.65189Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.40:2379"]} {"level":"info","ts":"2026-07-23T18:34:57.651951Z","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.19.213.40:2380"],"listen-peer-urls":["https://199.19.213.40:2380"],"advertise-client-urls":["https://199.19.213.40:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.40:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.40:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-23T18:34:57.660631Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.498868ms"} {"level":"info","ts":"2026-07-23T18:34:57.671019Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1082227b3dfb8db7","cluster-id":"1c36bbf7868e18b9"} {"level":"info","ts":"2026-07-23T18:34:57.671086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T18:34:57.671129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 became follower at term 0"} {"level":"info","ts":"2026-07-23T18:34:57.671142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1082227b3dfb8db7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T18:34:57.671157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 became follower at term 1"} {"level":"info","ts":"2026-07-23T18:34:57.671195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 switched to configuration voters=(1189551164295450039)"} {"level":"warn","ts":"2026-07-23T18:34:57.683594Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T18:34:57.685143Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T18:34:57.687704Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-23T18:34:57.691155Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1082227b3dfb8db7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T18:34:57.691966Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1082227b3dfb8db7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T18:34:57.693958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 switched to configuration voters=(1189551164295450039)"} {"level":"info","ts":"2026-07-23T18:34:57.693315Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-23T18:34:57.694784Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-23T18:34:57.694801Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-23T18:34:57.695978Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1c36bbf7868e18b9","local-member-id":"1082227b3dfb8db7","added-peer-id":"1082227b3dfb8db7","added-peer-peer-urls":["https://199.19.213.40:2380"]} {"level":"info","ts":"2026-07-23T18:34:57.697208Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-23T18:34:57.697297Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.40:2380"} {"level":"info","ts":"2026-07-23T18:34:57.697319Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.40:2380"} {"level":"info","ts":"2026-07-23T18:34:57.69737Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1082227b3dfb8db7","initial-advertise-peer-urls":["https://199.19.213.40:2380"],"listen-peer-urls":["https://199.19.213.40:2380"],"advertise-client-urls":["https://199.19.213.40:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.40:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T18:34:57.697406Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T18:34:57.971505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T18:34:57.971637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T18:34:57.971715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 received MsgPreVoteResp from 1082227b3dfb8db7 at term 1"} {"level":"info","ts":"2026-07-23T18:34:57.971775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 became candidate at term 2"} {"level":"info","ts":"2026-07-23T18:34:57.971814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 received MsgVoteResp from 1082227b3dfb8db7 at term 2"} {"level":"info","ts":"2026-07-23T18:34:57.97186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1082227b3dfb8db7 became leader at term 2"} {"level":"info","ts":"2026-07-23T18:34:57.971901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1082227b3dfb8db7 elected leader 1082227b3dfb8db7 at term 2"} {"level":"info","ts":"2026-07-23T18:34:57.975331Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T18:34:57.977766Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1082227b3dfb8db7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.40:2379]}","request-path":"/0/members/1082227b3dfb8db7/attributes","cluster-id":"1c36bbf7868e18b9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T18:34:57.977775Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T18:34:57.978058Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T18:34:57.977812Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T18:34:57.978101Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T18:34:57.978977Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1c36bbf7868e18b9","local-member-id":"1082227b3dfb8db7","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T18:34:57.979177Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T18:34:57.979316Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T18:34:57.981856Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.40:2379"} {"level":"info","ts":"2026-07-23T18:34:57.982449Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-23T18:35:09.019355Z","caller":"traceutil/trace.go:171","msg":"trace[515844701] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:366; }","duration":"175.028743ms","start":"2026-07-23T18:35:08.844305Z","end":"2026-07-23T18:35:09.019334Z","steps":["trace[515844701] 'read index received' (duration: 97.100758ms)","trace[515844701] 'applied index is now lower than readState.Index' (duration: 77.927425ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:09.019415Z","caller":"traceutil/trace.go:171","msg":"trace[393098341] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"306.56264ms","start":"2026-07-23T18:35:08.712825Z","end":"2026-07-23T18:35:09.019388Z","steps":["trace[393098341] 'process raft request' (duration: 228.667385ms)","trace[393098341] 'compare' (duration: 77.736263ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:09.019474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.164076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T18:35:09.019523Z","caller":"traceutil/trace.go:171","msg":"trace[1017401012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:355; }","duration":"175.262647ms","start":"2026-07-23T18:35:08.844252Z","end":"2026-07-23T18:35:09.019514Z","steps":["trace[1017401012] 'agreement among raft nodes before linearized reading' (duration: 175.163255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:09.019808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:08.712807Z","time spent":"306.657681ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:35:09.577874Z","caller":"traceutil/trace.go:171","msg":"trace[957407227] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"458.641343ms","start":"2026-07-23T18:35:09.119216Z","end":"2026-07-23T18:35:09.577857Z","steps":["trace[957407227] 'process raft request' (duration: 458.534561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:09.577978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:09.119196Z","time spent":"458.727793ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:35:15.788638Z","caller":"traceutil/trace.go:171","msg":"trace[1187958475] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"198.714418ms","start":"2026-07-23T18:35:15.589889Z","end":"2026-07-23T18:35:15.788603Z","steps":["trace[1187958475] 'process raft request' (duration: 175.502094ms)","trace[1187958475] 'compare' (duration: 22.973392ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:18.129826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.086603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:35:18.129939Z","caller":"traceutil/trace.go:171","msg":"trace[2038826555] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"128.137069ms","start":"2026-07-23T18:35:18.001781Z","end":"2026-07-23T18:35:18.129918Z","steps":["trace[2038826555] 'process raft request' (duration: 25.729082ms)","trace[2038826555] 'compare' (duration: 101.955741ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:20.00305Z","caller":"traceutil/trace.go:171","msg":"trace[1076942734] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"158.624719ms","start":"2026-07-23T18:35:19.844393Z","end":"2026-07-23T18:35:20.003018Z","steps":["trace[1076942734] 'process raft request' (duration: 158.486447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:20.634066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10211806122023783049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T18:35:21.149029Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10211806122023783049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T18:35:21.649923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10211806122023783049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T18:35:22.150477Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10211806122023783049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T18:35:22.168424Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.160320191s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T18:35:22.168884Z","caller":"traceutil/trace.go:171","msg":"trace[1853282775] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"2.160852577s","start":"2026-07-23T18:35:20.008Z","end":"2026-07-23T18:35:22.168852Z","steps":["trace[1853282775] 'process raft request' (duration: 2.160695185s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:22.169073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:20.007978Z","time spent":"2.160975708s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:35:22.182235Z","caller":"traceutil/trace.go:171","msg":"trace[1848752995] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:402; }","duration":"2.048604713s","start":"2026-07-23T18:35:20.133458Z","end":"2026-07-23T18:35:22.182063Z","steps":["trace[1848752995] 'read index received' (duration: 2.035190258s)","trace[1848752995] 'applied index is now lower than readState.Index' (duration: 13.413625ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:22.182436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.458654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-23T18:35:22.182423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.048910886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T18:35:22.1825Z","caller":"traceutil/trace.go:171","msg":"trace[307153066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:390; }","duration":"175.574235ms","start":"2026-07-23T18:35:22.006909Z","end":"2026-07-23T18:35:22.182483Z","steps":["trace[307153066] 'agreement among raft nodes before linearized reading' (duration: 175.436713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:22.182508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.123407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.40\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-23T18:35:22.182558Z","caller":"traceutil/trace.go:171","msg":"trace[781614483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:390; }","duration":"2.049097479s","start":"2026-07-23T18:35:20.133407Z","end":"2026-07-23T18:35:22.182504Z","steps":["trace[781614483] 'agreement among raft nodes before linearized reading' (duration: 2.048812586s)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:22.182629Z","caller":"traceutil/trace.go:171","msg":"trace[1977333625] range","detail":"{range_begin:/registry/masterleases/199.19.213.40; range_end:; response_count:1; response_revision:390; }","duration":"778.239638ms","start":"2026-07-23T18:35:21.4043Z","end":"2026-07-23T18:35:22.18254Z","steps":["trace[1977333625] 'agreement among raft nodes before linearized reading' (duration: 778.058876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:22.182661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:20.133377Z","time spent":"2.049269471s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T18:35:22.182738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:21.404289Z","time spent":"778.42057ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.40\" "} {"level":"warn","ts":"2026-07-23T18:35:22.182661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.850355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T18:35:22.182904Z","caller":"traceutil/trace.go:171","msg":"trace[1350077308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:390; }","duration":"887.132178ms","start":"2026-07-23T18:35:21.295759Z","end":"2026-07-23T18:35:22.182891Z","steps":["trace[1350077308] 'agreement among raft nodes before linearized reading' (duration: 886.716964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:22.182956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:21.295738Z","time spent":"887.204769ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-23T18:35:22.182986Z","caller":"traceutil/trace.go:171","msg":"trace[513611374] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"2.163946132s","start":"2026-07-23T18:35:20.018226Z","end":"2026-07-23T18:35:22.182172Z","steps":["trace[513611374] 'process raft request' (duration: 2.163687479s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:22.183114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.484408912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:35:22.183188Z","caller":"traceutil/trace.go:171","msg":"trace[1572913812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:390; }","duration":"1.484483082s","start":"2026-07-23T18:35:20.698659Z","end":"2026-07-23T18:35:22.183143Z","steps":["trace[1572913812] 'agreement among raft nodes before linearized reading' (duration: 1.483675223s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:22.183235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:20.698636Z","time spent":"1.484588354s","remote":"127.0.0.1:39926","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-07-23T18:35:22.183283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:20.018205Z","time spent":"2.164901313s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:35:23.828833Z","caller":"traceutil/trace.go:171","msg":"trace[1933450803] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:409; }","duration":"131.766414ms","start":"2026-07-23T18:35:23.697042Z","end":"2026-07-23T18:35:23.828809Z","steps":["trace[1933450803] 'read index received' (duration: 131.569012ms)","trace[1933450803] 'applied index is now lower than readState.Index' (duration: 196.222µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:23.828993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.935897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:35:23.829058Z","caller":"traceutil/trace.go:171","msg":"trace[1305079659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:395; }","duration":"132.046229ms","start":"2026-07-23T18:35:23.696993Z","end":"2026-07-23T18:35:23.829039Z","steps":["trace[1305079659] 'agreement among raft nodes before linearized reading' (duration: 131.905946ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:23.829014Z","caller":"traceutil/trace.go:171","msg":"trace[404270233] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"144.019265ms","start":"2026-07-23T18:35:23.684926Z","end":"2026-07-23T18:35:23.828945Z","steps":["trace[404270233] 'process raft request' (duration: 143.693861ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:24.358398Z","caller":"traceutil/trace.go:171","msg":"trace[863404749] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:410; }","duration":"153.790121ms","start":"2026-07-23T18:35:24.20458Z","end":"2026-07-23T18:35:24.35837Z","steps":["trace[863404749] 'read index received' (duration: 153.600919ms)","trace[863404749] 'applied index is now lower than readState.Index' (duration: 187.972µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:24.358634Z","caller":"traceutil/trace.go:171","msg":"trace[2022988803] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"181.260467ms","start":"2026-07-23T18:35:24.177363Z","end":"2026-07-23T18:35:24.358623Z","steps":["trace[2022988803] 'process raft request' (duration: 180.887232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:24.358837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.253117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-23T18:35:24.358879Z","caller":"traceutil/trace.go:171","msg":"trace[238365401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:396; }","duration":"154.346269ms","start":"2026-07-23T18:35:24.204521Z","end":"2026-07-23T18:35:24.358868Z","steps":["trace[238365401] 'agreement among raft nodes before linearized reading' (duration: 154.270578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:24.359062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.910677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T18:35:24.359165Z","caller":"traceutil/trace.go:171","msg":"trace[1668345905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:396; }","duration":"142.042268ms","start":"2026-07-23T18:35:24.217102Z","end":"2026-07-23T18:35:24.359144Z","steps":["trace[1668345905] 'agreement among raft nodes before linearized reading' (duration: 141.869306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:24.359224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.491842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T18:35:24.359267Z","caller":"traceutil/trace.go:171","msg":"trace[470289157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:396; }","duration":"141.577442ms","start":"2026-07-23T18:35:24.217678Z","end":"2026-07-23T18:35:24.359255Z","steps":["trace[470289157] 'agreement among raft nodes before linearized reading' (duration: 141.516131ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:24.559377Z","caller":"traceutil/trace.go:171","msg":"trace[1536986502] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"195.679482ms","start":"2026-07-23T18:35:24.363673Z","end":"2026-07-23T18:35:24.559352Z","steps":["trace[1536986502] 'process raft request' (duration: 169.000067ms)","trace[1536986502] 'compare' (duration: 26.528694ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:24.958554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.323824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:35:24.958812Z","caller":"traceutil/trace.go:171","msg":"trace[1651782816] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:412; }","duration":"260.479594ms","start":"2026-07-23T18:35:24.698315Z","end":"2026-07-23T18:35:24.958795Z","steps":["trace[1651782816] 'read index received' (duration: 32.883847ms)","trace[1651782816] 'applied index is now lower than readState.Index' (duration: 227.594757ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:24.958912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.595035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:35:24.958965Z","caller":"traceutil/trace.go:171","msg":"trace[960635506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:399; }","duration":"260.688997ms","start":"2026-07-23T18:35:24.698265Z","end":"2026-07-23T18:35:24.958954Z","steps":["trace[960635506] 'agreement among raft nodes before linearized reading' (duration: 260.608906ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:24.959082Z","caller":"traceutil/trace.go:171","msg":"trace[683833023] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"595.299111ms","start":"2026-07-23T18:35:24.363748Z","end":"2026-07-23T18:35:24.959047Z","steps":["trace[683833023] 'process raft request' (duration: 367.40642ms)","trace[683833023] 'compare' (duration: 227.062311ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:24.95927Z","caller":"traceutil/trace.go:171","msg":"trace[814362769] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"594.572502ms","start":"2026-07-23T18:35:24.364684Z","end":"2026-07-23T18:35:24.959256Z","steps":["trace[814362769] 'process raft request' (duration: 594.040566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:24.959289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:24.363732Z","time spent":"595.459052ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T18:35:24.959408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:24.364657Z","time spent":"594.670853ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:35:26.873461Z","caller":"traceutil/trace.go:171","msg":"trace[752940943] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:415; }","duration":"174.682958ms","start":"2026-07-23T18:35:26.698719Z","end":"2026-07-23T18:35:26.873402Z","steps":["trace[752940943] 'read index received' (duration: 174.572237ms)","trace[752940943] 'applied index is now lower than readState.Index' (duration: 109.681µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:26.873582Z","caller":"traceutil/trace.go:171","msg":"trace[149574007] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"288.453775ms","start":"2026-07-23T18:35:26.585088Z","end":"2026-07-23T18:35:26.873541Z","steps":["trace[149574007] 'process raft request' (duration: 288.173142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:26.873648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.86199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:35:26.873696Z","caller":"traceutil/trace.go:171","msg":"trace[778307016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"175.002172ms","start":"2026-07-23T18:35:26.69868Z","end":"2026-07-23T18:35:26.873682Z","steps":["trace[778307016] 'agreement among raft nodes before linearized reading' (duration: 174.851111ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:27.262216Z","caller":"traceutil/trace.go:171","msg":"trace[1176222241] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"108.36304ms","start":"2026-07-23T18:35:27.153829Z","end":"2026-07-23T18:35:27.262192Z","steps":["trace[1176222241] 'process raft request' (duration: 108.227719ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:28.665142Z","caller":"traceutil/trace.go:171","msg":"trace[781044537] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"195.652034ms","start":"2026-07-23T18:35:28.469465Z","end":"2026-07-23T18:35:28.665117Z","steps":["trace[781044537] 'process raft request' (duration: 195.509072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:29.090483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.915396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-07-23T18:35:29.090567Z","caller":"traceutil/trace.go:171","msg":"trace[1330462087] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:405; }","duration":"297.035828ms","start":"2026-07-23T18:35:28.79351Z","end":"2026-07-23T18:35:29.090546Z","steps":["trace[1330462087] 'range keys from in-memory index tree' (duration: 296.774774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:29.090571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.518882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T18:35:29.090651Z","caller":"traceutil/trace.go:171","msg":"trace[1450354716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"213.632384ms","start":"2026-07-23T18:35:28.877Z","end":"2026-07-23T18:35:29.090632Z","steps":["trace[1450354716] 'range keys from in-memory index tree' (duration: 213.189089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:29.090514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.819458ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:35:29.090994Z","caller":"traceutil/trace.go:171","msg":"trace[1945366812] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:405; }","duration":"223.311463ms","start":"2026-07-23T18:35:28.867661Z","end":"2026-07-23T18:35:29.090972Z","steps":["trace[1945366812] 'range keys from in-memory index tree' (duration: 222.812188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:29.091012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.716247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T18:35:29.091098Z","caller":"traceutil/trace.go:171","msg":"trace[993210350] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:405; }","duration":"108.389244ms","start":"2026-07-23T18:35:28.982689Z","end":"2026-07-23T18:35:29.091078Z","steps":["trace[993210350] 'range keys from in-memory index tree' (duration: 107.629745ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:29.45202Z","caller":"traceutil/trace.go:171","msg":"trace[168573789] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"115.745166ms","start":"2026-07-23T18:35:29.336247Z","end":"2026-07-23T18:35:29.451993Z","steps":["trace[168573789] 'read index received' (duration: 64.985384ms)","trace[168573789] 'applied index is now lower than readState.Index' (duration: 50.758702ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:29.452155Z","caller":"traceutil/trace.go:171","msg":"trace[1448723646] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"125.90417ms","start":"2026-07-23T18:35:29.326214Z","end":"2026-07-23T18:35:29.452118Z","steps":["trace[1448723646] 'process raft request' (duration: 74.932845ms)","trace[1448723646] 'compare' (duration: 50.705622ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:29.452213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.978488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-07-23T18:35:29.452249Z","caller":"traceutil/trace.go:171","msg":"trace[924407331] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:420; }","duration":"124.046829ms","start":"2026-07-23T18:35:29.328192Z","end":"2026-07-23T18:35:29.452239Z","steps":["trace[924407331] 'agreement among raft nodes before linearized reading' (duration: 123.898077ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:29.452907Z","caller":"traceutil/trace.go:171","msg":"trace[660034310] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"114.223438ms","start":"2026-07-23T18:35:29.338665Z","end":"2026-07-23T18:35:29.452889Z","steps":["trace[660034310] 'process raft request' (duration: 114.088957ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:29.64327Z","caller":"traceutil/trace.go:171","msg":"trace[1878377379] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:443; }","duration":"162.073648ms","start":"2026-07-23T18:35:29.481177Z","end":"2026-07-23T18:35:29.64325Z","steps":["trace[1878377379] 'read index received' (duration: 32.449876ms)","trace[1878377379] 'applied index is now lower than readState.Index' (duration: 129.622832ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:29.643503Z","caller":"traceutil/trace.go:171","msg":"trace[511358806] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"167.891133ms","start":"2026-07-23T18:35:29.475571Z","end":"2026-07-23T18:35:29.643462Z","steps":["trace[511358806] 'process raft request' (duration: 158.438797ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:29.643508Z","caller":"traceutil/trace.go:171","msg":"trace[879990519] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"163.295322ms","start":"2026-07-23T18:35:29.480181Z","end":"2026-07-23T18:35:29.643476Z","steps":["trace[879990519] 'process raft request' (duration: 163.027829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:29.643533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.190919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:35:29.643734Z","caller":"traceutil/trace.go:171","msg":"trace[1223743196] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:428; }","duration":"162.554063ms","start":"2026-07-23T18:35:29.481148Z","end":"2026-07-23T18:35:29.643702Z","steps":["trace[1223743196] 'agreement among raft nodes before linearized reading' (duration: 162.181999ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:29.644229Z","caller":"traceutil/trace.go:171","msg":"trace[343719028] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"124.212911ms","start":"2026-07-23T18:35:29.520005Z","end":"2026-07-23T18:35:29.644218Z","steps":["trace[343719028] 'process raft request' (duration: 124.12963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:30.378215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.655672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:35:30.378317Z","caller":"traceutil/trace.go:171","msg":"trace[1706980126] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:452; }","duration":"660.647858ms","start":"2026-07-23T18:35:29.71765Z","end":"2026-07-23T18:35:30.378298Z","steps":["trace[1706980126] 'read index received' (duration: 309.728353ms)","trace[1706980126] 'applied index is now lower than readState.Index' (duration: 350.918195ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:30.378384Z","caller":"traceutil/trace.go:171","msg":"trace[1664662100] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"676.375446ms","start":"2026-07-23T18:35:29.701975Z","end":"2026-07-23T18:35:30.37835Z","steps":["trace[1664662100] 'process raft request' (duration: 325.494571ms)","trace[1664662100] 'compare' (duration: 350.329999ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:30.378449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.77555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T18:35:30.378484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.534661ms","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-07-23T18:35:30.378533Z","caller":"traceutil/trace.go:171","msg":"trace[1950590990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:436; }","duration":"660.909152ms","start":"2026-07-23T18:35:29.717603Z","end":"2026-07-23T18:35:30.378512Z","steps":["trace[1950590990] 'agreement among raft nodes before linearized reading' (duration: 660.758209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:30.378572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:29.701953Z","time spent":"676.509477ms","remote":"127.0.0.1:40328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:35:30.378596Z","caller":"traceutil/trace.go:171","msg":"trace[1418422008] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:436; }","duration":"655.648783ms","start":"2026-07-23T18:35:29.722905Z","end":"2026-07-23T18:35:30.378554Z","steps":["trace[1418422008] 'agreement among raft nodes before linearized reading' (duration: 655.494921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:30.378635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:29.717581Z","time spent":"660.989802ms","remote":"127.0.0.1:39930","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-07-23T18:35:30.378701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:29.722874Z","time spent":"655.815635ms","remote":"127.0.0.1:40364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3877,"request content":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" "} {"level":"warn","ts":"2026-07-23T18:35:31.172125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10211806122023783138,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T18:35:31.673148Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10211806122023783138,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T18:35:32.173863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10211806122023783138,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T18:35:32.276398Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.89090112s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T18:35:32.298381Z","caller":"traceutil/trace.go:171","msg":"trace[1605407153] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:453; }","duration":"1.627344155s","start":"2026-07-23T18:35:30.671001Z","end":"2026-07-23T18:35:32.298345Z","steps":["trace[1605407153] 'read index received' (duration: 1.605520471s)","trace[1605407153] 'applied index is now lower than readState.Index' (duration: 21.821504ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:32.298531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627557777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-23T18:35:32.298558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145756395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T18:35:32.298599Z","caller":"traceutil/trace.go:171","msg":"trace[440315545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:437; }","duration":"1.145828826s","start":"2026-07-23T18:35:31.152756Z","end":"2026-07-23T18:35:32.298585Z","steps":["trace[440315545] 'agreement among raft nodes before linearized reading' (duration: 1.145725114s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:32.298584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.924211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.40\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-23T18:35:32.298598Z","caller":"traceutil/trace.go:171","msg":"trace[996688755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:437; }","duration":"1.627664979s","start":"2026-07-23T18:35:30.670916Z","end":"2026-07-23T18:35:32.298581Z","steps":["trace[996688755] 'agreement among raft nodes before linearized reading' (duration: 1.627547307s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:32.298634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:31.152741Z","time spent":"1.145884526s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T18:35:32.298654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:30.670893Z","time spent":"1.627745209s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-23T18:35:32.298657Z","caller":"traceutil/trace.go:171","msg":"trace[333780217] range","detail":"{range_begin:/registry/masterleases/199.19.213.40; range_end:; response_count:1; response_revision:437; }","duration":"894.044833ms","start":"2026-07-23T18:35:31.404585Z","end":"2026-07-23T18:35:32.29863Z","steps":["trace[333780217] 'agreement among raft nodes before linearized reading' (duration: 893.935362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:32.298674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.551121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T18:35:32.298704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:31.404564Z","time spent":"894.127434ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.40\" "} {"level":"info","ts":"2026-07-23T18:35:32.298735Z","caller":"traceutil/trace.go:171","msg":"trace[2092964145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:437; }","duration":"599.641732ms","start":"2026-07-23T18:35:31.69907Z","end":"2026-07-23T18:35:32.298712Z","steps":["trace[2092964145] 'agreement among raft nodes before linearized reading' (duration: 599.48811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:32.298778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:31.69905Z","time spent":"599.713713ms","remote":"127.0.0.1:39926","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-07-23T18:35:32.298857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128602362s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T18:35:32.29889Z","caller":"traceutil/trace.go:171","msg":"trace[1442208118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:437; }","duration":"1.128675683s","start":"2026-07-23T18:35:31.170205Z","end":"2026-07-23T18:35:32.29888Z","steps":["trace[1442208118] 'agreement among raft nodes before linearized reading' (duration: 1.128615462s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:32.298919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:31.170175Z","time spent":"1.128737263s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T18:35:32.298924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128721233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T18:35:32.298957Z","caller":"traceutil/trace.go:171","msg":"trace[1785550694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:437; }","duration":"1.128799685s","start":"2026-07-23T18:35:31.170149Z","end":"2026-07-23T18:35:32.298949Z","steps":["trace[1785550694] 'agreement among raft nodes before linearized reading' (duration: 1.128726614s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:32.298977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:31.170125Z","time spent":"1.128847325s","remote":"127.0.0.1:40150","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-07-23T18:35:32.298286Z","caller":"traceutil/trace.go:171","msg":"trace[2063040923] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"1.912866436s","start":"2026-07-23T18:35:30.385394Z","end":"2026-07-23T18:35:32.29826Z","steps":["trace[2063040923] 'process raft request' (duration: 1.891196274s)","trace[2063040923] 'compare' (duration: 21.216597ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:32.300017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:30.385371Z","time spent":"1.914581254s","remote":"127.0.0.1:40364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:35:38.482924Z","caller":"traceutil/trace.go:171","msg":"trace[1341178646] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"109.131649ms","start":"2026-07-23T18:35:38.373759Z","end":"2026-07-23T18:35:38.48289Z","steps":["trace[1341178646] 'process raft request' (duration: 108.904726ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:38.575716Z","caller":"traceutil/trace.go:171","msg":"trace[1376848516] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:472; }","duration":"191.257913ms","start":"2026-07-23T18:35:38.384429Z","end":"2026-07-23T18:35:38.575687Z","steps":["trace[1376848516] 'read index received' (duration: 98.318321ms)","trace[1376848516] 'applied index is now lower than readState.Index' (duration: 92.938462ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:38.575851Z","caller":"traceutil/trace.go:171","msg":"trace[238373803] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"200.996449ms","start":"2026-07-23T18:35:38.374842Z","end":"2026-07-23T18:35:38.575839Z","steps":["trace[238373803] 'process raft request' (duration: 133.793511ms)","trace[238373803] 'compare' (duration: 66.913245ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:38.575979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.526626ms","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-07-23T18:35:38.576026Z","caller":"traceutil/trace.go:171","msg":"trace[1508061377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"191.605216ms","start":"2026-07-23T18:35:38.384409Z","end":"2026-07-23T18:35:38.576015Z","steps":["trace[1508061377] 'agreement among raft nodes before linearized reading' (duration: 191.505475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:38.576102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.626947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T18:35:38.576234Z","caller":"traceutil/trace.go:171","msg":"trace[1835974684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:455; }","duration":"191.811588ms","start":"2026-07-23T18:35:38.384387Z","end":"2026-07-23T18:35:38.576199Z","steps":["trace[1835974684] 'agreement among raft nodes before linearized reading' (duration: 191.539425ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:42.429749Z","caller":"traceutil/trace.go:171","msg":"trace[62309945] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"141.458497ms","start":"2026-07-23T18:35:42.288271Z","end":"2026-07-23T18:35:42.429729Z","steps":["trace[62309945] 'process raft request' (duration: 141.353776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:42.697633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.092201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:35:42.698129Z","caller":"traceutil/trace.go:171","msg":"trace[570347908] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"400.343793ms","start":"2026-07-23T18:35:42.297738Z","end":"2026-07-23T18:35:42.698082Z","steps":["trace[570347908] 'process raft request' (duration: 400.031589ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:42.698199Z","caller":"traceutil/trace.go:171","msg":"trace[1059400696] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"387.09156ms","start":"2026-07-23T18:35:42.311069Z","end":"2026-07-23T18:35:42.698161Z","steps":["trace[1059400696] 'process raft request' (duration: 386.782866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:42.698319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:42.297733Z","time spent":"400.495895ms","remote":"127.0.0.1:39964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T18:35:42.698353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:42.311061Z","time spent":"387.219511ms","remote":"127.0.0.1:39964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T18:35:42.698384Z","caller":"traceutil/trace.go:171","msg":"trace[138166421] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"362.759744ms","start":"2026-07-23T18:35:42.335601Z","end":"2026-07-23T18:35:42.698361Z","steps":["trace[138166421] 'process raft request' (duration: 362.620882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:42.69846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:42.335572Z","time spent":"362.858035ms","remote":"127.0.0.1:39964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T18:35:42.698525Z","caller":"traceutil/trace.go:171","msg":"trace[1313406581] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"400.604796ms","start":"2026-07-23T18:35:42.297903Z","end":"2026-07-23T18:35:42.698508Z","steps":["trace[1313406581] 'process raft request' (duration: 399.905129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:42.698574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:42.297899Z","time spent":"400.651847ms","remote":"127.0.0.1:39964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T18:35:42.698617Z","caller":"traceutil/trace.go:171","msg":"trace[386774370] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"387.544334ms","start":"2026-07-23T18:35:42.311063Z","end":"2026-07-23T18:35:42.698607Z","steps":["trace[386774370] 'process raft request' (duration: 386.830727ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:42.698661Z","caller":"traceutil/trace.go:171","msg":"trace[1832821030] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"387.508434ms","start":"2026-07-23T18:35:42.311144Z","end":"2026-07-23T18:35:42.698652Z","steps":["trace[1832821030] 'process raft request' (duration: 386.827827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:42.698661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:42.311044Z","time spent":"387.596295ms","remote":"127.0.0.1:39964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T18:35:42.698703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:42.311137Z","time spent":"387.545364ms","remote":"127.0.0.1:39964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T18:35:42.698731Z","caller":"traceutil/trace.go:171","msg":"trace[1106545467] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"404.379916ms","start":"2026-07-23T18:35:42.294325Z","end":"2026-07-23T18:35:42.698705Z","steps":["trace[1106545467] 'process raft request' (duration: 288.150483ms)","trace[1106545467] 'compare' (duration: 114.95388ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:42.698785Z","caller":"traceutil/trace.go:171","msg":"trace[792428127] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"387.657465ms","start":"2026-07-23T18:35:42.311119Z","end":"2026-07-23T18:35:42.698776Z","steps":["trace[792428127] 'process raft request' (duration: 386.817096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:42.698821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:42.311115Z","time spent":"387.688475ms","remote":"127.0.0.1:39964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T18:35:42.698817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:42.294307Z","time spent":"404.470127ms","remote":"127.0.0.1:39964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T18:35:42.698854Z","caller":"traceutil/trace.go:171","msg":"trace[2132132385] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"375.442604ms","start":"2026-07-23T18:35:42.323399Z","end":"2026-07-23T18:35:42.698842Z","steps":["trace[2132132385] 'process raft request' (duration: 374.648944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:42.6989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:42.323374Z","time spent":"375.504764ms","remote":"127.0.0.1:39964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T18:35:42.699045Z","caller":"traceutil/trace.go:171","msg":"trace[1724715048] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"401.396116ms","start":"2026-07-23T18:35:42.297627Z","end":"2026-07-23T18:35:42.699023Z","steps":["trace[1724715048] 'process raft request' (duration: 400.102671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:42.69911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:42.297608Z","time spent":"401.463686ms","remote":"127.0.0.1:39964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T18:35:42.714207Z","caller":"traceutil/trace.go:171","msg":"trace[522261408] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:518; }","duration":"214.099377ms","start":"2026-07-23T18:35:42.500093Z","end":"2026-07-23T18:35:42.714193Z","steps":["trace[522261408] 'read index received' (duration: 82.440686ms)","trace[522261408] 'applied index is now lower than readState.Index' (duration: 131.658021ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:42.714892Z","caller":"traceutil/trace.go:171","msg":"trace[734984443] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"239.923588ms","start":"2026-07-23T18:35:42.47493Z","end":"2026-07-23T18:35:42.714853Z","steps":["trace[734984443] 'process raft request' (duration: 239.064909ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:42.715102Z","caller":"traceutil/trace.go:171","msg":"trace[461429315] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"268.138955ms","start":"2026-07-23T18:35:42.446957Z","end":"2026-07-23T18:35:42.715096Z","steps":["trace[461429315] 'process raft request' (duration: 266.925472ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:42.715131Z","caller":"traceutil/trace.go:171","msg":"trace[1942201666] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"239.711966ms","start":"2026-07-23T18:35:42.475407Z","end":"2026-07-23T18:35:42.715119Z","steps":["trace[1942201666] 'process raft request' (duration: 238.741386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:42.715289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.19052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T18:35:42.71532Z","caller":"traceutil/trace.go:171","msg":"trace[514616528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"215.336721ms","start":"2026-07-23T18:35:42.499976Z","end":"2026-07-23T18:35:42.715313Z","steps":["trace[514616528] 'agreement among raft nodes before linearized reading' (duration: 215.27365ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:42.715437Z","caller":"traceutil/trace.go:171","msg":"trace[1326057276] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"268.681281ms","start":"2026-07-23T18:35:42.446745Z","end":"2026-07-23T18:35:42.715426Z","steps":["trace[1326057276] 'process raft request' (duration: 267.059744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:44.15744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.036506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:35:44.157547Z","caller":"traceutil/trace.go:171","msg":"trace[114956736] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.21905914s","start":"2026-07-23T18:35:42.938468Z","end":"2026-07-23T18:35:44.157527Z","steps":["trace[114956736] 'process raft request' (duration: 273.855132ms)","trace[114956736] 'compare' (duration: 944.892604ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:44.157644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:42.938431Z","time spent":"1.21917839s","remote":"127.0.0.1:39964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:35:44.159966Z","caller":"traceutil/trace.go:171","msg":"trace[1696041097] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.213180616s","start":"2026-07-23T18:35:42.946763Z","end":"2026-07-23T18:35:44.159943Z","steps":["trace[1696041097] 'process raft request' (duration: 1.213053975s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:44.160083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:42.946735Z","time spent":"1.213287697s","remote":"127.0.0.1:39964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T18:35:44.389461Z","caller":"traceutil/trace.go:171","msg":"trace[1999707565] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"213.296658ms","start":"2026-07-23T18:35:44.176135Z","end":"2026-07-23T18:35:44.389431Z","steps":["trace[1999707565] 'process raft request' (duration: 197.168113ms)","trace[1999707565] 'compare' (duration: 15.560129ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:44.389501Z","caller":"traceutil/trace.go:171","msg":"trace[149053930] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"141.773065ms","start":"2026-07-23T18:35:44.24761Z","end":"2026-07-23T18:35:44.389383Z","steps":["trace[149053930] 'process raft request' (duration: 141.447181ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:44.86489Z","caller":"traceutil/trace.go:171","msg":"trace[155702324] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"107.058158ms","start":"2026-07-23T18:35:44.757813Z","end":"2026-07-23T18:35:44.864872Z","steps":["trace[155702324] 'process raft request' (duration: 47.038628ms)","trace[155702324] 'compare' (duration: 59.943339ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:45.222615Z","caller":"traceutil/trace.go:171","msg":"trace[1096812549] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"101.240463ms","start":"2026-07-23T18:35:45.12135Z","end":"2026-07-23T18:35:45.22259Z","steps":["trace[1096812549] 'process raft request' (duration: 101.078141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:51.455857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10211806122023783442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T18:35:51.873147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17421407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:35:51.873238Z","caller":"traceutil/trace.go:171","msg":"trace[134859487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"1.174359093s","start":"2026-07-23T18:35:50.698856Z","end":"2026-07-23T18:35:51.873215Z","steps":["trace[134859487] 'range keys from in-memory index tree' (duration: 1.174127488s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:51.873283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:50.698834Z","time spent":"1.174439993s","remote":"127.0.0.1:39926","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-07-23T18:35:51.95676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10211806122023783442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T18:35:52.457251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10211806122023783442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T18:35:52.672276Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.812092187s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T18:35:52.673605Z","caller":"traceutil/trace.go:171","msg":"trace[1677455266] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"1.717862252s","start":"2026-07-23T18:35:50.95572Z","end":"2026-07-23T18:35:52.673582Z","steps":["trace[1677455266] 'read index received' (duration: 1.716660959s)","trace[1677455266] 'applied index is now lower than readState.Index' (duration: 1.200193ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:52.673715Z","caller":"traceutil/trace.go:171","msg":"trace[49036192] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.81510655s","start":"2026-07-23T18:35:50.858572Z","end":"2026-07-23T18:35:52.673678Z","steps":["trace[49036192] 'process raft request' (duration: 1.814041729s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:52.673943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:50.858542Z","time spent":"1.815309661s","remote":"127.0.0.1:39996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T18:35:52.673963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.714550727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-23T18:35:52.673984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318587613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qt85x\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-23T18:35:52.674035Z","caller":"traceutil/trace.go:171","msg":"trace[651758988] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qt85x; range_end:; response_count:1; response_revision:608; }","duration":"1.318669533s","start":"2026-07-23T18:35:51.355356Z","end":"2026-07-23T18:35:52.674025Z","steps":["trace[651758988] 'agreement among raft nodes before linearized reading' (duration: 1.318528572s)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:52.674039Z","caller":"traceutil/trace.go:171","msg":"trace[82953948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"1.714685748s","start":"2026-07-23T18:35:50.959338Z","end":"2026-07-23T18:35:52.674023Z","steps":["trace[82953948] 'agreement among raft nodes before linearized reading' (duration: 1.714378615s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:52.674057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:51.355338Z","time spent":"1.318713303s","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-qt85x\" "} {"level":"warn","ts":"2026-07-23T18:35:52.67404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.691916655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-23T18:35:52.67406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.742004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T18:35:52.674086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:50.959322Z","time spent":"1.714751389s","remote":"127.0.0.1:40150","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-07-23T18:35:52.674089Z","caller":"traceutil/trace.go:171","msg":"trace[1636939592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"1.692009417s","start":"2026-07-23T18:35:50.982067Z","end":"2026-07-23T18:35:52.674076Z","steps":["trace[1636939592] 'agreement among raft nodes before linearized reading' (duration: 1.691845175s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:52.674092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.691354739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T18:35:52.674124Z","caller":"traceutil/trace.go:171","msg":"trace[566362423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"770.819845ms","start":"2026-07-23T18:35:51.903272Z","end":"2026-07-23T18:35:52.674092Z","steps":["trace[566362423] 'agreement among raft nodes before linearized reading' (duration: 770.725374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:52.67414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:50.982044Z","time spent":"1.692088207s","remote":"127.0.0.1:40150","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-07-23T18:35:52.674169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:51.903254Z","time spent":"770.903945ms","remote":"127.0.0.1:39930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-23T18:35:52.674185Z","caller":"traceutil/trace.go:171","msg":"trace[2126362234] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"1.691431999s","start":"2026-07-23T18:35:50.982717Z","end":"2026-07-23T18:35:52.674149Z","steps":["trace[2126362234] 'agreement among raft nodes before linearized reading' (duration: 1.691150076s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:52.673958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268556739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.40\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-23T18:35:52.674232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:50.982672Z","time spent":"1.691548451s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-23T18:35:52.674241Z","caller":"traceutil/trace.go:171","msg":"trace[179941280] range","detail":"{range_begin:/registry/masterleases/199.19.213.40; range_end:; response_count:1; response_revision:608; }","duration":"1.268890754s","start":"2026-07-23T18:35:51.405338Z","end":"2026-07-23T18:35:52.674228Z","steps":["trace[179941280] 'agreement among raft nodes before linearized reading' (duration: 1.26847556s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:52.674198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718426647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T18:35:52.674321Z","caller":"traceutil/trace.go:171","msg":"trace[613759892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"1.71861217s","start":"2026-07-23T18:35:50.955677Z","end":"2026-07-23T18:35:52.67429Z","steps":["trace[613759892] 'agreement among raft nodes before linearized reading' (duration: 1.718132855s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:52.674267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:51.40532Z","time spent":"1.268939134s","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.40\" "} {"level":"warn","ts":"2026-07-23T18:35:52.674386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:50.955651Z","time spent":"1.718724542s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T18:35:53.556517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.058046ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-07-23T18:35:53.556587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:52.67559Z","time spent":"880.993888ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-23T18:35:54.685509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000165465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-23T18:35:54.685627Z","caller":"traceutil/trace.go:171","msg":"trace[1729700270] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000331998s","start":"2026-07-23T18:35:52.685276Z","end":"2026-07-23T18:35:54.685608Z","steps":["trace[1729700270] 'agreement among raft nodes before linearized reading' (duration: 2.000194336s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:54.68568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:52.685264Z","time spent":"2.000405118s","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T18:35:55.099231Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.026602496s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T18:35:55.128607Z","caller":"traceutil/trace.go:171","msg":"trace[266713131] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"2.450358421s","start":"2026-07-23T18:35:52.678223Z","end":"2026-07-23T18:35:55.128581Z","steps":["trace[266713131] 'process raft request' (duration: 2.421201433s)","trace[266713131] 'compare' (duration: 28.968516ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:55.128999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:52.678203Z","time spent":"2.450460862s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:35:55.129235Z","caller":"traceutil/trace.go:171","msg":"trace[643630043] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"2.45023615s","start":"2026-07-23T18:35:52.678982Z","end":"2026-07-23T18:35:55.129218Z","steps":["trace[643630043] 'process raft request' (duration: 2.449550453s)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:55.129334Z","caller":"traceutil/trace.go:171","msg":"trace[1463866570] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"2.449913627s","start":"2026-07-23T18:35:52.679398Z","end":"2026-07-23T18:35:55.129312Z","steps":["trace[1463866570] 'process raft request' (duration: 2.449683324s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:55.129475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:52.679393Z","time spent":"2.450008698s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:35:55.129585Z","caller":"traceutil/trace.go:171","msg":"trace[877885514] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"2.449316169s","start":"2026-07-23T18:35:52.680251Z","end":"2026-07-23T18:35:55.129567Z","steps":["trace[877885514] 'process raft request' (duration: 2.449017376s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:55.129479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:52.678975Z","time spent":"2.450337821s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T18:35:55.130953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:52.680225Z","time spent":"2.449484431s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:35:55.129696Z","caller":"traceutil/trace.go:171","msg":"trace[1677947410] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"2.406497065s","start":"2026-07-23T18:35:52.723189Z","end":"2026-07-23T18:35:55.129686Z","steps":["trace[1677947410] 'process raft request' (duration: 2.406308034s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:55.13163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:52.723174Z","time spent":"2.408397056s","remote":"127.0.0.1:40390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T18:35:55.131796Z","caller":"traceutil/trace.go:171","msg":"trace[1652149694] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"2.4446571s","start":"2026-07-23T18:35:52.687084Z","end":"2026-07-23T18:35:55.131741Z","steps":["trace[1652149694] 'process raft request' (duration: 2.442333966s)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:55.130187Z","caller":"traceutil/trace.go:171","msg":"trace[1814948588] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"2.401943876s","start":"2026-07-23T18:35:52.728224Z","end":"2026-07-23T18:35:55.130168Z","steps":["trace[1814948588] 'process raft request' (duration: 2.4013083s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:55.131994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:52.687064Z","time spent":"2.444811202s","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T18:35:55.132067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:52.728217Z","time spent":"2.403798426s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:35:55.129592Z","caller":"traceutil/trace.go:171","msg":"trace[1609560675] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:629; }","duration":"2.444248886s","start":"2026-07-23T18:35:52.68532Z","end":"2026-07-23T18:35:55.129569Z","steps":["trace[1609560675] 'read index received' (duration: 387.28218ms)","trace[1609560675] 'applied index is now lower than readState.Index' (duration: 2.056965806s)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:56.686678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000113383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-23T18:35:56.687024Z","caller":"traceutil/trace.go:171","msg":"trace[2064535996] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000250824s","start":"2026-07-23T18:35:54.68649Z","end":"2026-07-23T18:35:56.686741Z","steps":["trace[2064535996] 'agreement among raft nodes before linearized reading' (duration: 2.000152903s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:56.687098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:54.686459Z","time spent":"2.000622419s","remote":"127.0.0.1:39930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/23 18:35:56 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-23T18:35:58.4729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.174122501s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:35:58.473396Z","caller":"traceutil/trace.go:171","msg":"trace[1095303660] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"4.915918703s","start":"2026-07-23T18:35:53.557436Z","end":"2026-07-23T18:35:58.473355Z","steps":["trace[1095303660] 'process raft request' (duration: 1.741267095s)","trace[1095303660] 'compare' (duration: 3.173359894s)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:58.473523Z","caller":"traceutil/trace.go:171","msg":"trace[1738063274] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"3.871271343s","start":"2026-07-23T18:35:54.602226Z","end":"2026-07-23T18:35:58.473498Z","steps":["trace[1738063274] 'process raft request' (duration: 3.870785487s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:58.473579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:53.55741Z","time spent":"4.916063425s","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T18:35:58.473614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:54.602125Z","time spent":"3.871447255s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:35:58.473679Z","caller":"traceutil/trace.go:171","msg":"trace[245869270] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"3.34076814s","start":"2026-07-23T18:35:55.132886Z","end":"2026-07-23T18:35:58.473654Z","steps":["trace[245869270] 'read index received' (duration: 165.733638ms)","trace[245869270] 'applied index is now lower than readState.Index' (duration: 3.175031872s)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:35:58.629545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.413909ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-23T18:35:58.630134Z","caller":"traceutil/trace.go:171","msg":"trace[350216429] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:639; }","duration":"156.359169ms","start":"2026-07-23T18:35:58.473737Z","end":"2026-07-23T18:35:58.630096Z","steps":["trace[350216429] 'read index received' (duration: 39.911µs)","trace[350216429] 'applied index is now lower than readState.Index' (duration: 156.315818ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:58.630174Z","caller":"traceutil/trace.go:171","msg":"trace[477954445] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.940086084s","start":"2026-07-23T18:35:56.690063Z","end":"2026-07-23T18:35:58.630149Z","steps":["trace[477954445] 'process raft request' (duration: 1.939629159s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:58.630317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:56.689988Z","time spent":"1.940242236s","remote":"127.0.0.1:39986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T18:35:58.637065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.420882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-07-23T18:35:58.637161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.943055235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:35:58.637178Z","caller":"traceutil/trace.go:171","msg":"trace[1669538222] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:618; }","duration":"161.582734ms","start":"2026-07-23T18:35:58.475578Z","end":"2026-07-23T18:35:58.637161Z","steps":["trace[1669538222] 'agreement among raft nodes before linearized reading' (duration: 154.708602ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:58.637213Z","caller":"traceutil/trace.go:171","msg":"trace[948613227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"1.943127106s","start":"2026-07-23T18:35:56.694069Z","end":"2026-07-23T18:35:58.637196Z","steps":["trace[948613227] 'agreement among raft nodes before linearized reading' (duration: 1.936079112s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:58.637259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:56.694061Z","time spent":"1.943190296s","remote":"127.0.0.1:39926","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-07-23T18:35:58.637204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.493851093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qt85x\" ","response":"range_response_count:1 size:17145"} {"level":"info","ts":"2026-07-23T18:35:58.637352Z","caller":"traceutil/trace.go:171","msg":"trace[785607791] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qt85x; range_end:; response_count:1; response_revision:618; }","duration":"3.494020994s","start":"2026-07-23T18:35:55.143314Z","end":"2026-07-23T18:35:58.637335Z","steps":["trace[785607791] 'agreement among raft nodes before linearized reading' (duration: 3.486836239s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:58.637368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.494962973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T18:35:58.637397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:55.143308Z","time spent":"3.494076085s","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17167,"request content":"key:\"/registry/pods/kube-system/cilium-qt85x\" "} {"level":"info","ts":"2026-07-23T18:35:58.63742Z","caller":"traceutil/trace.go:171","msg":"trace[1005151554] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:618; }","duration":"3.495044695s","start":"2026-07-23T18:35:55.142353Z","end":"2026-07-23T18:35:58.637398Z","steps":["trace[1005151554] 'agreement among raft nodes before linearized reading' (duration: 3.487911371s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:58.637428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.493496368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-23T18:35:58.637453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:55.142337Z","time spent":"3.495108146s","remote":"127.0.0.1:40114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-07-23T18:35:58.63748Z","caller":"traceutil/trace.go:171","msg":"trace[2136481810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"1.493552569s","start":"2026-07-23T18:35:57.143912Z","end":"2026-07-23T18:35:58.637465Z","steps":["trace[2136481810] 'agreement among raft nodes before linearized reading' (duration: 1.486206002s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:58.637508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495535649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-23T18:35:58.637517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:57.14387Z","time spent":"1.493639859s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T18:35:58.637519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.494483038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-23T18:35:58.637566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.494504268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T18:35:58.637578Z","caller":"traceutil/trace.go:171","msg":"trace[1480985299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.494596749s","start":"2026-07-23T18:35:57.142964Z","end":"2026-07-23T18:35:58.63756Z","steps":["trace[1480985299] 'agreement among raft nodes before linearized reading' (duration: 1.487139641s)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:58.637593Z","caller":"traceutil/trace.go:171","msg":"trace[1175638651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.494566289s","start":"2026-07-23T18:35:57.143019Z","end":"2026-07-23T18:35:58.637585Z","steps":["trace[1175638651] 'agreement among raft nodes before linearized reading' (duration: 1.487115021s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:58.637619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:57.142998Z","time spent":"1.494614439s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T18:35:58.637619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:57.14294Z","time spent":"1.494669239s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-23T18:35:58.637537Z","caller":"traceutil/trace.go:171","msg":"trace[449305249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"1.49560172s","start":"2026-07-23T18:35:57.141926Z","end":"2026-07-23T18:35:58.637528Z","steps":["trace[449305249] 'agreement among raft nodes before linearized reading' (duration: 1.488234524s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:58.637671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:35:57.141904Z","time spent":"1.495760422s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-23T18:35:59.143988Z","caller":"traceutil/trace.go:171","msg":"trace[1517122716] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"103.390012ms","start":"2026-07-23T18:35:59.040558Z","end":"2026-07-23T18:35:59.143948Z","steps":["trace[1517122716] 'process raft request' (duration: 102.687234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:35:59.48023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.757954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qt85x\" ","response":"range_response_count:1 size:17145"} {"level":"info","ts":"2026-07-23T18:35:59.480292Z","caller":"traceutil/trace.go:171","msg":"trace[1141051075] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qt85x; range_end:; response_count:1; response_revision:629; }","duration":"101.899526ms","start":"2026-07-23T18:35:59.378377Z","end":"2026-07-23T18:35:59.480277Z","steps":["trace[1141051075] 'range keys from in-memory index tree' (duration: 101.635863ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:35:59.480318Z","caller":"traceutil/trace.go:171","msg":"trace[931643230] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"100.678363ms","start":"2026-07-23T18:35:59.379607Z","end":"2026-07-23T18:35:59.480285Z","steps":["trace[931643230] 'process raft request' (duration: 69.195944ms)","trace[931643230] 'compare' (duration: 31.204866ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:35:59.788829Z","caller":"traceutil/trace.go:171","msg":"trace[162110741] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"293.309387ms","start":"2026-07-23T18:35:59.49549Z","end":"2026-07-23T18:35:59.788799Z","steps":["trace[162110741] 'process raft request' (duration: 292.784022ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:01.094325Z","caller":"traceutil/trace.go:171","msg":"trace[982259844] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"432.740094ms","start":"2026-07-23T18:36:00.661571Z","end":"2026-07-23T18:36:01.094311Z","steps":["trace[982259844] 'process raft request' (duration: 426.61656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:01.094443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:00.66155Z","time spent":"432.824354ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T18:36:01.094582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.197534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-23T18:36:01.094646Z","caller":"traceutil/trace.go:171","msg":"trace[1302858648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"424.301086ms","start":"2026-07-23T18:36:00.670328Z","end":"2026-07-23T18:36:01.094629Z","steps":["trace[1302858648] 'agreement among raft nodes before linearized reading' (duration: 424.141534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:01.094676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:00.670312Z","time spent":"424.356866ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-23T18:36:01.094247Z","caller":"traceutil/trace.go:171","msg":"trace[1884200295] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"423.857011ms","start":"2026-07-23T18:36:00.670365Z","end":"2026-07-23T18:36:01.094222Z","steps":["trace[1884200295] 'read index received' (duration: 417.861228ms)","trace[1884200295] 'applied index is now lower than readState.Index' (duration: 5.994763ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:36:01.094833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.293623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:36:01.094861Z","caller":"traceutil/trace.go:171","msg":"trace[965550511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"396.354874ms","start":"2026-07-23T18:36:00.698498Z","end":"2026-07-23T18:36:01.094853Z","steps":["trace[965550511] 'agreement among raft nodes before linearized reading' (duration: 396.305043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:01.094878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:00.698483Z","time spent":"396.390754ms","remote":"127.0.0.1:39926","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-07-23T18:36:01.094935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.76087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-23T18:36:01.094987Z","caller":"traceutil/trace.go:171","msg":"trace[529201564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"423.833681ms","start":"2026-07-23T18:36:00.67114Z","end":"2026-07-23T18:36:01.094974Z","steps":["trace[529201564] 'agreement among raft nodes before linearized reading' (duration: 423.658919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:01.09501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.82719ms","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-07-23T18:36:01.09502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:00.671135Z","time spent":"423.874942ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-23T18:36:01.095031Z","caller":"traceutil/trace.go:171","msg":"trace[2076181214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"423.856821ms","start":"2026-07-23T18:36:00.671168Z","end":"2026-07-23T18:36:01.095024Z","steps":["trace[2076181214] 'agreement among raft nodes before linearized reading' (duration: 423.7956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:01.095062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:00.671164Z","time spent":"423.880731ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T18:36:03.900831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.762535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-07-23T18:36:03.900935Z","caller":"traceutil/trace.go:171","msg":"trace[882397829] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:692; }","duration":"180.895747ms","start":"2026-07-23T18:36:03.720009Z","end":"2026-07-23T18:36:03.900904Z","steps":["trace[882397829] 'range keys from in-memory index tree' (duration: 180.587493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:03.901007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.148777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:36:03.90109Z","caller":"traceutil/trace.go:171","msg":"trace[1296572110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"201.267188ms","start":"2026-07-23T18:36:03.699802Z","end":"2026-07-23T18:36:03.901069Z","steps":["trace[1296572110] 'range keys from in-memory index tree' (duration: 201.067776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:03.901005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.703311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-23T18:36:03.901192Z","caller":"traceutil/trace.go:171","msg":"trace[1204151009] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:692; }","duration":"170.884193ms","start":"2026-07-23T18:36:03.730267Z","end":"2026-07-23T18:36:03.901151Z","steps":["trace[1204151009] 'range keys from in-memory index tree' (duration: 170.498539ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:04.581624Z","caller":"traceutil/trace.go:171","msg":"trace[1124638576] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"137.546124ms","start":"2026-07-23T18:36:04.444046Z","end":"2026-07-23T18:36:04.581592Z","steps":["trace[1124638576] 'process raft request' (duration: 109.552164ms)","trace[1124638576] 'compare' (duration: 27.616766ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:36:05.193563Z","caller":"traceutil/trace.go:171","msg":"trace[1619913703] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"131.147474ms","start":"2026-07-23T18:36:05.062386Z","end":"2026-07-23T18:36:05.193534Z","steps":["trace[1619913703] 'process raft request' (duration: 130.909792ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:05.457609Z","caller":"traceutil/trace.go:171","msg":"trace[587637431] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"109.275309ms","start":"2026-07-23T18:36:05.348316Z","end":"2026-07-23T18:36:05.457591Z","steps":["trace[587637431] 'process raft request' (duration: 108.820195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:05.64787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.600099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-07-23T18:36:05.647937Z","caller":"traceutil/trace.go:171","msg":"trace[1253438221] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:707; }","duration":"127.71381ms","start":"2026-07-23T18:36:05.520207Z","end":"2026-07-23T18:36:05.647921Z","steps":["trace[1253438221] 'range keys from in-memory index tree' (duration: 127.390187ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:05.695065Z","caller":"traceutil/trace.go:171","msg":"trace[1000821518] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"158.481958ms","start":"2026-07-23T18:36:05.536557Z","end":"2026-07-23T18:36:05.695039Z","steps":["trace[1000821518] 'process raft request' (duration: 157.907821ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:05.758653Z","caller":"traceutil/trace.go:171","msg":"trace[1639349570] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"217.174913ms","start":"2026-07-23T18:36:05.541444Z","end":"2026-07-23T18:36:05.758619Z","steps":["trace[1639349570] 'process raft request' (duration: 211.284763ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:06.017499Z","caller":"traceutil/trace.go:171","msg":"trace[1394917306] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"183.557977ms","start":"2026-07-23T18:36:05.833914Z","end":"2026-07-23T18:36:06.017472Z","steps":["trace[1394917306] 'process raft request' (duration: 170.710924ms)","trace[1394917306] 'compare' (duration: 12.743702ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:36:06.066055Z","caller":"traceutil/trace.go:171","msg":"trace[618095298] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"208.079869ms","start":"2026-07-23T18:36:05.857944Z","end":"2026-07-23T18:36:06.066024Z","steps":["trace[618095298] 'process raft request' (duration: 207.872527ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:06.066217Z","caller":"traceutil/trace.go:171","msg":"trace[1366367773] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:740; }","duration":"208.025059ms","start":"2026-07-23T18:36:05.858168Z","end":"2026-07-23T18:36:06.066193Z","steps":["trace[1366367773] 'read index received' (duration: 146.526974ms)","trace[1366367773] 'applied index is now lower than readState.Index' (duration: 61.496495ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:36:06.066263Z","caller":"traceutil/trace.go:171","msg":"trace[2024006421] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"182.11439ms","start":"2026-07-23T18:36:05.884123Z","end":"2026-07-23T18:36:06.066238Z","steps":["trace[2024006421] 'process raft request' (duration: 181.899368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:06.066476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.292262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-nppnk\" ","response":"range_response_count:1 size:3835"} {"level":"info","ts":"2026-07-23T18:36:06.066525Z","caller":"traceutil/trace.go:171","msg":"trace[1285859752] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-nppnk; range_end:; response_count:1; response_revision:718; }","duration":"208.371702ms","start":"2026-07-23T18:36:05.858138Z","end":"2026-07-23T18:36:06.06651Z","steps":["trace[1285859752] 'agreement among raft nodes before linearized reading' (duration: 208.2086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:06.066645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.24482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-07-23T18:36:06.066727Z","caller":"traceutil/trace.go:171","msg":"trace[453520765] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:718; }","duration":"147.369471ms","start":"2026-07-23T18:36:05.91932Z","end":"2026-07-23T18:36:06.066689Z","steps":["trace[453520765] 'agreement among raft nodes before linearized reading' (duration: 147.119468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:06.066986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.342173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-23T18:36:06.067097Z","caller":"traceutil/trace.go:171","msg":"trace[389120084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:718; }","duration":"151.435784ms","start":"2026-07-23T18:36:05.915614Z","end":"2026-07-23T18:36:06.06705Z","steps":["trace[389120084] 'agreement among raft nodes before linearized reading' (duration: 150.783417ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:06.222463Z","caller":"traceutil/trace.go:171","msg":"trace[162099350] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"103.581609ms","start":"2026-07-23T18:36:06.118858Z","end":"2026-07-23T18:36:06.22244Z","steps":["trace[162099350] 'read index received' (duration: 103.487728ms)","trace[162099350] 'applied index is now lower than readState.Index' (duration: 92.911µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:36:06.222625Z","caller":"traceutil/trace.go:171","msg":"trace[1964576678] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"119.227431ms","start":"2026-07-23T18:36:06.103386Z","end":"2026-07-23T18:36:06.222614Z","steps":["trace[1964576678] 'process raft request' (duration: 118.925548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:06.223065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.190305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-07-23T18:36:06.223099Z","caller":"traceutil/trace.go:171","msg":"trace[2048072933] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:722; }","duration":"104.252135ms","start":"2026-07-23T18:36:06.118835Z","end":"2026-07-23T18:36:06.223087Z","steps":["trace[2048072933] 'agreement among raft nodes before linearized reading' (duration: 104.088124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:06.252925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.782094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:833"} {"level":"info","ts":"2026-07-23T18:36:06.253006Z","caller":"traceutil/trace.go:171","msg":"trace[127126693] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:723; }","duration":"113.908065ms","start":"2026-07-23T18:36:06.139078Z","end":"2026-07-23T18:36:06.252986Z","steps":["trace[127126693] 'agreement among raft nodes before linearized reading' (duration: 113.696173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:06.253012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.66216ms","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-07-23T18:36:06.253109Z","caller":"traceutil/trace.go:171","msg":"trace[369194186] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:723; }","duration":"106.799081ms","start":"2026-07-23T18:36:06.146287Z","end":"2026-07-23T18:36:06.253086Z","steps":["trace[369194186] 'agreement among raft nodes before linearized reading' (duration: 106.589409ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:11.585174Z","caller":"traceutil/trace.go:171","msg":"trace[1446441083] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"109.796692ms","start":"2026-07-23T18:36:11.475351Z","end":"2026-07-23T18:36:11.585147Z","steps":["trace[1446441083] 'process raft request' (duration: 91.347134ms)","trace[1446441083] 'compare' (duration: 18.254666ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:36:11.585363Z","caller":"traceutil/trace.go:171","msg":"trace[2067886760] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"102.134334ms","start":"2026-07-23T18:36:11.483202Z","end":"2026-07-23T18:36:11.585336Z","steps":["trace[2067886760] 'process raft request' (duration: 101.889581ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:11.793538Z","caller":"traceutil/trace.go:171","msg":"trace[1974536437] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"194.78328ms","start":"2026-07-23T18:36:11.598714Z","end":"2026-07-23T18:36:11.793497Z","steps":["trace[1974536437] 'process raft request' (duration: 124.986658ms)","trace[1974536437] 'compare' (duration: 69.623471ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:36:11.798156Z","caller":"traceutil/trace.go:171","msg":"trace[1198483528] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:783; }","duration":"100.175214ms","start":"2026-07-23T18:36:11.697963Z","end":"2026-07-23T18:36:11.798138Z","steps":["trace[1198483528] 'read index received' (duration: 25.817535ms)","trace[1198483528] 'applied index is now lower than readState.Index' (duration: 74.356779ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:36:11.79824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.274485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:36:11.798218Z","caller":"traceutil/trace.go:171","msg":"trace[1878053152] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"164.555003ms","start":"2026-07-23T18:36:11.633627Z","end":"2026-07-23T18:36:11.798182Z","steps":["trace[1878053152] 'process raft request' (duration: 164.402511ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:11.798262Z","caller":"traceutil/trace.go:171","msg":"trace[1652994071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"100.344716ms","start":"2026-07-23T18:36:11.697911Z","end":"2026-07-23T18:36:11.798256Z","steps":["trace[1652994071] 'agreement among raft nodes before linearized reading' (duration: 100.297366ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:12.727099Z","caller":"traceutil/trace.go:171","msg":"trace[809320749] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"256.798351ms","start":"2026-07-23T18:36:12.470272Z","end":"2026-07-23T18:36:12.727071Z","steps":["trace[809320749] 'process raft request' (duration: 256.462877ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:13.067409Z","caller":"traceutil/trace.go:171","msg":"trace[1268727818] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"334.722155ms","start":"2026-07-23T18:36:12.732551Z","end":"2026-07-23T18:36:13.067273Z","steps":["trace[1268727818] 'process raft request' (duration: 334.548093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:13.067591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:12.732525Z","time spent":"334.973487ms","remote":"127.0.0.1:40162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1290,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:36:13.094856Z","caller":"traceutil/trace.go:171","msg":"trace[1645064681] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"361.919252ms","start":"2026-07-23T18:36:12.73292Z","end":"2026-07-23T18:36:13.094839Z","steps":["trace[1645064681] 'process raft request' (duration: 361.816291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:13.094957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:12.732889Z","time spent":"362.017972ms","remote":"127.0.0.1:40044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":781,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:36:13.555229Z","caller":"traceutil/trace.go:171","msg":"trace[88629529] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"160.171241ms","start":"2026-07-23T18:36:13.39503Z","end":"2026-07-23T18:36:13.555201Z","steps":["trace[88629529] 'process raft request' (duration: 160.06622ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:13.555237Z","caller":"traceutil/trace.go:171","msg":"trace[1060899472] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"160.716437ms","start":"2026-07-23T18:36:13.394491Z","end":"2026-07-23T18:36:13.555207Z","steps":["trace[1060899472] 'process raft request' (duration: 152.651485ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:14.321158Z","caller":"traceutil/trace.go:171","msg":"trace[1062137798] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"110.547545ms","start":"2026-07-23T18:36:14.210586Z","end":"2026-07-23T18:36:14.321134Z","steps":["trace[1062137798] 'process raft request' (duration: 110.387763ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:16.452716Z","caller":"traceutil/trace.go:171","msg":"trace[536908406] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"122.616613ms","start":"2026-07-23T18:36:16.330081Z","end":"2026-07-23T18:36:16.452698Z","steps":["trace[536908406] 'process raft request' (duration: 122.33455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:16.686788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.376343ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:36:16.686974Z","caller":"traceutil/trace.go:171","msg":"trace[916527929] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"206.644324ms","start":"2026-07-23T18:36:16.480308Z","end":"2026-07-23T18:36:16.686952Z","steps":["trace[916527929] 'process raft request' (duration: 67.006439ms)","trace[916527929] 'compare' (duration: 139.193221ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:36:16.820458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.300708ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-23T18:36:16.878978Z","caller":"traceutil/trace.go:171","msg":"trace[878094777] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"186.151308ms","start":"2026-07-23T18:36:16.692791Z","end":"2026-07-23T18:36:16.878943Z","steps":["trace[878094777] 'process raft request' (duration: 185.894796ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:16.879236Z","caller":"traceutil/trace.go:171","msg":"trace[1723178338] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"185.627302ms","start":"2026-07-23T18:36:16.693595Z","end":"2026-07-23T18:36:16.879223Z","steps":["trace[1723178338] 'process raft request' (duration: 185.258578ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:16.879372Z","caller":"traceutil/trace.go:171","msg":"trace[96980872] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"184.363259ms","start":"2026-07-23T18:36:16.694985Z","end":"2026-07-23T18:36:16.879348Z","steps":["trace[96980872] 'process raft request' (duration: 184.111227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:17.694705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.622838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:36:17.694994Z","caller":"traceutil/trace.go:171","msg":"trace[352858034] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"216.058777ms","start":"2026-07-23T18:36:17.478751Z","end":"2026-07-23T18:36:17.694809Z","steps":["trace[352858034] 'process raft request' (duration: 39.162626ms)","trace[352858034] 'compare' (duration: 176.482547ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:36:19.199282Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10211806122023783858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T18:36:19.593535Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.132300082s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T18:36:19.593984Z","caller":"traceutil/trace.go:171","msg":"trace[1965950563] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"1.132870327s","start":"2026-07-23T18:36:18.46109Z","end":"2026-07-23T18:36:19.59396Z","steps":["trace[1965950563] 'process raft request' (duration: 1.132693845s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:19.594364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:18.461067Z","time spent":"1.133016828s","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:36:19.600973Z","caller":"traceutil/trace.go:171","msg":"trace[182163218] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:812; }","duration":"902.099526ms","start":"2026-07-23T18:36:18.698854Z","end":"2026-07-23T18:36:19.600954Z","steps":["trace[182163218] 'read index received' (duration: 894.906092ms)","trace[182163218] 'applied index is now lower than readState.Index' (duration: 7.192764ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:36:19.601069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.196826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:36:19.601048Z","caller":"traceutil/trace.go:171","msg":"trace[1362508876] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"628.090648ms","start":"2026-07-23T18:36:18.972926Z","end":"2026-07-23T18:36:19.601017Z","steps":["trace[1362508876] 'process raft request' (duration: 627.913676ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:19.601141Z","caller":"traceutil/trace.go:171","msg":"trace[1815575459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"902.327008ms","start":"2026-07-23T18:36:18.698806Z","end":"2026-07-23T18:36:19.601133Z","steps":["trace[1815575459] 'agreement among raft nodes before linearized reading' (duration: 902.218927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:19.601274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:18.972904Z","time spent":"628.27653ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T18:36:19.601348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:18.698783Z","time spent":"902.507791ms","remote":"127.0.0.1:39926","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-07-23T18:36:19.601642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.538159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T18:36:19.601748Z","caller":"traceutil/trace.go:171","msg":"trace[105838491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"149.645081ms","start":"2026-07-23T18:36:19.452044Z","end":"2026-07-23T18:36:19.601689Z","steps":["trace[105838491] 'agreement among raft nodes before linearized reading' (duration: 149.247397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:19.748316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.490624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:36:19.748585Z","caller":"traceutil/trace.go:171","msg":"trace[2028288670] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"143.532508ms","start":"2026-07-23T18:36:19.60491Z","end":"2026-07-23T18:36:19.748443Z","steps":["trace[2028288670] 'process raft request' (duration: 36.775651ms)","trace[2028288670] 'compare' (duration: 106.324983ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:36:22.028063Z","caller":"traceutil/trace.go:171","msg":"trace[1347234089] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"132.096218ms","start":"2026-07-23T18:36:21.895943Z","end":"2026-07-23T18:36:22.02804Z","steps":["trace[1347234089] 'read index received' (duration: 37.576938ms)","trace[1347234089] 'applied index is now lower than readState.Index' (duration: 94.51834ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:36:22.028117Z","caller":"traceutil/trace.go:171","msg":"trace[2098041069] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"177.0577ms","start":"2026-07-23T18:36:21.851041Z","end":"2026-07-23T18:36:22.028098Z","steps":["trace[2098041069] 'process raft request' (duration: 82.502129ms)","trace[2098041069] 'compare' (duration: 94.358099ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:36:22.02828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.32249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T18:36:22.028367Z","caller":"traceutil/trace.go:171","msg":"trace[1409903449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"132.434641ms","start":"2026-07-23T18:36:21.8959Z","end":"2026-07-23T18:36:22.028334Z","steps":["trace[1409903449] 'agreement among raft nodes before linearized reading' (duration: 132.230169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:27.107855Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10211806122023783932,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T18:36:27.60863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10211806122023783932,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T18:36:28.109679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10211806122023783932,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T18:36:28.531959Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.999445476s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T18:36:28.532424Z","caller":"traceutil/trace.go:171","msg":"trace[1289112532] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"1.999969331s","start":"2026-07-23T18:36:26.53243Z","end":"2026-07-23T18:36:28.532399Z","steps":["trace[1289112532] 'process raft request' (duration: 1.999809599s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:28.532574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:26.532418Z","time spent":"2.000079642s","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:36:28.576512Z","caller":"traceutil/trace.go:171","msg":"trace[1032373574] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:844; }","duration":"1.969155992s","start":"2026-07-23T18:36:26.607326Z","end":"2026-07-23T18:36:28.576482Z","steps":["trace[1032373574] 'read index received' (duration: 1.924948042s)","trace[1032373574] 'applied index is now lower than readState.Index' (duration: 44.20616ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:36:28.576734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.877353566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T18:36:28.576723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.969365764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T18:36:28.576779Z","caller":"traceutil/trace.go:171","msg":"trace[325043160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:815; }","duration":"1.877457978s","start":"2026-07-23T18:36:26.69931Z","end":"2026-07-23T18:36:28.576768Z","steps":["trace[325043160] 'agreement among raft nodes before linearized reading' (duration: 1.877352507s)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:28.576804Z","caller":"traceutil/trace.go:171","msg":"trace[132593576] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:815; }","duration":"1.969502256s","start":"2026-07-23T18:36:26.607276Z","end":"2026-07-23T18:36:28.576779Z","steps":["trace[132593576] 'agreement among raft nodes before linearized reading' (duration: 1.969330854s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:28.576913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.250593ms","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-07-23T18:36:28.576997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.216446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T18:36:28.577001Z","caller":"traceutil/trace.go:171","msg":"trace[533861257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:815; }","duration":"468.376384ms","start":"2026-07-23T18:36:28.108601Z","end":"2026-07-23T18:36:28.576977Z","steps":["trace[533861257] 'agreement among raft nodes before linearized reading' (duration: 468.120281ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:28.577046Z","caller":"traceutil/trace.go:171","msg":"trace[1551550093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:815; }","duration":"668.300626ms","start":"2026-07-23T18:36:27.908732Z","end":"2026-07-23T18:36:28.577033Z","steps":["trace[1551550093] 'agreement among raft nodes before linearized reading' (duration: 668.126695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:28.577064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:28.108579Z","time spent":"468.471225ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T18:36:28.577081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:27.908707Z","time spent":"668.367137ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T18:36:28.577138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.059549ms","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-07-23T18:36:28.576847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:26.607243Z","time spent":"1.969596227s","remote":"127.0.0.1:40404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-07-23T18:36:28.577129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.350504ms","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-07-23T18:36:28.57715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.099124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T18:36:28.577354Z","caller":"traceutil/trace.go:171","msg":"trace[329815069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:815; }","duration":"674.295206ms","start":"2026-07-23T18:36:27.903002Z","end":"2026-07-23T18:36:28.577297Z","steps":["trace[329815069] 'agreement among raft nodes before linearized reading' (duration: 673.862412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:28.577509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:27.902979Z","time spent":"674.512298ms","remote":"127.0.0.1:40150","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-07-23T18:36:28.577237Z","caller":"traceutil/trace.go:171","msg":"trace[548046130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:815; }","duration":"468.493995ms","start":"2026-07-23T18:36:28.108715Z","end":"2026-07-23T18:36:28.577209Z","steps":["trace[548046130] 'agreement among raft nodes before linearized reading' (duration: 468.157422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:28.578844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:28.108709Z","time spent":"470.107131ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-23T18:36:28.57718Z","caller":"traceutil/trace.go:171","msg":"trace[1444700064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:815; }","duration":"817.151601ms","start":"2026-07-23T18:36:27.760019Z","end":"2026-07-23T18:36:28.57717Z","steps":["trace[1444700064] 'agreement among raft nodes before linearized reading' (duration: 816.816988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:28.578976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:27.759989Z","time spent":"818.976958ms","remote":"127.0.0.1:40150","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-07-23T18:36:28.576819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:36:26.69928Z","time spent":"1.877531068s","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-23T18:36:44.823864Z","caller":"traceutil/trace.go:171","msg":"trace[2084908917] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"119.690118ms","start":"2026-07-23T18:36:44.704149Z","end":"2026-07-23T18:36:44.823839Z","steps":["trace[2084908917] 'process raft request' (duration: 119.572857ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:44.825485Z","caller":"traceutil/trace.go:171","msg":"trace[840700315] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"115.428907ms","start":"2026-07-23T18:36:44.710042Z","end":"2026-07-23T18:36:44.825471Z","steps":["trace[840700315] 'read index received' (duration: 114.356937ms)","trace[840700315] 'applied index is now lower than readState.Index' (duration: 1.07105ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:36:44.825682Z","caller":"traceutil/trace.go:171","msg":"trace[2068961680] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"118.545007ms","start":"2026-07-23T18:36:44.707126Z","end":"2026-07-23T18:36:44.825671Z","steps":["trace[2068961680] 'process raft request' (duration: 118.269515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:44.825791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.73612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T18:36:44.825823Z","caller":"traceutil/trace.go:171","msg":"trace[284708873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:996; }","duration":"115.797701ms","start":"2026-07-23T18:36:44.710016Z","end":"2026-07-23T18:36:44.825813Z","steps":["trace[284708873] 'agreement among raft nodes before linearized reading' (duration: 115.68988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:44.825909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.043764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T18:36:44.825983Z","caller":"traceutil/trace.go:171","msg":"trace[1557602775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"111.141465ms","start":"2026-07-23T18:36:44.714822Z","end":"2026-07-23T18:36:44.825964Z","steps":["trace[1557602775] 'agreement among raft nodes before linearized reading' (duration: 110.917873ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:46.517082Z","caller":"traceutil/trace.go:171","msg":"trace[667239334] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"279.611786ms","start":"2026-07-23T18:36:46.237444Z","end":"2026-07-23T18:36:46.517055Z","steps":["trace[667239334] 'process raft request' (duration: 279.465704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:47.08381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.056259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:36:47.084352Z","caller":"traceutil/trace.go:171","msg":"trace[331946821] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"181.019052ms","start":"2026-07-23T18:36:46.903304Z","end":"2026-07-23T18:36:47.084323Z","steps":["trace[331946821] 'process raft request' (duration: 21.361847ms)","trace[331946821] 'compare' (duration: 158.921888ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:36:47.096688Z","caller":"traceutil/trace.go:171","msg":"trace[1816545633] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"192.848439ms","start":"2026-07-23T18:36:46.903822Z","end":"2026-07-23T18:36:47.09667Z","steps":["trace[1816545633] 'process raft request' (duration: 192.725548ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:36:47.0968Z","caller":"traceutil/trace.go:171","msg":"trace[167130818] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"192.709437ms","start":"2026-07-23T18:36:46.904078Z","end":"2026-07-23T18:36:47.096788Z","steps":["trace[167130818] 'process raft request' (duration: 192.550186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:53.297397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.582072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:36:53.297594Z","caller":"traceutil/trace.go:171","msg":"trace[310893857] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"123.916168ms","start":"2026-07-23T18:36:53.173656Z","end":"2026-07-23T18:36:53.297572Z","steps":["trace[310893857] 'read index received' (duration: 30.17µs)","trace[310893857] 'applied index is now lower than readState.Index' (duration: 123.884868ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:36:53.297599Z","caller":"traceutil/trace.go:171","msg":"trace[1795628778] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"141.66103ms","start":"2026-07-23T18:36:53.155913Z","end":"2026-07-23T18:36:53.297574Z","steps":["trace[1795628778] 'compare' (duration: 136.466531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:36:53.297744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.092251ms","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-07-23T18:36:53.297841Z","caller":"traceutil/trace.go:171","msg":"trace[1315733814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1026; }","duration":"124.224152ms","start":"2026-07-23T18:36:53.17361Z","end":"2026-07-23T18:36:53.297834Z","steps":["trace[1315733814] 'agreement among raft nodes before linearized reading' (duration: 124.0313ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:37:09.469883Z","caller":"traceutil/trace.go:171","msg":"trace[762507752] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"221.835077ms","start":"2026-07-23T18:37:09.248029Z","end":"2026-07-23T18:37:09.469864Z","steps":["trace[762507752] 'read index received' (duration: 216.014237ms)","trace[762507752] 'applied index is now lower than readState.Index' (duration: 5.82003ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:37:09.469958Z","caller":"traceutil/trace.go:171","msg":"trace[1339009250] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"229.014678ms","start":"2026-07-23T18:37:09.240901Z","end":"2026-07-23T18:37:09.469916Z","steps":["trace[1339009250] 'process raft request' (duration: 223.219259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:37:09.470093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.048435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-23T18:37:09.470146Z","caller":"traceutil/trace.go:171","msg":"trace[651183329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1079; }","duration":"222.114467ms","start":"2026-07-23T18:37:09.248016Z","end":"2026-07-23T18:37:09.470131Z","steps":["trace[651183329] 'agreement among raft nodes before linearized reading' (duration: 221.949081ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:37:09.980465Z","caller":"traceutil/trace.go:171","msg":"trace[981716017] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"506.159609ms","start":"2026-07-23T18:37:09.47427Z","end":"2026-07-23T18:37:09.98043Z","steps":["trace[981716017] 'process raft request' (duration: 500.213043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:37:09.980728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:37:09.474087Z","time spent":"506.520843ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:37:10.020813Z","caller":"traceutil/trace.go:171","msg":"trace[256695294] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1119; }","duration":"523.305944ms","start":"2026-07-23T18:37:09.497484Z","end":"2026-07-23T18:37:10.02079Z","steps":["trace[256695294] 'read index received' (duration: 476.990515ms)","trace[256695294] 'applied index is now lower than readState.Index' (duration: 46.314199ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:37:10.021195Z","caller":"traceutil/trace.go:171","msg":"trace[1827511563] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"546.581875ms","start":"2026-07-23T18:37:09.474595Z","end":"2026-07-23T18:37:10.021177Z","steps":["trace[1827511563] 'process raft request' (duration: 546.038254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:37:10.021523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:37:09.474586Z","time spent":"546.685418ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T18:37:10.021884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.749445ms","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-07-23T18:37:10.02299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.497797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T18:37:10.021934Z","caller":"traceutil/trace.go:171","msg":"trace[749482174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1082; }","duration":"498.828508ms","start":"2026-07-23T18:37:09.523093Z","end":"2026-07-23T18:37:10.021921Z","steps":["trace[749482174] 'agreement among raft nodes before linearized reading' (duration: 498.677532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:37:10.023265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:37:09.523078Z","time spent":"500.139917ms","remote":"127.0.0.1:40150","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-07-23T18:37:10.023596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.76332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:37:10.023641Z","caller":"traceutil/trace.go:171","msg":"trace[1768340070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"249.862683ms","start":"2026-07-23T18:37:09.773766Z","end":"2026-07-23T18:37:10.023628Z","steps":["trace[1768340070] 'agreement among raft nodes before linearized reading' (duration: 249.795971ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:37:10.024122Z","caller":"traceutil/trace.go:171","msg":"trace[732485623] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"530.07277ms","start":"2026-07-23T18:37:09.494033Z","end":"2026-07-23T18:37:10.024106Z","steps":["trace[732485623] 'process raft request' (duration: 526.713304ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:37:10.024313Z","caller":"traceutil/trace.go:171","msg":"trace[1649201879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1082; }","duration":"525.56768ms","start":"2026-07-23T18:37:09.497457Z","end":"2026-07-23T18:37:10.023025Z","steps":["trace[1649201879] 'agreement among raft nodes before linearized reading' (duration: 524.948367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:37:10.024505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:37:09.497448Z","time spent":"526.92416ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T18:37:10.024469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:37:09.49398Z","time spent":"530.201185ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:37:10.169146Z","caller":"traceutil/trace.go:171","msg":"trace[2027408318] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"139.737082ms","start":"2026-07-23T18:37:10.029383Z","end":"2026-07-23T18:37:10.16912Z","steps":["trace[2027408318] 'process raft request' (duration: 139.051246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:37:10.442613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.779784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T18:37:10.44271Z","caller":"traceutil/trace.go:171","msg":"trace[539278168] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1084; }","duration":"135.934619ms","start":"2026-07-23T18:37:10.306755Z","end":"2026-07-23T18:37:10.44269Z","steps":["trace[539278168] 'count revisions from in-memory index tree' (duration: 135.697199ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:37:12.153905Z","caller":"traceutil/trace.go:171","msg":"trace[840356803] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1129; }","duration":"117.690805ms","start":"2026-07-23T18:37:12.036194Z","end":"2026-07-23T18:37:12.153884Z","steps":["trace[840356803] 'read index received' (duration: 70.803049ms)","trace[840356803] 'applied index is now lower than readState.Index' (duration: 46.886876ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:37:12.154099Z","caller":"traceutil/trace.go:171","msg":"trace[545220010] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"122.495574ms","start":"2026-07-23T18:37:12.031562Z","end":"2026-07-23T18:37:12.154058Z","steps":["trace[545220010] 'process raft request' (duration: 122.236674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:37:12.154378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.887783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T18:37:12.154432Z","caller":"traceutil/trace.go:171","msg":"trace[1697721765] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"118.270908ms","start":"2026-07-23T18:37:12.036146Z","end":"2026-07-23T18:37:12.154417Z","steps":["trace[1697721765] 'agreement among raft nodes before linearized reading' (duration: 117.825201ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:37:12.54371Z","caller":"traceutil/trace.go:171","msg":"trace[1511384216] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"368.797743ms","start":"2026-07-23T18:37:12.174881Z","end":"2026-07-23T18:37:12.543679Z","steps":["trace[1511384216] 'read index received' (duration: 303.445417ms)","trace[1511384216] 'applied index is now lower than readState.Index' (duration: 65.350926ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:37:12.543741Z","caller":"traceutil/trace.go:171","msg":"trace[634720045] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"385.209964ms","start":"2026-07-23T18:37:12.158486Z","end":"2026-07-23T18:37:12.543696Z","steps":["trace[634720045] 'process raft request' (duration: 319.914841ms)","trace[634720045] 'compare' (duration: 65.030823ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:37:12.543895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:37:12.158469Z","time spent":"385.342629ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T18:37:12.543952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.048852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-23T18:37:12.544023Z","caller":"traceutil/trace.go:171","msg":"trace[774855875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1091; }","duration":"369.125235ms","start":"2026-07-23T18:37:12.174879Z","end":"2026-07-23T18:37:12.544004Z","steps":["trace[774855875] 'agreement among raft nodes before linearized reading' (duration: 368.907927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:37:12.544063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:37:12.174856Z","time spent":"369.198548ms","remote":"127.0.0.1:40150","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":"info","ts":"2026-07-23T18:37:13.712129Z","caller":"traceutil/trace.go:171","msg":"trace[485889785] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"151.84173ms","start":"2026-07-23T18:37:13.560262Z","end":"2026-07-23T18:37:13.712104Z","steps":["trace[485889785] 'process raft request' (duration: 151.610561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:37:14.127055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.804912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T18:37:14.127124Z","caller":"traceutil/trace.go:171","msg":"trace[175739813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1093; }","duration":"131.927456ms","start":"2026-07-23T18:37:13.995178Z","end":"2026-07-23T18:37:14.127106Z","steps":["trace[175739813] 'range keys from in-memory index tree' (duration: 131.641906ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:37:14.36862Z","caller":"traceutil/trace.go:171","msg":"trace[712838658] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"237.579101ms","start":"2026-07-23T18:37:14.131012Z","end":"2026-07-23T18:37:14.368592Z","steps":["trace[712838658] 'process raft request' (duration: 235.861887ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:37:14.36898Z","caller":"traceutil/trace.go:171","msg":"trace[12357838] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1135; }","duration":"155.859378ms","start":"2026-07-23T18:37:14.213097Z","end":"2026-07-23T18:37:14.368957Z","steps":["trace[12357838] 'read index received' (duration: 153.724149ms)","trace[12357838] 'applied index is now lower than readState.Index' (duration: 2.133699ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:37:14.369094Z","caller":"traceutil/trace.go:171","msg":"trace[64432157] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"209.220246ms","start":"2026-07-23T18:37:14.159832Z","end":"2026-07-23T18:37:14.369052Z","steps":["trace[64432157] 'process raft request' (duration: 208.639905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:37:14.369164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.005353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T18:37:14.369224Z","caller":"traceutil/trace.go:171","msg":"trace[1486319460] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1096; }","duration":"156.147799ms","start":"2026-07-23T18:37:14.213061Z","end":"2026-07-23T18:37:14.369209Z","steps":["trace[1486319460] 'agreement among raft nodes before linearized reading' (duration: 155.983493ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:37:14.76145Z","caller":"traceutil/trace.go:171","msg":"trace[446828676] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"157.57309ms","start":"2026-07-23T18:37:14.603845Z","end":"2026-07-23T18:37:14.761418Z","steps":["trace[446828676] 'process raft request' (duration: 93.512868ms)","trace[446828676] 'compare' (duration: 63.900926ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:37:15.883258Z","caller":"traceutil/trace.go:171","msg":"trace[236802795] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1139; }","duration":"152.464614ms","start":"2026-07-23T18:37:15.730766Z","end":"2026-07-23T18:37:15.883231Z","steps":["trace[236802795] 'read index received' (duration: 152.167013ms)","trace[236802795] 'applied index is now lower than readState.Index' (duration: 296.31µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:37:15.883314Z","caller":"traceutil/trace.go:171","msg":"trace[1636022753] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"164.619819ms","start":"2026-07-23T18:37:15.718661Z","end":"2026-07-23T18:37:15.883281Z","steps":["trace[1636022753] 'process raft request' (duration: 164.404892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:37:15.883482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.692372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1083"} {"level":"info","ts":"2026-07-23T18:37:15.883908Z","caller":"traceutil/trace.go:171","msg":"trace[1409946910] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1099; }","duration":"153.133678ms","start":"2026-07-23T18:37:15.730752Z","end":"2026-07-23T18:37:15.883886Z","steps":["trace[1409946910] 'agreement among raft nodes before linearized reading' (duration: 152.657921ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:37:20.031249Z","caller":"traceutil/trace.go:171","msg":"trace[708832550] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"127.527497ms","start":"2026-07-23T18:37:19.903698Z","end":"2026-07-23T18:37:20.031225Z","steps":["trace[708832550] 'process raft request' (duration: 127.358511ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:37:32.229929Z","caller":"traceutil/trace.go:171","msg":"trace[95342512] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"117.592869ms","start":"2026-07-23T18:37:32.112311Z","end":"2026-07-23T18:37:32.229904Z","steps":["trace[95342512] 'process raft request' (duration: 117.481975ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:37:46.670229Z","caller":"traceutil/trace.go:171","msg":"trace[14922049] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"112.829479ms","start":"2026-07-23T18:37:46.557368Z","end":"2026-07-23T18:37:46.670198Z","steps":["trace[14922049] 'process raft request' (duration: 112.651844ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:38:13.016781Z","caller":"traceutil/trace.go:171","msg":"trace[550115631] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"110.440916ms","start":"2026-07-23T18:38:12.9063Z","end":"2026-07-23T18:38:13.016741Z","steps":["trace[550115631] 'process raft request' (duration: 110.158738ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:38:13.152814Z","caller":"traceutil/trace.go:171","msg":"trace[458365156] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"100.863038ms","start":"2026-07-23T18:38:13.051917Z","end":"2026-07-23T18:38:13.15278Z","steps":["trace[458365156] 'process raft request' (duration: 78.044623ms)","trace[458365156] 'compare' (duration: 22.697052ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:38:21.673665Z","caller":"traceutil/trace.go:171","msg":"trace[2033186966] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"347.803257ms","start":"2026-07-23T18:38:21.325836Z","end":"2026-07-23T18:38:21.673639Z","steps":["trace[2033186966] 'process raft request' (duration: 347.637762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:21.673829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:38:21.325807Z","time spent":"347.937251ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T18:38:21.862533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.81936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:38:21.8627Z","caller":"traceutil/trace.go:171","msg":"trace[1782689594] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1405; }","duration":"449.094581ms","start":"2026-07-23T18:38:21.413535Z","end":"2026-07-23T18:38:21.86263Z","steps":["trace[1782689594] 'read index received' (duration: 259.925362ms)","trace[1782689594] 'applied index is now lower than readState.Index' (duration: 189.167629ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:38:21.862838Z","caller":"traceutil/trace.go:171","msg":"trace[756995501] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"535.466425ms","start":"2026-07-23T18:38:21.327196Z","end":"2026-07-23T18:38:21.862662Z","steps":["trace[756995501] 'process raft request' (duration: 417.42084ms)","trace[756995501] 'compare' (duration: 117.596103ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:38:21.863025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.78519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:38:21.863066Z","caller":"traceutil/trace.go:171","msg":"trace[2056739282] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1352; }","duration":"181.870623ms","start":"2026-07-23T18:38:21.681185Z","end":"2026-07-23T18:38:21.863056Z","steps":["trace[2056739282] 'agreement among raft nodes before linearized reading' (duration: 181.78274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:21.863139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.604635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.40\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-23T18:38:21.863192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:38:21.327178Z","time spent":"535.759092ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:38:21.863271Z","caller":"traceutil/trace.go:171","msg":"trace[1009039708] range","detail":"{range_begin:/registry/masterleases/199.19.213.40; range_end:; response_count:1; response_revision:1352; }","duration":"449.750399ms","start":"2026-07-23T18:38:21.413481Z","end":"2026-07-23T18:38:21.863231Z","steps":["trace[1009039708] 'agreement among raft nodes before linearized reading' (duration: 449.40544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:21.863143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.437997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T18:38:21.863355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:38:21.413465Z","time spent":"449.848751ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.40\" "} {"level":"info","ts":"2026-07-23T18:38:21.863385Z","caller":"traceutil/trace.go:171","msg":"trace[692571611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1352; }","duration":"163.701524ms","start":"2026-07-23T18:38:21.699659Z","end":"2026-07-23T18:38:21.863361Z","steps":["trace[692571611] 'agreement among raft nodes before linearized reading' (duration: 163.437297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:22.617709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.088405ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-07-23T18:38:22.617783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:38:21.864332Z","time spent":"753.447549ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-23T18:38:22.799473Z","caller":"traceutil/trace.go:171","msg":"trace[950966389] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"495.091562ms","start":"2026-07-23T18:38:22.304339Z","end":"2026-07-23T18:38:22.799431Z","steps":["trace[950966389] 'process raft request' (duration: 415.666365ms)","trace[950966389] 'compare' (duration: 79.16183ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:38:22.799493Z","caller":"traceutil/trace.go:171","msg":"trace[1503087800] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"179.752466ms","start":"2026-07-23T18:38:22.619709Z","end":"2026-07-23T18:38:22.799462Z","steps":["trace[1503087800] 'process raft request' (duration: 179.622232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:22.799655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.770851ms","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-07-23T18:38:22.799698Z","caller":"traceutil/trace.go:171","msg":"trace[99601811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1354; }","duration":"196.862914ms","start":"2026-07-23T18:38:22.60283Z","end":"2026-07-23T18:38:22.799692Z","steps":["trace[99601811] 'agreement among raft nodes before linearized reading' (duration: 196.70469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:22.799644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:38:22.304316Z","time spent":"495.244086ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:38:22.799495Z","caller":"traceutil/trace.go:171","msg":"trace[2056998071] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"196.605017ms","start":"2026-07-23T18:38:22.602866Z","end":"2026-07-23T18:38:22.799471Z","steps":["trace[2056998071] 'read index received' (duration: 117.151899ms)","trace[2056998071] 'applied index is now lower than readState.Index' (duration: 79.451298ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:38:23.001052Z","caller":"traceutil/trace.go:171","msg":"trace[547023456] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"157.655543ms","start":"2026-07-23T18:38:22.843365Z","end":"2026-07-23T18:38:23.001021Z","steps":["trace[547023456] 'process raft request' (duration: 157.590351ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:38:23.00109Z","caller":"traceutil/trace.go:171","msg":"trace[1135346816] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"197.762787ms","start":"2026-07-23T18:38:22.803303Z","end":"2026-07-23T18:38:23.001065Z","steps":["trace[1135346816] 'process raft request' (duration: 128.557894ms)","trace[1135346816] 'compare' (duration: 68.949757ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:38:23.001287Z","caller":"traceutil/trace.go:171","msg":"trace[1782816021] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1410; }","duration":"180.623348ms","start":"2026-07-23T18:38:22.820644Z","end":"2026-07-23T18:38:23.001267Z","steps":["trace[1782816021] 'read index received' (duration: 111.292861ms)","trace[1782816021] 'applied index is now lower than readState.Index' (duration: 69.328057ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:38:23.001484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.107835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T18:38:23.00151Z","caller":"traceutil/trace.go:171","msg":"trace[111951594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1356; }","duration":"127.168476ms","start":"2026-07-23T18:38:22.874334Z","end":"2026-07-23T18:38:23.001503Z","steps":["trace[111951594] 'agreement among raft nodes before linearized reading' (duration: 127.079074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:23.001505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.846124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-23T18:38:23.001545Z","caller":"traceutil/trace.go:171","msg":"trace[1917258921] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1356; }","duration":"180.928706ms","start":"2026-07-23T18:38:22.820603Z","end":"2026-07-23T18:38:23.001532Z","steps":["trace[1917258921] 'agreement among raft nodes before linearized reading' (duration: 180.777872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:23.001564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.444129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T18:38:23.001643Z","caller":"traceutil/trace.go:171","msg":"trace[1162091614] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1356; }","duration":"119.570933ms","start":"2026-07-23T18:38:22.882052Z","end":"2026-07-23T18:38:23.001622Z","steps":["trace[1162091614] 'agreement among raft nodes before linearized reading' (duration: 119.374607ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:38:31.411302Z","caller":"traceutil/trace.go:171","msg":"trace[2041301109] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1442; }","duration":"310.984498ms","start":"2026-07-23T18:38:31.100281Z","end":"2026-07-23T18:38:31.411265Z","steps":["trace[2041301109] 'read index received' (duration: 234.215317ms)","trace[2041301109] 'applied index is now lower than readState.Index' (duration: 76.766961ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:38:31.4117Z","caller":"traceutil/trace.go:171","msg":"trace[1335868359] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"345.856428ms","start":"2026-07-23T18:38:31.06553Z","end":"2026-07-23T18:38:31.411386Z","steps":["trace[1335868359] 'process raft request' (duration: 268.975113ms)","trace[1335868359] 'compare' (duration: 75.54829ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:38:31.411753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.448841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-07-23T18:38:31.411842Z","caller":"traceutil/trace.go:171","msg":"trace[1289406218] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:1385; }","duration":"311.591514ms","start":"2026-07-23T18:38:31.100227Z","end":"2026-07-23T18:38:31.411818Z","steps":["trace[1289406218] 'agreement among raft nodes before linearized reading' (duration: 311.331677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:31.411887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:38:31.100208Z","time spent":"311.667956ms","remote":"127.0.0.1:40228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":773,"request content":"key:\"/registry/clusterrolebindings/system:controller:endpoint-controller\" "} {"level":"warn","ts":"2026-07-23T18:38:31.4119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:38:31.06551Z","time spent":"346.29316ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T18:38:31.500582Z","caller":"traceutil/trace.go:171","msg":"trace[788934588] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"397.046059ms","start":"2026-07-23T18:38:31.103496Z","end":"2026-07-23T18:38:31.500542Z","steps":["trace[788934588] 'process raft request' (duration: 396.861954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:31.500671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.746541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" ","response":"range_response_count:1 size:843"} {"level":"info","ts":"2026-07-23T18:38:31.500758Z","caller":"traceutil/trace.go:171","msg":"trace[1748375379] range","detail":"{range_begin:/registry/clusterroles/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:1386; }","duration":"360.885164ms","start":"2026-07-23T18:38:31.139848Z","end":"2026-07-23T18:38:31.500733Z","steps":["trace[1748375379] 'agreement among raft nodes before linearized reading' (duration: 360.73171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:31.500763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.208737ms","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-07-23T18:38:31.50077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:38:31.103474Z","time spent":"397.199772ms","remote":"127.0.0.1:40150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T18:38:31.50081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:38:31.139823Z","time spent":"360.979067ms","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":865,"request content":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" "} {"level":"info","ts":"2026-07-23T18:38:31.500832Z","caller":"traceutil/trace.go:171","msg":"trace[480729665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1386; }","duration":"272.315839ms","start":"2026-07-23T18:38:31.228499Z","end":"2026-07-23T18:38:31.500814Z","steps":["trace[480729665] 'agreement among raft nodes before linearized reading' (duration: 272.083744ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:38:31.625153Z","caller":"traceutil/trace.go:171","msg":"trace[184254384] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"119.085974ms","start":"2026-07-23T18:38:31.506041Z","end":"2026-07-23T18:38:31.625127Z","steps":["trace[184254384] 'process raft request' (duration: 19.78998ms)","trace[184254384] 'compare' (duration: 99.032228ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:38:31.625352Z","caller":"traceutil/trace.go:171","msg":"trace[209845402] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"116.762674ms","start":"2026-07-23T18:38:31.508563Z","end":"2026-07-23T18:38:31.625326Z","steps":["trace[209845402] 'process raft request' (duration: 116.691403ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:38:33.258102Z","caller":"traceutil/trace.go:171","msg":"trace[2083492593] linearizableReadLoop","detail":"{readStateIndex:1451; appliedIndex:1450; }","duration":"114.8023ms","start":"2026-07-23T18:38:33.143276Z","end":"2026-07-23T18:38:33.258079Z","steps":["trace[2083492593] 'read index received' (duration: 106.079856ms)","trace[2083492593] 'applied index is now lower than readState.Index' (duration: 8.720894ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:38:33.258207Z","caller":"traceutil/trace.go:171","msg":"trace[1346743107] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"154.884827ms","start":"2026-07-23T18:38:33.103277Z","end":"2026-07-23T18:38:33.258162Z","steps":["trace[1346743107] 'process raft request' (duration: 146.02251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:33.258262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.972275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:node-controller\" ","response":"range_response_count:1 size:735"} {"level":"info","ts":"2026-07-23T18:38:33.258446Z","caller":"traceutil/trace.go:171","msg":"trace[2108896661] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:node-controller; range_end:; response_count:1; response_revision:1392; }","duration":"115.19352ms","start":"2026-07-23T18:38:33.143212Z","end":"2026-07-23T18:38:33.258405Z","steps":["trace[2108896661] 'agreement among raft nodes before linearized reading' (duration: 114.992105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:33.530931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.579735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-07-23T18:38:33.531024Z","caller":"traceutil/trace.go:171","msg":"trace[133143367] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1392; }","duration":"107.726268ms","start":"2026-07-23T18:38:33.423274Z","end":"2026-07-23T18:38:33.531Z","steps":["trace[133143367] 'range keys from in-memory index tree' (duration: 107.457811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:33.531134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.577904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-23T18:38:33.531193Z","caller":"traceutil/trace.go:171","msg":"trace[1486618067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1392; }","duration":"114.663056ms","start":"2026-07-23T18:38:33.416511Z","end":"2026-07-23T18:38:33.531174Z","steps":["trace[1486618067] 'range keys from in-memory index tree' (duration: 114.428101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:33.530942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.648739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-07-23T18:38:33.531298Z","caller":"traceutil/trace.go:171","msg":"trace[486257796] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:1; response_revision:1392; }","duration":"105.015939ms","start":"2026-07-23T18:38:33.426263Z","end":"2026-07-23T18:38:33.531279Z","steps":["trace[486257796] 'range keys from in-memory index tree' (duration: 104.588758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:47.937074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.876975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:38:47.937203Z","caller":"traceutil/trace.go:171","msg":"trace[1937578683] linearizableReadLoop","detail":"{readStateIndex:1502; appliedIndex:1501; }","duration":"284.326402ms","start":"2026-07-23T18:38:47.652856Z","end":"2026-07-23T18:38:47.937182Z","steps":["trace[1937578683] 'read index received' (duration: 110.280394ms)","trace[1937578683] 'applied index is now lower than readState.Index' (duration: 174.044138ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:38:47.937388Z","caller":"traceutil/trace.go:171","msg":"trace[1113889759] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"287.811177ms","start":"2026-07-23T18:38:47.649468Z","end":"2026-07-23T18:38:47.937279Z","steps":["trace[1113889759] 'process raft request' (duration: 113.610584ms)","trace[1113889759] 'compare' (duration: 173.65732ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:38:47.937385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.508387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-l204dz\" ","response":"range_response_count:1 size:692"} {"level":"warn","ts":"2026-07-23T18:38:47.937711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.671683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:70 size:51768"} {"level":"info","ts":"2026-07-23T18:38:47.937801Z","caller":"traceutil/trace.go:171","msg":"trace[103697524] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-l204dz; range_end:; response_count:1; response_revision:1440; }","duration":"284.934787ms","start":"2026-07-23T18:38:47.652833Z","end":"2026-07-23T18:38:47.937768Z","steps":["trace[103697524] 'agreement among raft nodes before linearized reading' (duration: 284.475346ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:38:47.937811Z","caller":"traceutil/trace.go:171","msg":"trace[1029687808] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:70; response_revision:1440; }","duration":"261.812867ms","start":"2026-07-23T18:38:47.675979Z","end":"2026-07-23T18:38:47.937791Z","steps":["trace[1029687808] 'agreement among raft nodes before linearized reading' (duration: 261.323295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:47.937464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.305061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:38:47.938147Z","caller":"traceutil/trace.go:171","msg":"trace[1758502108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1440; }","duration":"239.964267ms","start":"2026-07-23T18:38:47.698133Z","end":"2026-07-23T18:38:47.938097Z","steps":["trace[1758502108] 'agreement among raft nodes before linearized reading' (duration: 239.310852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:47.937423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.403727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T18:38:47.938269Z","caller":"traceutil/trace.go:171","msg":"trace[1723715881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1440; }","duration":"137.282718ms","start":"2026-07-23T18:38:47.800976Z","end":"2026-07-23T18:38:47.938258Z","steps":["trace[1723715881] 'agreement among raft nodes before linearized reading' (duration: 136.328985ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:38:48.076325Z","caller":"traceutil/trace.go:171","msg":"trace[1726481514] linearizableReadLoop","detail":"{readStateIndex:1503; appliedIndex:1502; }","duration":"133.314164ms","start":"2026-07-23T18:38:47.942979Z","end":"2026-07-23T18:38:48.076293Z","steps":["trace[1726481514] 'read index received' (duration: 120.028874ms)","trace[1726481514] 'applied index is now lower than readState.Index' (duration: 13.28417ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:38:48.076465Z","caller":"traceutil/trace.go:171","msg":"trace[1776434721] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"134.065402ms","start":"2026-07-23T18:38:47.942372Z","end":"2026-07-23T18:38:48.076438Z","steps":["trace[1776434721] 'process raft request' (duration: 120.569957ms)","trace[1776434721] 'compare' (duration: 13.244639ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:38:48.076511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.514459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-l204dz\" ","response":"range_response_count:1 size:692"} {"level":"info","ts":"2026-07-23T18:38:48.076561Z","caller":"traceutil/trace.go:171","msg":"trace[1691004704] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-l204dz; range_end:; response_count:1; response_revision:1441; }","duration":"133.591891ms","start":"2026-07-23T18:38:47.942942Z","end":"2026-07-23T18:38:48.076534Z","steps":["trace[1691004704] 'agreement among raft nodes before linearized reading' (duration: 133.500879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:48.076703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.417733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T18:38:48.076777Z","caller":"traceutil/trace.go:171","msg":"trace[1968456052] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1441; }","duration":"118.545097ms","start":"2026-07-23T18:38:47.958208Z","end":"2026-07-23T18:38:48.076753Z","steps":["trace[1968456052] 'agreement among raft nodes before linearized reading' (duration: 118.323671ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:38:48.244953Z","caller":"traceutil/trace.go:171","msg":"trace[1172387420] linearizableReadLoop","detail":"{readStateIndex:1504; appliedIndex:1503; }","duration":"124.986828ms","start":"2026-07-23T18:38:48.119942Z","end":"2026-07-23T18:38:48.244929Z","steps":["trace[1172387420] 'read index received' (duration: 120.264874ms)","trace[1172387420] 'applied index is now lower than readState.Index' (duration: 4.721084ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:38:48.245042Z","caller":"traceutil/trace.go:171","msg":"trace[1082959830] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"164.112373ms","start":"2026-07-23T18:38:48.080887Z","end":"2026-07-23T18:38:48.244999Z","steps":["trace[1082959830] 'process raft request' (duration: 159.319887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:48.245135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.170202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:38:48.245172Z","caller":"traceutil/trace.go:171","msg":"trace[1285451566] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1442; }","duration":"125.262725ms","start":"2026-07-23T18:38:48.119899Z","end":"2026-07-23T18:38:48.245161Z","steps":["trace[1285451566] 'agreement among raft nodes before linearized reading' (duration: 125.162702ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:38:51.935623Z","caller":"traceutil/trace.go:171","msg":"trace[806217602] linearizableReadLoop","detail":"{readStateIndex:1516; appliedIndex:1515; }","duration":"244.58216ms","start":"2026-07-23T18:38:51.69102Z","end":"2026-07-23T18:38:51.935603Z","steps":["trace[806217602] 'read index received' (duration: 244.352254ms)","trace[806217602] 'applied index is now lower than readState.Index' (duration: 228.846µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:38:51.935693Z","caller":"traceutil/trace.go:171","msg":"trace[759680118] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"271.167485ms","start":"2026-07-23T18:38:51.664487Z","end":"2026-07-23T18:38:51.935655Z","steps":["trace[759680118] 'process raft request' (duration: 270.9605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:51.935834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.413009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:38:51.935867Z","caller":"traceutil/trace.go:171","msg":"trace[1264017410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1453; }","duration":"237.47341ms","start":"2026-07-23T18:38:51.698385Z","end":"2026-07-23T18:38:51.935859Z","steps":["trace[1264017410] 'agreement among raft nodes before linearized reading' (duration: 237.363837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:51.93593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.887617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T18:38:51.935996Z","caller":"traceutil/trace.go:171","msg":"trace[1915841857] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1453; }","duration":"245.02461ms","start":"2026-07-23T18:38:51.690955Z","end":"2026-07-23T18:38:51.935979Z","steps":["trace[1915841857] 'agreement among raft nodes before linearized reading' (duration: 244.773244ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:38:55.661758Z","caller":"traceutil/trace.go:171","msg":"trace[659481150] linearizableReadLoop","detail":"{readStateIndex:1528; appliedIndex:1527; }","duration":"162.066293ms","start":"2026-07-23T18:38:55.499674Z","end":"2026-07-23T18:38:55.661741Z","steps":["trace[659481150] 'read index received' (duration: 161.96318ms)","trace[659481150] 'applied index is now lower than readState.Index' (duration: 102.243µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:38:55.661788Z","caller":"traceutil/trace.go:171","msg":"trace[698364299] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"233.897261ms","start":"2026-07-23T18:38:55.427868Z","end":"2026-07-23T18:38:55.661765Z","steps":["trace[698364299] 'process raft request' (duration: 233.780859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:55.661899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.209326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5154"} {"level":"info","ts":"2026-07-23T18:38:55.661919Z","caller":"traceutil/trace.go:171","msg":"trace[897585852] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1465; }","duration":"162.276168ms","start":"2026-07-23T18:38:55.499637Z","end":"2026-07-23T18:38:55.661913Z","steps":["trace[897585852] 'agreement among raft nodes before linearized reading' (duration: 162.206386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:55.959118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.704197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5154"} {"level":"info","ts":"2026-07-23T18:38:55.959307Z","caller":"traceutil/trace.go:171","msg":"trace[1774079397] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1465; }","duration":"227.935002ms","start":"2026-07-23T18:38:55.731347Z","end":"2026-07-23T18:38:55.959282Z","steps":["trace[1774079397] 'range keys from in-memory index tree' (duration: 227.581954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:56.285797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.593393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:38:56.285916Z","caller":"traceutil/trace.go:171","msg":"trace[339239672] linearizableReadLoop","detail":"{readStateIndex:1530; appliedIndex:1529; }","duration":"234.739782ms","start":"2026-07-23T18:38:56.05116Z","end":"2026-07-23T18:38:56.285899Z","steps":["trace[339239672] 'read index received' (duration: 79.990835ms)","trace[339239672] 'applied index is now lower than readState.Index' (duration: 154.747807ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:38:56.285951Z","caller":"traceutil/trace.go:171","msg":"trace[146060057] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"284.168011ms","start":"2026-07-23T18:38:56.001745Z","end":"2026-07-23T18:38:56.285913Z","steps":["trace[146060057] 'process raft request' (duration: 129.375113ms)","trace[146060057] 'compare' (duration: 154.46511ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:38:56.286052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.896436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5154"} {"level":"info","ts":"2026-07-23T18:38:56.286088Z","caller":"traceutil/trace.go:171","msg":"trace[691219032] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1467; }","duration":"234.957997ms","start":"2026-07-23T18:38:56.05112Z","end":"2026-07-23T18:38:56.286078Z","steps":["trace[691219032] 'agreement among raft nodes before linearized reading' (duration: 234.863055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:38:56.286231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.888808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-23T18:38:56.286317Z","caller":"traceutil/trace.go:171","msg":"trace[740113241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1467; }","duration":"131.003771ms","start":"2026-07-23T18:38:56.155291Z","end":"2026-07-23T18:38:56.286295Z","steps":["trace[740113241] 'agreement among raft nodes before linearized reading' (duration: 130.803036ms)"],"step_count":1}