{"level":"warn","ts":"2026-04-20T10:11:20.554048Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T10:11:20.554163Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.138: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.138:2380","--initial-cluster=instance=https://199.19.213.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.138:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-20T10:11:20.554251Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T10:11:20.554265Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.138:2380"]} {"level":"info","ts":"2026-04-20T10:11:20.554289Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T10:11:20.555275Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"]} {"level":"info","ts":"2026-04-20T10:11:20.555386Z","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.138:2380"],"listen-peer-urls":["https://199.19.213.138:2380"],"advertise-client-urls":["https://199.19.213.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.138:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-20T10:11:20.559702Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.047898ms"} {"level":"info","ts":"2026-04-20T10:11:20.567557Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2062be27cfb43adb","cluster-id":"f353072e75ed4d57"} {"level":"info","ts":"2026-04-20T10:11:20.56764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T10:11:20.56766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became follower at term 0"} {"level":"info","ts":"2026-04-20T10:11:20.567671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2062be27cfb43adb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T10:11:20.567679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became follower at term 1"} {"level":"info","ts":"2026-04-20T10:11:20.567713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=(2333636635129035483)"} {"level":"warn","ts":"2026-04-20T10:11:20.573057Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T10:11:20.57425Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T10:11:20.575985Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-20T10:11:20.577957Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2062be27cfb43adb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T10:11:20.578354Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T10:11:20.578403Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2062be27cfb43adb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T10:11:20.57955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=(2333636635129035483)"} {"level":"info","ts":"2026-04-20T10:11:20.579865Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T10:11:20.5799Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T10:11:20.579936Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f353072e75ed4d57","local-member-id":"2062be27cfb43adb","added-peer-id":"2062be27cfb43adb","added-peer-peer-urls":["https://199.19.213.138:2380"]} {"level":"info","ts":"2026-04-20T10:11:20.583154Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T10:11:20.583385Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2062be27cfb43adb","initial-advertise-peer-urls":["https://199.19.213.138:2380"],"listen-peer-urls":["https://199.19.213.138:2380"],"advertise-client-urls":["https://199.19.213.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T10:11:20.583436Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T10:11:20.583685Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.138:2380"} {"level":"info","ts":"2026-04-20T10:11:20.583731Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.138:2380"} {"level":"info","ts":"2026-04-20T10:11:20.968859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T10:11:20.96891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T10:11:20.96894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb received MsgPreVoteResp from 2062be27cfb43adb at term 1"} {"level":"info","ts":"2026-04-20T10:11:20.968958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became candidate at term 2"} {"level":"info","ts":"2026-04-20T10:11:20.96897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb received MsgVoteResp from 2062be27cfb43adb at term 2"} {"level":"info","ts":"2026-04-20T10:11:20.968982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became leader at term 2"} {"level":"info","ts":"2026-04-20T10:11:20.968995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2062be27cfb43adb elected leader 2062be27cfb43adb at term 2"} {"level":"info","ts":"2026-04-20T10:11:20.969855Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2062be27cfb43adb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.138:2379]}","request-path":"/0/members/2062be27cfb43adb/attributes","cluster-id":"f353072e75ed4d57","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T10:11:20.969897Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:11:20.970412Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:11:20.970468Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:11:20.970639Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T10:11:20.970661Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T10:11:20.971871Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f353072e75ed4d57","local-member-id":"2062be27cfb43adb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:11:20.972155Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:11:20.972236Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:11:20.97242Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T10:11:20.97841Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.138:2379"} {"level":"info","ts":"2026-04-20T10:11:22.705342Z","caller":"traceutil/trace.go:171","msg":"trace[1934850531] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"273.1752ms","start":"2026-04-20T10:11:22.432085Z","end":"2026-04-20T10:11:22.70526Z","steps":["trace[1934850531] 'process raft request' (duration: 179.242379ms)","trace[1934850531] 'compare' (duration: 93.743904ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:11:22.70537Z","caller":"traceutil/trace.go:171","msg":"trace[2099471943] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"273.16361ms","start":"2026-04-20T10:11:22.432183Z","end":"2026-04-20T10:11:22.705347Z","steps":["trace[2099471943] 'process raft request' (duration: 273.069136ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.705387Z","caller":"traceutil/trace.go:171","msg":"trace[1499996300] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"273.222111ms","start":"2026-04-20T10:11:22.432117Z","end":"2026-04-20T10:11:22.705339Z","steps":["trace[1499996300] 'process raft request' (duration: 273.073356ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.705473Z","caller":"traceutil/trace.go:171","msg":"trace[207530568] transaction","detail":"{read_only:false; number_of_response:0; response_revision:9; }","duration":"272.466632ms","start":"2026-04-20T10:11:22.432967Z","end":"2026-04-20T10:11:22.705433Z","steps":["trace[207530568] 'process raft request' (duration: 272.348758ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.70543Z","caller":"traceutil/trace.go:171","msg":"trace[1887082628] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"271.488855ms","start":"2026-04-20T10:11:22.433905Z","end":"2026-04-20T10:11:22.705394Z","steps":["trace[1887082628] 'process raft request' (duration: 271.437293ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.705505Z","caller":"traceutil/trace.go:171","msg":"trace[1918960914] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"273.20421ms","start":"2026-04-20T10:11:22.43229Z","end":"2026-04-20T10:11:22.705495Z","steps":["trace[1918960914] 'process raft request' (duration: 272.990132ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.706371Z","caller":"traceutil/trace.go:171","msg":"trace[418872932] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"273.70597ms","start":"2026-04-20T10:11:22.432173Z","end":"2026-04-20T10:11:22.705879Z","steps":["trace[418872932] 'process raft request' (duration: 273.052005ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.804097Z","caller":"traceutil/trace.go:171","msg":"trace[487616779] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"367.37344ms","start":"2026-04-20T10:11:22.436689Z","end":"2026-04-20T10:11:22.804063Z","steps":["trace[487616779] 'process raft request' (duration: 367.11818ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.80426Z","caller":"traceutil/trace.go:171","msg":"trace[1773987615] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"359.249019ms","start":"2026-04-20T10:11:22.444916Z","end":"2026-04-20T10:11:22.804165Z","steps":["trace[1773987615] 'process raft request' (duration: 359.026451ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.804419Z","caller":"traceutil/trace.go:171","msg":"trace[171766078] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"359.095113ms","start":"2026-04-20T10:11:22.44531Z","end":"2026-04-20T10:11:22.804405Z","steps":["trace[171766078] 'process raft request' (duration: 358.697848ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.8041Z","caller":"traceutil/trace.go:171","msg":"trace[1272312583] linearizableReadLoop","detail":"{readStateIndex:17; appliedIndex:7; }","duration":"318.81315ms","start":"2026-04-20T10:11:22.485263Z","end":"2026-04-20T10:11:22.804076Z","steps":["trace[1272312583] 'read index received' (duration: 126.088392ms)","trace[1272312583] 'applied index is now lower than readState.Index' (duration: 192.723848ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:11:22.80466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.436682Z","time spent":"367.520525ms","remote":"127.0.0.1:36304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:11:22.804572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.298037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-04-20T10:11:22.80469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.444903Z","time spent":"359.461546ms","remote":"127.0.0.1:35974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:11:22.804783Z","caller":"traceutil/trace.go:171","msg":"trace[548763479] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"319.528706ms","start":"2026-04-20T10:11:22.485215Z","end":"2026-04-20T10:11:22.804744Z","steps":["trace[548763479] 'agreement among raft nodes before linearized reading' (duration: 318.956024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:22.80477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.4453Z","time spent":"359.159255ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:11:22.804833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.485194Z","time spent":"319.62779ms","remote":"127.0.0.1:35894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-20T10:11:23.01083Z","caller":"traceutil/trace.go:171","msg":"trace[835789479] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:17; }","duration":"206.321836ms","start":"2026-04-20T10:11:22.804486Z","end":"2026-04-20T10:11:23.010808Z","steps":["trace[835789479] 'read index received' (duration: 203.166616ms)","trace[835789479] 'applied index is now lower than readState.Index' (duration: 3.15434ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:11:23.010951Z","caller":"traceutil/trace.go:171","msg":"trace[263520531] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"301.011244ms","start":"2026-04-20T10:11:22.709899Z","end":"2026-04-20T10:11:23.010911Z","steps":["trace[263520531] 'process raft request' (duration: 297.736279ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:23.010943Z","caller":"traceutil/trace.go:171","msg":"trace[1982752044] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"300.91264ms","start":"2026-04-20T10:11:22.710003Z","end":"2026-04-20T10:11:23.010915Z","steps":["trace[1982752044] 'process raft request' (duration: 300.768494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:23.011115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.006222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T10:11:23.011144Z","caller":"traceutil/trace.go:171","msg":"trace[692365134] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:18; }","duration":"314.064354ms","start":"2026-04-20T10:11:22.69707Z","end":"2026-04-20T10:11:23.011134Z","steps":["trace[692365134] 'agreement among raft nodes before linearized reading' (duration: 314.023953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:23.011191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.697053Z","time spent":"314.127667ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-20T10:11:23.011183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.709871Z","time spent":"301.141789ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:11:23.011353Z","caller":"traceutil/trace.go:171","msg":"trace[1952606321] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"301.376727ms","start":"2026-04-20T10:11:22.709925Z","end":"2026-04-20T10:11:23.011302Z","steps":["trace[1952606321] 'process raft request' (duration: 300.729253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:23.011459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.792918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-20T10:11:23.011263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.709982Z","time spent":"301.135318ms","remote":"127.0.0.1:36304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:11:23.011487Z","caller":"traceutil/trace.go:171","msg":"trace[1028787910] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"301.46515ms","start":"2026-04-20T10:11:22.70997Z","end":"2026-04-20T10:11:23.011435Z","steps":["trace[1028787910] 'process raft request' (duration: 300.743173ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:23.011557Z","caller":"traceutil/trace.go:171","msg":"trace[1798438414] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"301.527344ms","start":"2026-04-20T10:11:22.710019Z","end":"2026-04-20T10:11:23.011546Z","steps":["trace[1798438414] 'process raft request' (duration: 300.721893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:23.011078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.303845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-20T10:11:23.011732Z","caller":"traceutil/trace.go:171","msg":"trace[419743669] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:18; }","duration":"301.984581ms","start":"2026-04-20T10:11:22.709729Z","end":"2026-04-20T10:11:23.011713Z","steps":["trace[419743669] 'agreement among raft nodes before linearized reading' (duration: 301.316716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:23.011458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.709896Z","time spent":"301.535263ms","remote":"127.0.0.1:36304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:11:23.011743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.709953Z","time spent":"301.70841ms","remote":"127.0.0.1:36304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:11:23.011786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.709703Z","time spent":"302.069734ms","remote":"127.0.0.1:35894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-20T10:11:23.011514Z","caller":"traceutil/trace.go:171","msg":"trace[317090861] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:18; }","duration":"382.910803ms","start":"2026-04-20T10:11:22.628586Z","end":"2026-04-20T10:11:23.011497Z","steps":["trace[317090861] 'agreement among raft nodes before linearized reading' (duration: 382.84284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:23.011736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.710009Z","time spent":"301.652588ms","remote":"127.0.0.1:36304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:11:23.011849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.628557Z","time spent":"383.276556ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-04-20T10:11:23.011021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.743738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T10:11:23.012025Z","caller":"traceutil/trace.go:171","msg":"trace[545463015] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:18; }","duration":"299.778346ms","start":"2026-04-20T10:11:22.712231Z","end":"2026-04-20T10:11:23.012009Z","steps":["trace[545463015] 'agreement among raft nodes before linearized reading' (duration: 298.704346ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:23.019263Z","caller":"traceutil/trace.go:171","msg":"trace[801577935] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"211.199449ms","start":"2026-04-20T10:11:22.808045Z","end":"2026-04-20T10:11:23.019245Z","steps":["trace[801577935] 'process raft request' (duration: 211.059453ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:23.019329Z","caller":"traceutil/trace.go:171","msg":"trace[964773813] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"209.823996ms","start":"2026-04-20T10:11:22.80949Z","end":"2026-04-20T10:11:23.019314Z","steps":["trace[964773813] 'process raft request' (duration: 209.708442ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:23.019401Z","caller":"traceutil/trace.go:171","msg":"trace[1395271430] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"202.691602ms","start":"2026-04-20T10:11:22.816702Z","end":"2026-04-20T10:11:23.019394Z","steps":["trace[1395271430] 'process raft request' (duration: 202.544277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:23.019512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.25531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-4w88j\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-04-20T10:11:23.019542Z","caller":"traceutil/trace.go:171","msg":"trace[2088348217] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-4w88j; range_end:; response_count:1; response_revision:21; }","duration":"211.307662ms","start":"2026-04-20T10:11:22.808226Z","end":"2026-04-20T10:11:23.019534Z","steps":["trace[2088348217] 'agreement among raft nodes before linearized reading' (duration: 211.23791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:20.959534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.191053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T10:12:20.959662Z","caller":"traceutil/trace.go:171","msg":"trace[1203734786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"169.394008ms","start":"2026-04-20T10:12:20.790242Z","end":"2026-04-20T10:12:20.959636Z","steps":["trace[1203734786] 'range keys from in-memory index tree' (duration: 168.917818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:20.959755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.071085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T10:12:20.959863Z","caller":"traceutil/trace.go:171","msg":"trace[856970853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:804; }","duration":"171.272979ms","start":"2026-04-20T10:12:20.788565Z","end":"2026-04-20T10:12:20.959838Z","steps":["trace[856970853] 'range keys from in-memory index tree' (duration: 170.812179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:14.015314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241156829172171481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:14:14.516146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241156829172171481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:14:14.682233Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.470614783s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T10:14:14.693493Z","caller":"traceutil/trace.go:171","msg":"trace[630781253] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"1.490074399s","start":"2026-04-20T10:14:13.203341Z","end":"2026-04-20T10:14:14.693415Z","steps":["trace[630781253] 'process raft request' (duration: 1.479174945s)","trace[630781253] 'compare' (duration: 10.364827ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:14:14.693933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:13.203325Z","time spent":"1.490267391s","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:14:14.719043Z","caller":"traceutil/trace.go:171","msg":"trace[48807624] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1403; }","duration":"1.204130445s","start":"2026-04-20T10:14:13.514888Z","end":"2026-04-20T10:14:14.719018Z","steps":["trace[48807624] 'read index received' (duration: 1.167728686s)","trace[48807624] 'applied index is now lower than readState.Index' (duration: 36.401059ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:14:14.71914Z","caller":"traceutil/trace.go:171","msg":"trace[733415089] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"1.18017448s","start":"2026-04-20T10:14:13.538949Z","end":"2026-04-20T10:14:14.719123Z","steps":["trace[733415089] 'process raft request' (duration: 1.179942297s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:14.719285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:13.538927Z","time spent":"1.180248952s","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:14:14.719355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.20367714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T10:14:14.7194Z","caller":"traceutil/trace.go:171","msg":"trace[633427996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1351; }","duration":"1.203725671s","start":"2026-04-20T10:14:13.515663Z","end":"2026-04-20T10:14:14.719389Z","steps":["trace[633427996] 'agreement among raft nodes before linearized reading' (duration: 1.203548058s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:14.7194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.238255ms","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-04-20T10:14:14.719408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.72276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:14:14.719465Z","caller":"traceutil/trace.go:171","msg":"trace[1837873110] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1351; }","duration":"746.358716ms","start":"2026-04-20T10:14:13.973093Z","end":"2026-04-20T10:14:14.719451Z","steps":["trace[1837873110] 'agreement among raft nodes before linearized reading' (duration: 746.177334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:14.719477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.654496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T10:14:14.719494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:13.973071Z","time spent":"746.416938ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T10:14:14.719506Z","caller":"traceutil/trace.go:171","msg":"trace[1531292505] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1351; }","duration":"823.720256ms","start":"2026-04-20T10:14:13.895777Z","end":"2026-04-20T10:14:14.719498Z","steps":["trace[1531292505] 'agreement among raft nodes before linearized reading' (duration: 823.667395ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:14.719516Z","caller":"traceutil/trace.go:171","msg":"trace[908639694] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1351; }","duration":"313.861602ms","start":"2026-04-20T10:14:14.405614Z","end":"2026-04-20T10:14:14.719476Z","steps":["trace[908639694] 'agreement among raft nodes before linearized reading' (duration: 313.70422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:14.719539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:13.895757Z","time spent":"823.773908ms","remote":"127.0.0.1:36212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:14:14.719429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:13.515615Z","time spent":"1.203807442s","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T10:14:14.719561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:14.405583Z","time spent":"313.966453ms","remote":"127.0.0.1:36052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:14:14.719635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.467322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:14:14.719672Z","caller":"traceutil/trace.go:171","msg":"trace[1649584354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1351; }","duration":"838.548533ms","start":"2026-04-20T10:14:13.881115Z","end":"2026-04-20T10:14:14.719664Z","steps":["trace[1649584354] 'agreement among raft nodes before linearized reading' (duration: 838.493173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:14.719699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:13.881096Z","time spent":"838.596644ms","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:14:14.719954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205044498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T10:14:14.720075Z","caller":"traceutil/trace.go:171","msg":"trace[68209062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1351; }","duration":"1.205172129s","start":"2026-04-20T10:14:13.514883Z","end":"2026-04-20T10:14:14.720055Z","steps":["trace[68209062] 'agreement among raft nodes before linearized reading' (duration: 1.204288828s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:14.720184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:13.51484Z","time spent":"1.205331102s","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T10:14:14.719999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.400244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T10:14:14.720445Z","caller":"traceutil/trace.go:171","msg":"trace[1585745747] range","detail":"{range_begin:/registry/masterleases/199.19.213.138; range_end:; response_count:1; response_revision:1351; }","duration":"676.85283ms","start":"2026-04-20T10:14:14.04358Z","end":"2026-04-20T10:14:14.720433Z","steps":["trace[1585745747] 'agreement among raft nodes before linearized reading' (duration: 675.881938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:14.720478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:14.04352Z","time spent":"676.947852ms","remote":"127.0.0.1:35830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.138\" "} {"level":"info","ts":"2026-04-20T10:14:16.351067Z","caller":"traceutil/trace.go:171","msg":"trace[1213389624] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"184.522528ms","start":"2026-04-20T10:14:16.166522Z","end":"2026-04-20T10:14:16.351045Z","steps":["trace[1213389624] 'process raft request' (duration: 184.388537ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:16.964853Z","caller":"traceutil/trace.go:171","msg":"trace[392884411] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"157.652286ms","start":"2026-04-20T10:14:16.807181Z","end":"2026-04-20T10:14:16.964833Z","steps":["trace[392884411] 'process raft request' (duration: 122.647528ms)","trace[392884411] 'compare' (duration: 34.756014ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:14:16.965226Z","caller":"traceutil/trace.go:171","msg":"trace[273973717] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"157.99973ms","start":"2026-04-20T10:14:16.8072Z","end":"2026-04-20T10:14:16.9652Z","steps":["trace[273973717] 'process raft request' (duration: 157.580185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:45.444016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241156829172172849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:14:45.945231Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241156829172172849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:14:46.445422Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241156829172172849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:14:46.945834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241156829172172849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:14:47.446733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241156829172172849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:14:47.519443Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.582917928s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T10:14:47.522545Z","caller":"traceutil/trace.go:171","msg":"trace[1272664657] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"2.586041337s","start":"2026-04-20T10:14:44.936468Z","end":"2026-04-20T10:14:47.52251Z","steps":["trace[1272664657] 'process raft request' (duration: 2.583421604s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:47.522724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:44.93646Z","time spent":"2.586166578s","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:14:47.523298Z","caller":"traceutil/trace.go:171","msg":"trace[2022489359] linearizableReadLoop","detail":"{readStateIndex:1516; appliedIndex:1513; }","duration":"2.579807179s","start":"2026-04-20T10:14:44.943452Z","end":"2026-04-20T10:14:47.523259Z","steps":["trace[2022489359] 'read index received' (duration: 2.576386196s)","trace[2022489359] 'applied index is now lower than readState.Index' (duration: 3.419653ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:14:47.523402Z","caller":"traceutil/trace.go:171","msg":"trace[1324718506] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"698.898241ms","start":"2026-04-20T10:14:46.824468Z","end":"2026-04-20T10:14:47.523366Z","steps":["trace[1324718506] 'process raft request' (duration: 698.607928ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:47.523505Z","caller":"traceutil/trace.go:171","msg":"trace[811114628] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"605.149263ms","start":"2026-04-20T10:14:46.91832Z","end":"2026-04-20T10:14:47.52347Z","steps":["trace[811114628] 'process raft request' (duration: 604.8747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:47.523551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:46.824441Z","time spent":"699.042442ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:14:47.523566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.799885044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:14:47.523623Z","caller":"traceutil/trace.go:171","msg":"trace[1854996243] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1455; }","duration":"1.799989105s","start":"2026-04-20T10:14:45.723617Z","end":"2026-04-20T10:14:47.523606Z","steps":["trace[1854996243] 'agreement among raft nodes before linearized reading' (duration: 1.799886784s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:47.523614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.193446281s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:14:47.523641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:46.918301Z","time spent":"605.276455ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:14:47.523683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:45.723594Z","time spent":"1.800075987s","remote":"127.0.0.1:35894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":6,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:14:47.523699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.764947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T10:14:47.523699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.434516907s","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":"warn","ts":"2026-04-20T10:14:47.523746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.64235133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:14:47.523768Z","caller":"traceutil/trace.go:171","msg":"trace[150272097] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1455; }","duration":"2.434654479s","start":"2026-04-20T10:14:45.089096Z","end":"2026-04-20T10:14:47.523751Z","steps":["trace[150272097] 'agreement among raft nodes before linearized reading' (duration: 2.434440746s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:47.523789Z","caller":"traceutil/trace.go:171","msg":"trace[4986835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1455; }","duration":"1.642420771s","start":"2026-04-20T10:14:45.881359Z","end":"2026-04-20T10:14:47.523779Z","steps":["trace[4986835] 'agreement among raft nodes before linearized reading' (duration: 1.64236552s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:47.523813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:45.089069Z","time spent":"2.434734919s","remote":"127.0.0.1:36070","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-04-20T10:14:47.523829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:45.881343Z","time spent":"1.642479772s","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:14:47.523635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.146694278s","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-04-20T10:14:47.523909Z","caller":"traceutil/trace.go:171","msg":"trace[955425385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1455; }","duration":"2.146991583s","start":"2026-04-20T10:14:45.37691Z","end":"2026-04-20T10:14:47.523902Z","steps":["trace[955425385] 'agreement among raft nodes before linearized reading' (duration: 2.146685909s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:47.523939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:45.376896Z","time spent":"2.147035673s","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T10:14:47.523731Z","caller":"traceutil/trace.go:171","msg":"trace[261288095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1455; }","duration":"587.831877ms","start":"2026-04-20T10:14:46.935891Z","end":"2026-04-20T10:14:47.523723Z","steps":["trace[261288095] 'agreement among raft nodes before linearized reading' (duration: 587.774577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:47.524018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:46.935871Z","time spent":"588.126741ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T10:14:47.523572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.434503236s","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-04-20T10:14:47.524224Z","caller":"traceutil/trace.go:171","msg":"trace[1234199883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1455; }","duration":"2.435176104s","start":"2026-04-20T10:14:45.089032Z","end":"2026-04-20T10:14:47.524208Z","steps":["trace[1234199883] 'agreement among raft nodes before linearized reading' (duration: 2.434465486s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:47.524288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:45.089016Z","time spent":"2.435259366s","remote":"127.0.0.1:36070","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-04-20T10:14:47.523692Z","caller":"traceutil/trace.go:171","msg":"trace[1057341061] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1455; }","duration":"2.193588302s","start":"2026-04-20T10:14:45.330085Z","end":"2026-04-20T10:14:47.523673Z","steps":["trace[1057341061] 'agreement among raft nodes before linearized reading' (duration: 2.1934492s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:47.524407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:45.330064Z","time spent":"2.19431685s","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:14:47.525891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.582434541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-db5l9\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-04-20T10:14:47.52594Z","caller":"traceutil/trace.go:171","msg":"trace[1168839837] range","detail":"{range_begin:/registry/pods/kube-system/cilium-db5l9; range_end:; response_count:1; response_revision:1455; }","duration":"2.582505113s","start":"2026-04-20T10:14:44.943423Z","end":"2026-04-20T10:14:47.525928Z","steps":["trace[1168839837] 'agreement among raft nodes before linearized reading' (duration: 2.580039102s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:14:47.525968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:14:44.943414Z","time spent":"2.582547293s","remote":"127.0.0.1:35982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18548,"request content":"key:\"/registry/pods/kube-system/cilium-db5l9\" "}