{"level":"warn","ts":"2026-05-09T16:51:05.116479Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:51:05.116572Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-05-09T16:51:05.116641Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:51:05.116656Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-09T16:51:05.116675Z","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-05-09T16:51:05.117315Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-05-09T16:51:05.117392Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-05-09T16:51:05.240024Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"122.433716ms"} {"level":"info","ts":"2026-05-09T16:51:05.298888Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-05-09T16:51:05.299016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T16:51:05.299193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-05-09T16:51:05.299281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T16:51:05.299292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-05-09T16:51:05.299492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-05-09T16:51:05.339382Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T16:51:05.349167Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T16:51:05.364916Z","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-05-09T16:51:05.386563Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T16:51:05.387228Z","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-05-09T16:51:05.38784Z","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-05-09T16:51:05.387871Z","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-05-09T16:51:05.388183Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T16:51:05.393618Z","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-05-09T16:51:05.393717Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-09T16:51:05.393756Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-09T16:51:05.393933Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T16:51:05.39396Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T16:51:05.395595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-05-09T16:51:05.395836Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-09T16:51:05.900713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T16:51:05.900777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T16:51:05.900824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-05-09T16:51:05.900845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-05-09T16:51:05.900908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-09T16:51:05.900955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-05-09T16:51:05.901023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"warn","ts":"2026-05-09T16:51:07.212352Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.311168245s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T16:51:07.212878Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:51:08.026848Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:51:08.026843Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T16:51:08.026869Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:51:08.027314Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T16:51:08.027408Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T16:51:08.030168Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T16:51:08.030581Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-05-09T16:51:08.107241Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:51:08.107629Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:51:08.107824Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:51:10.452818Z","caller":"traceutil/trace.go:171","msg":"trace[1001414511] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"111.293385ms","start":"2026-05-09T16:51:10.341476Z","end":"2026-05-09T16:51:10.452769Z","steps":["trace[1001414511] 'process raft request' (duration: 52.780761ms)","trace[1001414511] 'compare' (duration: 58.279008ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:10.788125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.636867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-09T16:51:10.788339Z","caller":"traceutil/trace.go:171","msg":"trace[1650002177] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"292.711877ms","start":"2026-05-09T16:51:10.495606Z","end":"2026-05-09T16:51:10.788318Z","steps":["trace[1650002177] 'process raft request' (duration: 189.414612ms)","trace[1650002177] 'compare' (duration: 102.486632ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:10.994411Z","caller":"traceutil/trace.go:171","msg":"trace[571073288] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"115.589749ms","start":"2026-05-09T16:51:10.878796Z","end":"2026-05-09T16:51:10.994386Z","steps":["trace[571073288] 'process raft request' (duration: 41.991052ms)","trace[571073288] 'compare' (duration: 73.480883ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:11.699368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.297629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-09T16:51:11.69949Z","caller":"traceutil/trace.go:171","msg":"trace[1382026965] linearizableReadLoop","detail":"{readStateIndex:109; appliedIndex:108; }","duration":"186.871439ms","start":"2026-05-09T16:51:11.5126Z","end":"2026-05-09T16:51:11.699472Z","steps":["trace[1382026965] 'read index received' (duration: 44.291µs)","trace[1382026965] 'applied index is now lower than readState.Index' (duration: 186.825968ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:11.699589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.985593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T16:51:11.69958Z","caller":"traceutil/trace.go:171","msg":"trace[78755438] transaction","detail":"{read_only:false; response_revision:104; number_of_response:1; }","duration":"363.033356ms","start":"2026-05-09T16:51:11.336512Z","end":"2026-05-09T16:51:11.699545Z","steps":["trace[78755438] 'process raft request' (duration: 38.453349ms)","trace[78755438] 'compare' (duration: 324.103074ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:11.699624Z","caller":"traceutil/trace.go:171","msg":"trace[1187162393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:104; }","duration":"187.066975ms","start":"2026-05-09T16:51:11.512548Z","end":"2026-05-09T16:51:11.699615Z","steps":["trace[1187162393] 'agreement among raft nodes before linearized reading' (duration: 186.972403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:11.699729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:11.336496Z","time spent":"363.157769ms","remote":"127.0.0.1:49092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1368,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:51:12.032479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.772144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T16:51:12.032649Z","caller":"traceutil/trace.go:171","msg":"trace[12627962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:106; }","duration":"166.00443ms","start":"2026-05-09T16:51:11.866622Z","end":"2026-05-09T16:51:12.032627Z","steps":["trace[12627962] 'range keys from in-memory index tree' (duration: 165.676101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:12.03248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.209636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:cronjob-controller\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T16:51:12.033297Z","caller":"traceutil/trace.go:171","msg":"trace[1592369302] range","detail":"{range_begin:/registry/clusterroles/system:controller:cronjob-controller; range_end:; response_count:0; response_revision:106; }","duration":"232.070789ms","start":"2026-05-09T16:51:11.801205Z","end":"2026-05-09T16:51:12.033276Z","steps":["trace[1592369302] 'range keys from in-memory index tree' (duration: 231.146444ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:12.164421Z","caller":"traceutil/trace.go:171","msg":"trace[1801886043] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"127.937352ms","start":"2026-05-09T16:51:12.036453Z","end":"2026-05-09T16:51:12.164391Z","steps":["trace[1801886043] 'process raft request' (duration: 123.060836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:13.113138Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410281724105788,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:51:13.485879Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.04580981s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-09T16:51:14.613163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000531098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-09T16:51:14.613283Z","caller":"traceutil/trace.go:171","msg":"trace[801836816] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000718432s","start":"2026-05-09T16:51:12.612529Z","end":"2026-05-09T16:51:14.613248Z","steps":["trace[801836816] 'agreement among raft nodes before linearized reading' (duration: 2.000577249s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:14.61363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:12.612501Z","time spent":"2.001110253s","remote":"127.0.0.1:48746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/09 16:51:14 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-09T16:51:14.852344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365762809s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-09T16:51:14.852426Z","caller":"traceutil/trace.go:171","msg":"trace[481887460] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"2.412421283s","start":"2026-05-09T16:51:12.439992Z","end":"2026-05-09T16:51:14.852413Z","steps":["trace[481887460] 'process raft request' (duration: 1.046541661s)","trace[481887460] 'compare' (duration: 1.365633996s)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:14.852463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:12.439975Z","time spent":"2.412473304s","remote":"127.0.0.1:49092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:51:14.930764Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.444657991s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T16:51:14.931072Z","caller":"traceutil/trace.go:171","msg":"trace[1445484520] linearizableReadLoop","detail":"{readStateIndex:123; appliedIndex:121; }","duration":"2.318455075s","start":"2026-05-09T16:51:12.612592Z","end":"2026-05-09T16:51:14.931047Z","steps":["trace[1445484520] 'read index received' (duration: 873.938689ms)","trace[1445484520] 'applied index is now lower than readState.Index' (duration: 1.444515546s)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:14.931159Z","caller":"traceutil/trace.go:171","msg":"trace[253641311] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"1.955183306s","start":"2026-05-09T16:51:12.975966Z","end":"2026-05-09T16:51:14.931149Z","steps":["trace[253641311] 'process raft request' (duration: 1.955011941s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:14.931239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:12.975948Z","time spent":"1.955241688s","remote":"127.0.0.1:48944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:51:15.281378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.696902ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-05-09T16:51:15.281525Z","caller":"traceutil/trace.go:171","msg":"trace[1673171828] linearizableReadLoop","detail":"{readStateIndex:124; appliedIndex:123; }","duration":"350.378794ms","start":"2026-05-09T16:51:14.931124Z","end":"2026-05-09T16:51:15.281502Z","steps":["trace[1673171828] 'read index received' (duration: 154.534458ms)","trace[1673171828] 'applied index is now lower than readState.Index' (duration: 195.842486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:15.281615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007137817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T16:51:15.281649Z","caller":"traceutil/trace.go:171","msg":"trace[1837817878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:118; }","duration":"2.007207419s","start":"2026-05-09T16:51:13.274426Z","end":"2026-05-09T16:51:15.281634Z","steps":["trace[1837817878] 'agreement among raft nodes before linearized reading' (duration: 2.007132587s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:15.281623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.208548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-09T16:51:15.281691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:13.274404Z","time spent":"2.007277311s","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T16:51:15.281698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.619622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:generic-garbage-collector\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T16:51:15.28172Z","caller":"traceutil/trace.go:171","msg":"trace[1126137528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:118; }","duration":"668.358082ms","start":"2026-05-09T16:51:14.613336Z","end":"2026-05-09T16:51:15.281694Z","steps":["trace[1126137528] 'agreement among raft nodes before linearized reading' (duration: 668.184797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:15.281663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:14.121723Z","time spent":"1.159920046s","remote":"127.0.0.1:48836","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-09T16:51:15.281772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:14.613316Z","time spent":"668.447355ms","remote":"127.0.0.1:48734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T16:51:15.28182Z","caller":"traceutil/trace.go:171","msg":"trace[2007741863] range","detail":"{range_begin:/registry/clusterroles/system:controller:generic-garbage-collector; range_end:; response_count:0; response_revision:118; }","duration":"426.725944ms","start":"2026-05-09T16:51:14.855022Z","end":"2026-05-09T16:51:15.281748Z","steps":["trace[2007741863] 'agreement among raft nodes before linearized reading' (duration: 426.608971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:15.281875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:14.855007Z","time spent":"426.854548ms","remote":"127.0.0.1:49092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:controller:generic-garbage-collector\" "} {"level":"warn","ts":"2026-05-09T16:51:15.281933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097645429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-09T16:51:15.281999Z","caller":"traceutil/trace.go:171","msg":"trace[1523527079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:118; }","duration":"1.097741433s","start":"2026-05-09T16:51:14.184242Z","end":"2026-05-09T16:51:15.281984Z","steps":["trace[1523527079] 'agreement among raft nodes before linearized reading' (duration: 1.097611218s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:15.282018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516973496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-09T16:51:15.28204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:14.184224Z","time spent":"1.097804834s","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T16:51:15.282054Z","caller":"traceutil/trace.go:171","msg":"trace[431377510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:118; }","duration":"1.517007286s","start":"2026-05-09T16:51:13.765036Z","end":"2026-05-09T16:51:15.282044Z","steps":["trace[431377510] 'agreement among raft nodes before linearized reading' (duration: 1.516954075s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:15.282083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:13.764974Z","time spent":"1.517103449s","remote":"127.0.0.1:48746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T16:51:15.587495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.991724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-09T16:51:15.587753Z","caller":"traceutil/trace.go:171","msg":"trace[1347630937] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"301.134368ms","start":"2026-05-09T16:51:15.286582Z","end":"2026-05-09T16:51:15.587716Z","steps":["trace[1347630937] 'process raft request' (duration: 102.839446ms)","trace[1347630937] 'compare' (duration: 197.691985ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:15.587829Z","caller":"traceutil/trace.go:171","msg":"trace[1125201034] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"269.629816ms","start":"2026-05-09T16:51:15.318176Z","end":"2026-05-09T16:51:15.587806Z","steps":["trace[1125201034] 'process raft request' (duration: 269.581134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:15.587956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:15.286554Z","time spent":"301.285673ms","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:51:15.587991Z","caller":"traceutil/trace.go:171","msg":"trace[399685020] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"301.109428ms","start":"2026-05-09T16:51:15.286856Z","end":"2026-05-09T16:51:15.587966Z","steps":["trace[399685020] 'process raft request' (duration: 300.777139ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:15.588103Z","caller":"traceutil/trace.go:171","msg":"trace[759104077] linearizableReadLoop","detail":"{readStateIndex:128; appliedIndex:124; }","duration":"276.127191ms","start":"2026-05-09T16:51:15.311966Z","end":"2026-05-09T16:51:15.588093Z","steps":["trace[759104077] 'read index received' (duration: 1.80585ms)","trace[759104077] 'applied index is now lower than readState.Index' (duration: 274.319981ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:15.58824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.275015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T16:51:15.58829Z","caller":"traceutil/trace.go:171","msg":"trace[1340225098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:123; }","duration":"276.359717ms","start":"2026-05-09T16:51:15.311921Z","end":"2026-05-09T16:51:15.58828Z","steps":["trace[1340225098] 'agreement among raft nodes before linearized reading' (duration: 276.207423ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:15.588321Z","caller":"traceutil/trace.go:171","msg":"trace[299400174] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"290.824969ms","start":"2026-05-09T16:51:15.297484Z","end":"2026-05-09T16:51:15.588309Z","steps":["trace[299400174] 'process raft request' (duration: 290.216523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:15.588386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:15.286844Z","time spent":"301.19931ms","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:51:15.850113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.839161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-09T16:51:15.850328Z","caller":"traceutil/trace.go:171","msg":"trace[654902325] transaction","detail":"{read_only:false; response_revision:125; number_of_response:1; }","duration":"256.993411ms","start":"2026-05-09T16:51:15.593307Z","end":"2026-05-09T16:51:15.850301Z","steps":["trace[654902325] 'process raft request' (duration: 141.867152ms)","trace[654902325] 'compare' (duration: 114.677727ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:15.850438Z","caller":"traceutil/trace.go:171","msg":"trace[53223247] linearizableReadLoop","detail":"{readStateIndex:133; appliedIndex:130; }","duration":"137.604477ms","start":"2026-05-09T16:51:15.712815Z","end":"2026-05-09T16:51:15.850419Z","steps":["trace[53223247] 'read index received' (duration: 22.349285ms)","trace[53223247] 'applied index is now lower than readState.Index' (duration: 115.254182ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:15.850553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.735162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T16:51:15.850588Z","caller":"traceutil/trace.go:171","msg":"trace[678763887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:127; }","duration":"137.808464ms","start":"2026-05-09T16:51:15.71277Z","end":"2026-05-09T16:51:15.850578Z","steps":["trace[678763887] 'agreement among raft nodes before linearized reading' (duration: 137.714851ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:15.850672Z","caller":"traceutil/trace.go:171","msg":"trace[1604576064] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"255.708518ms","start":"2026-05-09T16:51:15.594951Z","end":"2026-05-09T16:51:15.850659Z","steps":["trace[1604576064] 'process raft request' (duration: 255.269655ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:15.850809Z","caller":"traceutil/trace.go:171","msg":"trace[1094933919] transaction","detail":"{read_only:false; response_revision:127; number_of_response:1; }","duration":"209.689641ms","start":"2026-05-09T16:51:15.641109Z","end":"2026-05-09T16:51:15.850799Z","steps":["trace[1094933919] 'process raft request' (duration: 209.267348ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:16.644632Z","caller":"traceutil/trace.go:171","msg":"trace[2089047951] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"119.513813ms","start":"2026-05-09T16:51:16.525084Z","end":"2026-05-09T16:51:16.644598Z","steps":["trace[2089047951] 'process raft request' (duration: 119.281147ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:16.809852Z","caller":"traceutil/trace.go:171","msg":"trace[1641718600] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"105.077297ms","start":"2026-05-09T16:51:16.704752Z","end":"2026-05-09T16:51:16.809829Z","steps":["trace[1641718600] 'process raft request' (duration: 105.021975ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:16.809919Z","caller":"traceutil/trace.go:171","msg":"trace[1601724277] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"112.773932ms","start":"2026-05-09T16:51:16.697121Z","end":"2026-05-09T16:51:16.809895Z","steps":["trace[1601724277] 'process raft request' (duration: 35.543302ms)","trace[1601724277] 'compare' (duration: 76.978174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:19.782553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.207996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:51:19.783087Z","caller":"traceutil/trace.go:171","msg":"trace[1674767525] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"231.463076ms","start":"2026-05-09T16:51:19.551601Z","end":"2026-05-09T16:51:19.783064Z","steps":["trace[1674767525] 'process raft request' (duration: 50.664835ms)","trace[1674767525] 'compare' (duration: 180.046992ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:20.447803Z","caller":"traceutil/trace.go:171","msg":"trace[2085368969] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"882.527722ms","start":"2026-05-09T16:51:19.565237Z","end":"2026-05-09T16:51:20.447765Z","steps":["trace[2085368969] 'process raft request' (duration: 861.773308ms)","trace[2085368969] 'compare' (duration: 20.466575ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:20.447859Z","caller":"traceutil/trace.go:171","msg":"trace[1975772480] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"882.15546ms","start":"2026-05-09T16:51:19.565675Z","end":"2026-05-09T16:51:20.447831Z","steps":["trace[1975772480] 'process raft request' (duration: 881.950354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:20.447974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:19.565653Z","time spent":"882.260274ms","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1934,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:51:20.447983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:19.565196Z","time spent":"882.716396ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:51:20.44791Z","caller":"traceutil/trace.go:171","msg":"trace[1805248061] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:309; }","duration":"847.439701ms","start":"2026-05-09T16:51:19.600448Z","end":"2026-05-09T16:51:20.447888Z","steps":["trace[1805248061] 'read index received' (duration: 1.89292ms)","trace[1805248061] 'applied index is now lower than readState.Index' (duration: 845.545891ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:20.448124Z","caller":"traceutil/trace.go:171","msg":"trace[617897941] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"871.31651ms","start":"2026-05-09T16:51:19.576797Z","end":"2026-05-09T16:51:20.448113Z","steps":["trace[617897941] 'process raft request' (duration: 871.027682ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:20.44813Z","caller":"traceutil/trace.go:171","msg":"trace[2047258018] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"871.32142ms","start":"2026-05-09T16:51:19.576796Z","end":"2026-05-09T16:51:20.448117Z","steps":["trace[2047258018] 'process raft request' (duration: 871.009542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:20.448197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:19.576782Z","time spent":"871.385331ms","remote":"127.0.0.1:48944","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:51:20.448221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:19.576777Z","time spent":"871.385361ms","remote":"127.0.0.1:48944","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:51:20.448378Z","caller":"traceutil/trace.go:171","msg":"trace[1859583793] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"870.404556ms","start":"2026-05-09T16:51:19.577964Z","end":"2026-05-09T16:51:20.448369Z","steps":["trace[1859583793] 'process raft request' (duration: 869.878603ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:20.448398Z","caller":"traceutil/trace.go:171","msg":"trace[933099904] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"871.73601ms","start":"2026-05-09T16:51:19.576646Z","end":"2026-05-09T16:51:20.448382Z","steps":["trace[933099904] 'process raft request' (duration: 871.124995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:20.448425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:19.577956Z","time spent":"870.450207ms","remote":"127.0.0.1:48944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:51:20.448486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:19.576635Z","time spent":"871.813632ms","remote":"127.0.0.1:48944","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:51:20.448498Z","caller":"traceutil/trace.go:171","msg":"trace[431731550] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"872.173872ms","start":"2026-05-09T16:51:19.576317Z","end":"2026-05-09T16:51:20.448491Z","steps":["trace[431731550] 'process raft request' (duration: 871.431243ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:20.448491Z","caller":"traceutil/trace.go:171","msg":"trace[479417496] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"882.782127ms","start":"2026-05-09T16:51:19.565693Z","end":"2026-05-09T16:51:20.448475Z","steps":["trace[479417496] 'process raft request' (duration: 882.013356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:20.448508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.049836ms","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-05-09T16:51:20.448535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:19.5763Z","time spent":"872.217553ms","remote":"127.0.0.1:48944","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:51:20.448548Z","caller":"traceutil/trace.go:171","msg":"trace[481109627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:306; }","duration":"848.122247ms","start":"2026-05-09T16:51:19.600412Z","end":"2026-05-09T16:51:20.448535Z","steps":["trace[481109627] 'agreement among raft nodes before linearized reading' (duration: 847.994604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:20.448581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:19.600399Z","time spent":"848.173659ms","remote":"127.0.0.1:49018","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-05-09T16:51:20.448573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:19.565668Z","time spent":"882.868788ms","remote":"127.0.0.1:48936","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:51:20.453866Z","caller":"traceutil/trace.go:171","msg":"trace[831178125] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"752.637848ms","start":"2026-05-09T16:51:19.70121Z","end":"2026-05-09T16:51:20.453848Z","steps":["trace[831178125] 'process raft request' (duration: 752.413752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:20.453968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:19.701184Z","time spent":"752.734681ms","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:51:20.454132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.493339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-09T16:51:20.454169Z","caller":"traceutil/trace.go:171","msg":"trace[719630438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:307; }","duration":"283.566591ms","start":"2026-05-09T16:51:20.170593Z","end":"2026-05-09T16:51:20.45416Z","steps":["trace[719630438] 'agreement among raft nodes before linearized reading' (duration: 283.503969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:20.454471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.270359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:51:20.45459Z","caller":"traceutil/trace.go:171","msg":"trace[164512530] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:307; }","duration":"668.376422ms","start":"2026-05-09T16:51:19.786151Z","end":"2026-05-09T16:51:20.454528Z","steps":["trace[164512530] 'agreement among raft nodes before linearized reading' (duration: 668.265849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:20.454644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:19.786137Z","time spent":"668.492655ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" "} {"level":"warn","ts":"2026-05-09T16:51:20.454855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.738192ms","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-05-09T16:51:20.454958Z","caller":"traceutil/trace.go:171","msg":"trace[293292423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:307; }","duration":"586.891226ms","start":"2026-05-09T16:51:19.86804Z","end":"2026-05-09T16:51:20.454931Z","steps":["trace[293292423] 'agreement among raft nodes before linearized reading' (duration: 586.295691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:20.455135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:19.868033Z","time spent":"587.085301ms","remote":"127.0.0.1:49018","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-05-09T16:51:21.05081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.910624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:51:21.050969Z","caller":"traceutil/trace.go:171","msg":"trace[719555813] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"276.61569ms","start":"2026-05-09T16:51:20.774329Z","end":"2026-05-09T16:51:21.050945Z","steps":["trace[719555813] 'read index received' (duration: 174.430319ms)","trace[719555813] 'applied index is now lower than readState.Index' (duration: 102.183541ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:21.051098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.760074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-09T16:51:21.051131Z","caller":"traceutil/trace.go:171","msg":"trace[837292401] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:329; }","duration":"276.800555ms","start":"2026-05-09T16:51:20.774314Z","end":"2026-05-09T16:51:21.051115Z","steps":["trace[837292401] 'agreement among raft nodes before linearized reading' (duration: 276.711162ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:21.051093Z","caller":"traceutil/trace.go:171","msg":"trace[1844991880] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"276.956949ms","start":"2026-05-09T16:51:20.774082Z","end":"2026-05-09T16:51:21.051039Z","steps":["trace[1844991880] 'process raft request' (duration: 174.675105ms)","trace[1844991880] 'compare' (duration: 101.73472ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:21.2865Z","caller":"traceutil/trace.go:171","msg":"trace[1311159108] linearizableReadLoop","detail":"{readStateIndex:343; appliedIndex:342; }","duration":"199.427076ms","start":"2026-05-09T16:51:21.087046Z","end":"2026-05-09T16:51:21.286473Z","steps":["trace[1311159108] 'read index received' (duration: 102.099903ms)","trace[1311159108] 'applied index is now lower than readState.Index' (duration: 97.325943ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:21.286647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.569111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-09T16:51:21.286638Z","caller":"traceutil/trace.go:171","msg":"trace[1021445140] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"201.323365ms","start":"2026-05-09T16:51:21.085283Z","end":"2026-05-09T16:51:21.286606Z","steps":["trace[1021445140] 'process raft request' (duration: 103.858238ms)","trace[1021445140] 'compare' (duration: 97.1956ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:21.286692Z","caller":"traceutil/trace.go:171","msg":"trace[274063727] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:331; }","duration":"199.669703ms","start":"2026-05-09T16:51:21.08701Z","end":"2026-05-09T16:51:21.28668Z","steps":["trace[274063727] 'agreement among raft nodes before linearized reading' (duration: 199.55617ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:21.557729Z","caller":"traceutil/trace.go:171","msg":"trace[65189787] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"179.435929ms","start":"2026-05-09T16:51:21.378265Z","end":"2026-05-09T16:51:21.557701Z","steps":["trace[65189787] 'process raft request' (duration: 139.424512ms)","trace[65189787] 'compare' (duration: 39.871873ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:21.560545Z","caller":"traceutil/trace.go:171","msg":"trace[676746794] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"178.005573ms","start":"2026-05-09T16:51:21.382523Z","end":"2026-05-09T16:51:21.560529Z","steps":["trace[676746794] 'process raft request' (duration: 177.92045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:22.683864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.774774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:51:22.68398Z","caller":"traceutil/trace.go:171","msg":"trace[830110471] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"180.57743ms","start":"2026-05-09T16:51:22.503383Z","end":"2026-05-09T16:51:22.683961Z","steps":["trace[830110471] 'process raft request' (duration: 77.603251ms)","trace[830110471] 'compare' (duration: 102.640001ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:22.684314Z","caller":"traceutil/trace.go:171","msg":"trace[577100725] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:356; }","duration":"134.407089ms","start":"2026-05-09T16:51:22.549879Z","end":"2026-05-09T16:51:22.684286Z","steps":["trace[577100725] 'read index received' (duration: 31.114512ms)","trace[577100725] 'applied index is now lower than readState.Index' (duration: 103.291327ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:22.684565Z","caller":"traceutil/trace.go:171","msg":"trace[1249942210] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"177.412961ms","start":"2026-05-09T16:51:22.507141Z","end":"2026-05-09T16:51:22.684554Z","steps":["trace[1249942210] 'process raft request' (duration: 176.999211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:22.68475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.816899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-09T16:51:22.684858Z","caller":"traceutil/trace.go:171","msg":"trace[978904441] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:346; }","duration":"134.980653ms","start":"2026-05-09T16:51:22.549862Z","end":"2026-05-09T16:51:22.684842Z","steps":["trace[978904441] 'agreement among raft nodes before linearized reading' (duration: 134.710096ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:22.862037Z","caller":"traceutil/trace.go:171","msg":"trace[1045930516] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"106.19396ms","start":"2026-05-09T16:51:22.755824Z","end":"2026-05-09T16:51:22.862018Z","steps":["trace[1045930516] 'process raft request' (duration: 96.460236ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.22688Z","caller":"traceutil/trace.go:171","msg":"trace[860877172] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"201.817871ms","start":"2026-05-09T16:51:23.025042Z","end":"2026-05-09T16:51:23.22686Z","steps":["trace[860877172] 'read index received' (duration: 201.657317ms)","trace[860877172] 'applied index is now lower than readState.Index' (duration: 159.384µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:23.226968Z","caller":"traceutil/trace.go:171","msg":"trace[423750545] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"218.561918ms","start":"2026-05-09T16:51:23.008381Z","end":"2026-05-09T16:51:23.226942Z","steps":["trace[423750545] 'process raft request' (duration: 218.367114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.227207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.914133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"warn","ts":"2026-05-09T16:51:23.227484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.408219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-09T16:51:23.227525Z","caller":"traceutil/trace.go:171","msg":"trace[816174070] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:350; }","duration":"202.199941ms","start":"2026-05-09T16:51:23.025263Z","end":"2026-05-09T16:51:23.227463Z","steps":["trace[816174070] 'agreement among raft nodes before linearized reading' (duration: 201.877443ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.227548Z","caller":"traceutil/trace.go:171","msg":"trace[1386735024] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:350; }","duration":"176.518892ms","start":"2026-05-09T16:51:23.05101Z","end":"2026-05-09T16:51:23.227529Z","steps":["trace[1386735024] 'agreement among raft nodes before linearized reading' (duration: 176.374828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.227202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.135989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-05-09T16:51:23.227627Z","caller":"traceutil/trace.go:171","msg":"trace[2129754929] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:350; }","duration":"202.611931ms","start":"2026-05-09T16:51:23.024998Z","end":"2026-05-09T16:51:23.227609Z","steps":["trace[2129754929] 'agreement among raft nodes before linearized reading' (duration: 201.972885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.227368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.196831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-09T16:51:23.227711Z","caller":"traceutil/trace.go:171","msg":"trace[2099938078] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:350; }","duration":"126.558699ms","start":"2026-05-09T16:51:23.101132Z","end":"2026-05-09T16:51:23.227691Z","steps":["trace[2099938078] 'agreement among raft nodes before linearized reading' (duration: 126.162749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.5866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.841477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:51:23.586711Z","caller":"traceutil/trace.go:171","msg":"trace[912306302] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:362; }","duration":"354.899311ms","start":"2026-05-09T16:51:23.231791Z","end":"2026-05-09T16:51:23.586691Z","steps":["trace[912306302] 'read index received' (duration: 61.9091ms)","trace[912306302] 'applied index is now lower than readState.Index' (duration: 292.988181ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:23.586832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.031964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-05-09T16:51:23.586777Z","caller":"traceutil/trace.go:171","msg":"trace[1724489854] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"355.022814ms","start":"2026-05-09T16:51:23.231674Z","end":"2026-05-09T16:51:23.586696Z","steps":["trace[1724489854] 'process raft request' (duration: 62.005143ms)","trace[1724489854] 'compare' (duration: 292.719504ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:23.586867Z","caller":"traceutil/trace.go:171","msg":"trace[1387381122] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:351; }","duration":"355.098916ms","start":"2026-05-09T16:51:23.231759Z","end":"2026-05-09T16:51:23.586858Z","steps":["trace[1387381122] 'agreement among raft nodes before linearized reading' (duration: 355.006614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.586903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:23.231745Z","time spent":"355.150587ms","remote":"127.0.0.1:48936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-09T16:51:23.587041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:23.231652Z","time spent":"355.269019ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:51:23.594818Z","caller":"traceutil/trace.go:171","msg":"trace[860593771] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"357.742751ms","start":"2026-05-09T16:51:23.237053Z","end":"2026-05-09T16:51:23.594796Z","steps":["trace[860593771] 'process raft request' (duration: 357.645999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.594977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:23.237047Z","time spent":"357.847924ms","remote":"127.0.0.1:49014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1247,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:23.595082Z","caller":"traceutil/trace.go:171","msg":"trace[1316191391] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"357.945946ms","start":"2026-05-09T16:51:23.237117Z","end":"2026-05-09T16:51:23.595063Z","steps":["trace[1316191391] 'process raft request' (duration: 357.628368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.595211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:23.237112Z","time spent":"358.026538ms","remote":"127.0.0.1:49092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:51:23.595397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.296117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-09T16:51:23.595456Z","caller":"traceutil/trace.go:171","msg":"trace[1525614152] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"358.059249ms","start":"2026-05-09T16:51:23.237383Z","end":"2026-05-09T16:51:23.595442Z","steps":["trace[1525614152] 'process raft request' (duration: 357.711691ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.595473Z","caller":"traceutil/trace.go:171","msg":"trace[861520113] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:357; }","duration":"344.394239ms","start":"2026-05-09T16:51:23.251058Z","end":"2026-05-09T16:51:23.595452Z","steps":["trace[861520113] 'agreement among raft nodes before linearized reading' (duration: 344.128592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.595516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:23.25105Z","time spent":"344.457871ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-05-09T16:51:23.595523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:23.237378Z","time spent":"358.11054ms","remote":"127.0.0.1:49092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:23.595563Z","caller":"traceutil/trace.go:171","msg":"trace[1820078466] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"357.882255ms","start":"2026-05-09T16:51:23.237665Z","end":"2026-05-09T16:51:23.595547Z","steps":["trace[1820078466] 'process raft request' (duration: 357.476765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.595614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.276926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T16:51:23.595648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:23.237633Z","time spent":"357.969507ms","remote":"127.0.0.1:49092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:23.595657Z","caller":"traceutil/trace.go:171","msg":"trace[1415322266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:357; }","duration":"129.344878ms","start":"2026-05-09T16:51:23.4663Z","end":"2026-05-09T16:51:23.595645Z","steps":["trace[1415322266] 'agreement among raft nodes before linearized reading' (duration: 129.295897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.595771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.296053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-09T16:51:23.595818Z","caller":"traceutil/trace.go:171","msg":"trace[1841012739] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:357; }","duration":"243.382045ms","start":"2026-05-09T16:51:23.352425Z","end":"2026-05-09T16:51:23.595807Z","steps":["trace[1841012739] 'agreement among raft nodes before linearized reading' (duration: 243.309673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.595568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.174561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-09T16:51:23.595914Z","caller":"traceutil/trace.go:171","msg":"trace[1519120577] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:357; }","duration":"194.556651ms","start":"2026-05-09T16:51:23.401346Z","end":"2026-05-09T16:51:23.595903Z","steps":["trace[1519120577] 'agreement among raft nodes before linearized reading' (duration: 194.174621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.595959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.452506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-09T16:51:23.595999Z","caller":"traceutil/trace.go:171","msg":"trace[441864070] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:357; }","duration":"294.521588ms","start":"2026-05-09T16:51:23.301468Z","end":"2026-05-09T16:51:23.59599Z","steps":["trace[441864070] 'agreement among raft nodes before linearized reading' (duration: 294.461506ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.595202Z","caller":"traceutil/trace.go:171","msg":"trace[1845345113] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"357.831823ms","start":"2026-05-09T16:51:23.237354Z","end":"2026-05-09T16:51:23.595186Z","steps":["trace[1845345113] 'process raft request' (duration: 357.624698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.59906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:23.237346Z","time spent":"359.319991ms","remote":"127.0.0.1:48936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:23.594945Z","caller":"traceutil/trace.go:171","msg":"trace[1319959967] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"359.382842ms","start":"2026-05-09T16:51:23.235545Z","end":"2026-05-09T16:51:23.594928Z","steps":["trace[1319959967] 'process raft request' (duration: 359.040003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.599873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:23.235534Z","time spent":"364.273113ms","remote":"127.0.0.1:49030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:51:23.776058Z","caller":"traceutil/trace.go:171","msg":"trace[913126685] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"173.191119ms","start":"2026-05-09T16:51:23.602807Z","end":"2026-05-09T16:51:23.775998Z","steps":["trace[913126685] 'process raft request' (duration: 144.795752ms)","trace[913126685] 'compare' (duration: 27.901794ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:23.776074Z","caller":"traceutil/trace.go:171","msg":"trace[1937379745] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"171.906368ms","start":"2026-05-09T16:51:23.604126Z","end":"2026-05-09T16:51:23.776032Z","steps":["trace[1937379745] 'process raft request' (duration: 171.736134ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.776149Z","caller":"traceutil/trace.go:171","msg":"trace[840556024] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"152.913865ms","start":"2026-05-09T16:51:23.623214Z","end":"2026-05-09T16:51:23.776128Z","steps":["trace[840556024] 'process raft request' (duration: 152.875935ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.776339Z","caller":"traceutil/trace.go:171","msg":"trace[168914022] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"172.181404ms","start":"2026-05-09T16:51:23.604136Z","end":"2026-05-09T16:51:23.776318Z","steps":["trace[168914022] 'process raft request' (duration: 171.766394ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.776479Z","caller":"traceutil/trace.go:171","msg":"trace[1149511782] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:370; }","duration":"155.294295ms","start":"2026-05-09T16:51:23.62117Z","end":"2026-05-09T16:51:23.776465Z","steps":["trace[1149511782] 'read index received' (duration: 126.443176ms)","trace[1149511782] 'applied index is now lower than readState.Index' (duration: 28.849959ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:23.776467Z","caller":"traceutil/trace.go:171","msg":"trace[168387137] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"169.5874ms","start":"2026-05-09T16:51:23.606866Z","end":"2026-05-09T16:51:23.776453Z","steps":["trace[168387137] 'process raft request' (duration: 169.112229ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.776513Z","caller":"traceutil/trace.go:171","msg":"trace[589295746] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"171.720553ms","start":"2026-05-09T16:51:23.604745Z","end":"2026-05-09T16:51:23.776466Z","steps":["trace[589295746] 'process raft request' (duration: 171.19399ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.776246Z","caller":"traceutil/trace.go:171","msg":"trace[1432062619] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"172.385549ms","start":"2026-05-09T16:51:23.60385Z","end":"2026-05-09T16:51:23.776236Z","steps":["trace[1432062619] 'process raft request' (duration: 171.974819ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.776673Z","caller":"traceutil/trace.go:171","msg":"trace[1034813996] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"168.996545ms","start":"2026-05-09T16:51:23.607656Z","end":"2026-05-09T16:51:23.776653Z","steps":["trace[1034813996] 'process raft request' (duration: 168.391761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.776749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.748439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-05-09T16:51:23.776808Z","caller":"traceutil/trace.go:171","msg":"trace[1176991564] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:367; }","duration":"172.851821ms","start":"2026-05-09T16:51:23.603938Z","end":"2026-05-09T16:51:23.776789Z","steps":["trace[1176991564] 'agreement among raft nodes before linearized reading' (duration: 172.709897ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.776295Z","caller":"traceutil/trace.go:171","msg":"trace[643942252] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"173.089496ms","start":"2026-05-09T16:51:23.603195Z","end":"2026-05-09T16:51:23.776284Z","steps":["trace[643942252] 'process raft request' (duration: 172.560762ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.962145Z","caller":"traceutil/trace.go:171","msg":"trace[916905776] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"178.871311ms","start":"2026-05-09T16:51:23.783243Z","end":"2026-05-09T16:51:23.962115Z","steps":["trace[916905776] 'process raft request' (duration: 150.091385ms)","trace[916905776] 'compare' (duration: 28.432487ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:23.962373Z","caller":"traceutil/trace.go:171","msg":"trace[211134818] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:379; }","duration":"178.018039ms","start":"2026-05-09T16:51:23.784178Z","end":"2026-05-09T16:51:23.962196Z","steps":["trace[211134818] 'read index received' (duration: 66.714159ms)","trace[211134818] 'applied index is now lower than readState.Index' (duration: 111.30282ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:23.962618Z","caller":"traceutil/trace.go:171","msg":"trace[1469378110] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"178.702126ms","start":"2026-05-09T16:51:23.783889Z","end":"2026-05-09T16:51:23.962591Z","steps":["trace[1469378110] 'process raft request' (duration: 178.05004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:23.962658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.451739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-09T16:51:23.962764Z","caller":"traceutil/trace.go:171","msg":"trace[1978032200] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"176.563613ms","start":"2026-05-09T16:51:23.78618Z","end":"2026-05-09T16:51:23.962744Z","steps":["trace[1978032200] 'process raft request' (duration: 175.878336ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.962835Z","caller":"traceutil/trace.go:171","msg":"trace[1026467585] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"178.254905ms","start":"2026-05-09T16:51:23.784563Z","end":"2026-05-09T16:51:23.962818Z","steps":["trace[1026467585] 'process raft request' (duration: 177.418524ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.962856Z","caller":"traceutil/trace.go:171","msg":"trace[583246516] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:373; }","duration":"178.636115ms","start":"2026-05-09T16:51:23.784161Z","end":"2026-05-09T16:51:23.962797Z","steps":["trace[583246516] 'agreement among raft nodes before linearized reading' (duration: 178.338158ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:23.963198Z","caller":"traceutil/trace.go:171","msg":"trace[1322236998] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"176.574614ms","start":"2026-05-09T16:51:23.786602Z","end":"2026-05-09T16:51:23.963176Z","steps":["trace[1322236998] 'process raft request' (duration: 175.530348ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:24.8557Z","caller":"traceutil/trace.go:171","msg":"trace[1773617281] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:397; }","duration":"352.314645ms","start":"2026-05-09T16:51:24.503363Z","end":"2026-05-09T16:51:24.855677Z","steps":["trace[1773617281] 'read index received' (duration: 352.140041ms)","trace[1773617281] 'applied index is now lower than readState.Index' (duration: 173.614µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:24.855808Z","caller":"traceutil/trace.go:171","msg":"trace[391514718] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"368.998016ms","start":"2026-05-09T16:51:24.486801Z","end":"2026-05-09T16:51:24.855799Z","steps":["trace[391514718] 'process raft request' (duration: 368.73367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:24.855888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.964931ms","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-05-09T16:51:24.855903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:24.486771Z","time spent":"369.061928ms","remote":"127.0.0.1:49018","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-05-09T16:51:24.855921Z","caller":"traceutil/trace.go:171","msg":"trace[1816755298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:386; }","duration":"351.043963ms","start":"2026-05-09T16:51:24.50487Z","end":"2026-05-09T16:51:24.855914Z","steps":["trace[1816755298] 'agreement among raft nodes before linearized reading' (duration: 350.984432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:24.855939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:24.504853Z","time spent":"351.082515ms","remote":"127.0.0.1:49018","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-05-09T16:51:24.856043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.178531ms","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-05-09T16:51:24.856057Z","caller":"traceutil/trace.go:171","msg":"trace[53601818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:386; }","duration":"168.230642ms","start":"2026-05-09T16:51:24.687821Z","end":"2026-05-09T16:51:24.856052Z","steps":["trace[53601818] 'agreement among raft nodes before linearized reading' (duration: 168.203962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:24.856042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.672544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-09T16:51:24.85609Z","caller":"traceutil/trace.go:171","msg":"trace[1858768583] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:386; }","duration":"352.796947ms","start":"2026-05-09T16:51:24.503279Z","end":"2026-05-09T16:51:24.856076Z","steps":["trace[1858768583] 'agreement among raft nodes before linearized reading' (duration: 352.712505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:24.856141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:24.503257Z","time spent":"352.876458ms","remote":"127.0.0.1:48944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-09T16:51:25.85833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410281724106336,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:51:26.291712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.333311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:51:26.291864Z","caller":"traceutil/trace.go:171","msg":"trace[732140191] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"1.429936133s","start":"2026-05-09T16:51:24.861905Z","end":"2026-05-09T16:51:26.291841Z","steps":["trace[732140191] 'process raft request' (duration: 467.378626ms)","trace[732140191] 'compare' (duration: 961.388458ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:26.291935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:24.861884Z","time spent":"1.430021064s","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:51:26.359101Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410281724106336,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:51:26.703024Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.373731304s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-09T16:51:27.469873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999287873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-09T16:51:27.469956Z","caller":"traceutil/trace.go:171","msg":"trace[1146261917] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999380115s","start":"2026-05-09T16:51:25.470555Z","end":"2026-05-09T16:51:27.469935Z","steps":["trace[1146261917] 'agreement among raft nodes before linearized reading' (duration: 1.999273412s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:27.470004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:25.470503Z","time spent":"1.999491687s","remote":"127.0.0.1:48746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/09 16:51:27 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-09T16:51:28.142423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.499002ms","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-05-09T16:51:28.142546Z","caller":"traceutil/trace.go:171","msg":"trace[1332561493] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:387; }","duration":"308.657406ms","start":"2026-05-09T16:51:27.833873Z","end":"2026-05-09T16:51:28.14253Z","steps":["trace[1332561493] 'range keys from in-memory index tree' (duration: 308.475962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:28.142643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439316544s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:51:28.14285Z","caller":"traceutil/trace.go:171","msg":"trace[1388315021] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:399; }","duration":"2.785409184s","start":"2026-05-09T16:51:25.357429Z","end":"2026-05-09T16:51:28.142838Z","steps":["trace[1388315021] 'read index received' (duration: 1.345734511s)","trace[1388315021] 'applied index is now lower than readState.Index' (duration: 1.439673923s)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:28.142853Z","caller":"traceutil/trace.go:171","msg":"trace[2074234875] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"3.28030084s","start":"2026-05-09T16:51:24.862535Z","end":"2026-05-09T16:51:28.142835Z","steps":["trace[2074234875] 'process raft request' (duration: 1.840733691s)","trace[2074234875] 'compare' (duration: 1.43916884s)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:28.142943Z","caller":"traceutil/trace.go:171","msg":"trace[742513616] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"3.278099605s","start":"2026-05-09T16:51:24.864834Z","end":"2026-05-09T16:51:28.142934Z","steps":["trace[742513616] 'process raft request' (duration: 3.27788324s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:28.14301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:24.864824Z","time spent":"3.278150227s","remote":"127.0.0.1:48944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:51:28.143035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282142062s","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-05-09T16:51:28.14307Z","caller":"traceutil/trace.go:171","msg":"trace[1044780914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:389; }","duration":"1.282220304s","start":"2026-05-09T16:51:26.860841Z","end":"2026-05-09T16:51:28.143061Z","steps":["trace[1044780914] 'agreement among raft nodes before linearized reading' (duration: 1.282110472s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:28.143108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.482112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:51:28.143137Z","caller":"traceutil/trace.go:171","msg":"trace[895622147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:389; }","duration":"672.542465ms","start":"2026-05-09T16:51:27.470586Z","end":"2026-05-09T16:51:28.143128Z","steps":["trace[895622147] 'agreement among raft nodes before linearized reading' (duration: 672.497393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:28.143159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:27.470568Z","time spent":"672.585395ms","remote":"127.0.0.1:48734","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-05-09T16:51:28.143217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.272946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-09T16:51:28.143305Z","caller":"traceutil/trace.go:171","msg":"trace[1007433371] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:389; }","duration":"429.375588ms","start":"2026-05-09T16:51:27.713908Z","end":"2026-05-09T16:51:28.143284Z","steps":["trace[1007433371] 'agreement among raft nodes before linearized reading' (duration: 429.156672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:28.14336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.785923427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T16:51:28.143394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:27.713848Z","time spent":"429.535643ms","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"info","ts":"2026-05-09T16:51:28.143383Z","caller":"traceutil/trace.go:171","msg":"trace[915034803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:389; }","duration":"2.785993709s","start":"2026-05-09T16:51:25.357382Z","end":"2026-05-09T16:51:28.143376Z","steps":["trace[915034803] 'agreement among raft nodes before linearized reading' (duration: 2.785927067s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:28.143446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:25.357355Z","time spent":"2.786085931s","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T16:51:28.14314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:26.86082Z","time spent":"1.282310807s","remote":"127.0.0.1:49018","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-05-09T16:51:28.144609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:24.862481Z","time spent":"3.282039672s","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:51:28.544639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:28.144328Z","time spent":"400.30292ms","remote":"127.0.0.1:48786","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-09T16:51:28.545102Z","caller":"traceutil/trace.go:171","msg":"trace[1784685382] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"396.724106ms","start":"2026-05-09T16:51:28.148343Z","end":"2026-05-09T16:51:28.545067Z","steps":["trace[1784685382] 'process raft request' (duration: 396.391809ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:28.545281Z","caller":"traceutil/trace.go:171","msg":"trace[595215688] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"396.790138ms","start":"2026-05-09T16:51:28.148429Z","end":"2026-05-09T16:51:28.545219Z","steps":["trace[595215688] 'process raft request' (duration: 396.468291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:28.545322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:28.148311Z","time spent":"396.893461ms","remote":"127.0.0.1:49018","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-05-09T16:51:28.545409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:28.148396Z","time spent":"396.934822ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:51:28.545885Z","caller":"traceutil/trace.go:171","msg":"trace[1843442139] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"397.388622ms","start":"2026-05-09T16:51:28.148482Z","end":"2026-05-09T16:51:28.545871Z","steps":["trace[1843442139] 'process raft request' (duration: 396.663605ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:28.545905Z","caller":"traceutil/trace.go:171","msg":"trace[821817013] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:401; }","duration":"397.487894ms","start":"2026-05-09T16:51:28.148378Z","end":"2026-05-09T16:51:28.545866Z","steps":["trace[821817013] 'read index received' (duration: 320.989816ms)","trace[821817013] 'applied index is now lower than readState.Index' (duration: 76.493208ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:28.546049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.662658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6964"} {"level":"warn","ts":"2026-05-09T16:51:28.546211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.441521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:51:28.546254Z","caller":"traceutil/trace.go:171","msg":"trace[1118312538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:392; }","duration":"394.509683ms","start":"2026-05-09T16:51:28.151731Z","end":"2026-05-09T16:51:28.546241Z","steps":["trace[1118312538] 'agreement among raft nodes before linearized reading' (duration: 394.457422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:28.546288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:28.151714Z","time spent":"394.567645ms","remote":"127.0.0.1:48746","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-05-09T16:51:28.546333Z","caller":"traceutil/trace.go:171","msg":"trace[2020367628] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:392; }","duration":"397.900683ms","start":"2026-05-09T16:51:28.148319Z","end":"2026-05-09T16:51:28.54622Z","steps":["trace[2020367628] 'agreement among raft nodes before linearized reading' (duration: 397.675928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:28.546328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:28.148457Z","time spent":"397.474434ms","remote":"127.0.0.1:49018","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-05-09T16:51:28.546453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.488577ms","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-05-09T16:51:28.54649Z","caller":"traceutil/trace.go:171","msg":"trace[2115002956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:392; }","duration":"249.524357ms","start":"2026-05-09T16:51:28.296956Z","end":"2026-05-09T16:51:28.546481Z","steps":["trace[2115002956] 'agreement among raft nodes before linearized reading' (duration: 249.460566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:28.546661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:28.148292Z","time spent":"398.115699ms","remote":"127.0.0.1:48944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6986,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-05-09T16:51:28.70593Z","caller":"traceutil/trace.go:171","msg":"trace[1924700228] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"145.94664ms","start":"2026-05-09T16:51:28.559929Z","end":"2026-05-09T16:51:28.705875Z","steps":["trace[1924700228] 'process raft request' (duration: 145.709914ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:28.706061Z","caller":"traceutil/trace.go:171","msg":"trace[548726804] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"144.871443ms","start":"2026-05-09T16:51:28.561165Z","end":"2026-05-09T16:51:28.706037Z","steps":["trace[548726804] 'process raft request' (duration: 144.538746ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:28.706028Z","caller":"traceutil/trace.go:171","msg":"trace[1814564099] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"146.706767ms","start":"2026-05-09T16:51:28.559248Z","end":"2026-05-09T16:51:28.705955Z","steps":["trace[1814564099] 'process raft request' (duration: 96.793068ms)","trace[1814564099] 'compare' (duration: 49.439748ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:42.966963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410281724106493,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:51:43.467914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410281724106493,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:51:43.52327Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.224279149s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T16:51:43.523777Z","caller":"traceutil/trace.go:171","msg":"trace[1538006556] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"1.057325256s","start":"2026-05-09T16:51:42.466424Z","end":"2026-05-09T16:51:43.523749Z","steps":["trace[1538006556] 'read index received' (duration: 1.057078391s)","trace[1538006556] 'applied index is now lower than readState.Index' (duration: 245.795µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:43.523844Z","caller":"traceutil/trace.go:171","msg":"trace[1419012900] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"1.224969542s","start":"2026-05-09T16:51:42.298838Z","end":"2026-05-09T16:51:43.523808Z","steps":["trace[1419012900] 'process raft request' (duration: 1.224757678s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:43.524008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.014463ms","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-05-09T16:51:43.524027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:42.298817Z","time spent":"1.225102615s","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:51:43.524017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057555441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:51:43.524057Z","caller":"traceutil/trace.go:171","msg":"trace[1691204602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:470; }","duration":"872.137226ms","start":"2026-05-09T16:51:42.651908Z","end":"2026-05-09T16:51:43.524045Z","steps":["trace[1691204602] 'agreement among raft nodes before linearized reading' (duration: 872.013553ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:43.524099Z","caller":"traceutil/trace.go:171","msg":"trace[871057702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"1.057686633s","start":"2026-05-09T16:51:42.466384Z","end":"2026-05-09T16:51:43.524071Z","steps":["trace[871057702] 'agreement among raft nodes before linearized reading' (duration: 1.0575357s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:43.524105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:42.651885Z","time spent":"872.207997ms","remote":"127.0.0.1:49018","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-05-09T16:51:43.524158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:42.466366Z","time spent":"1.057782125s","remote":"127.0.0.1:48746","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-05-09T16:51:43.524161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.750861ms","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-05-09T16:51:43.52422Z","caller":"traceutil/trace.go:171","msg":"trace[927576175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:470; }","duration":"811.822212ms","start":"2026-05-09T16:51:42.712378Z","end":"2026-05-09T16:51:43.5242Z","steps":["trace[927576175] 'agreement among raft nodes before linearized reading' (duration: 811.72673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:43.524186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.798105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-09T16:51:43.524268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:42.712368Z","time spent":"811.891474ms","remote":"127.0.0.1:49018","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-05-09T16:51:43.524318Z","caller":"traceutil/trace.go:171","msg":"trace[1766256678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:470; }","duration":"784.993909ms","start":"2026-05-09T16:51:42.73929Z","end":"2026-05-09T16:51:43.524284Z","steps":["trace[1766256678] 'agreement among raft nodes before linearized reading' (duration: 784.701043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:43.524385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:42.739285Z","time spent":"785.089691ms","remote":"127.0.0.1:49018","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-05-09T16:51:49.902719Z","caller":"traceutil/trace.go:171","msg":"trace[169855120] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:502; }","duration":"312.851623ms","start":"2026-05-09T16:51:49.589838Z","end":"2026-05-09T16:51:49.90269Z","steps":["trace[169855120] 'read index received' (duration: 312.648549ms)","trace[169855120] 'applied index is now lower than readState.Index' (duration: 201.614µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:49.902961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.080188ms","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-05-09T16:51:49.903073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.756172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T16:51:49.903084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.057875ms","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-05-09T16:51:49.903107Z","caller":"traceutil/trace.go:171","msg":"trace[1446975744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:483; }","duration":"313.263311ms","start":"2026-05-09T16:51:49.589815Z","end":"2026-05-09T16:51:49.903078Z","steps":["trace[1446975744] 'agreement among raft nodes before linearized reading' (duration: 312.984806ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:49.903141Z","caller":"traceutil/trace.go:171","msg":"trace[1699865068] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:483; }","duration":"157.876994ms","start":"2026-05-09T16:51:49.745243Z","end":"2026-05-09T16:51:49.90312Z","steps":["trace[1699865068] 'agreement among raft nodes before linearized reading' (duration: 157.744662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:49.903155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.860864ms","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-05-09T16:51:49.903181Z","caller":"traceutil/trace.go:171","msg":"trace[266518575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:483; }","duration":"301.226468ms","start":"2026-05-09T16:51:49.601927Z","end":"2026-05-09T16:51:49.903154Z","steps":["trace[266518575] 'agreement among raft nodes before linearized reading' (duration: 300.976363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:49.90319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:49.589747Z","time spent":"313.426694ms","remote":"127.0.0.1:49018","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-05-09T16:51:49.903231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:49.601865Z","time spent":"301.35701ms","remote":"127.0.0.1:49018","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-05-09T16:51:49.903227Z","caller":"traceutil/trace.go:171","msg":"trace[1870380891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:483; }","duration":"296.001127ms","start":"2026-05-09T16:51:49.607206Z","end":"2026-05-09T16:51:49.903207Z","steps":["trace[1870380891] 'agreement among raft nodes before linearized reading' (duration: 295.828394ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:49.904962Z","caller":"traceutil/trace.go:171","msg":"trace[1850000450] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"328.505206ms","start":"2026-05-09T16:51:49.574246Z","end":"2026-05-09T16:51:49.902751Z","steps":["trace[1850000450] 'process raft request' (duration: 328.219431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:49.90522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:49.574227Z","time spent":"330.863202ms","remote":"127.0.0.1:49018","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-05-09T16:51:50.012197Z","caller":"traceutil/trace.go:171","msg":"trace[1639666957] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"103.914788ms","start":"2026-05-09T16:51:49.908248Z","end":"2026-05-09T16:51:50.012163Z","steps":["trace[1639666957] 'process raft request' (duration: 80.014328ms)","trace[1639666957] 'compare' (duration: 23.586654ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:50.01242Z","caller":"traceutil/trace.go:171","msg":"trace[1590456432] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:503; }","duration":"103.389548ms","start":"2026-05-09T16:51:49.908991Z","end":"2026-05-09T16:51:50.01238Z","steps":["trace[1590456432] 'read index received' (duration: 44.404839ms)","trace[1590456432] 'applied index is now lower than readState.Index' (duration: 58.982519ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:50.012601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.571712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:51:50.012674Z","caller":"traceutil/trace.go:171","msg":"trace[1676794881] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:0; response_revision:486; }","duration":"103.705354ms","start":"2026-05-09T16:51:49.908948Z","end":"2026-05-09T16:51:50.012653Z","steps":["trace[1676794881] 'agreement among raft nodes before linearized reading' (duration: 103.559952ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:50.012452Z","caller":"traceutil/trace.go:171","msg":"trace[388429249] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"103.379778ms","start":"2026-05-09T16:51:49.909044Z","end":"2026-05-09T16:51:50.012424Z","steps":["trace[388429249] 'process raft request' (duration: 103.063632ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:50.252456Z","caller":"traceutil/trace.go:171","msg":"trace[486865249] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"137.330404ms","start":"2026-05-09T16:51:50.1151Z","end":"2026-05-09T16:51:50.25243Z","steps":["trace[486865249] 'process raft request' (duration: 136.953067ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:50.442671Z","caller":"traceutil/trace.go:171","msg":"trace[588824195] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"108.45248ms","start":"2026-05-09T16:51:50.334179Z","end":"2026-05-09T16:51:50.442631Z","steps":["trace[588824195] 'process raft request' (duration: 108.290427ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:50.442895Z","caller":"traceutil/trace.go:171","msg":"trace[1844972139] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"114.949735ms","start":"2026-05-09T16:51:50.327919Z","end":"2026-05-09T16:51:50.442868Z","steps":["trace[1844972139] 'process raft request' (duration: 86.523439ms)","trace[1844972139] 'compare' (duration: 27.789754ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:50.685221Z","caller":"traceutil/trace.go:171","msg":"trace[1392260037] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"199.957405ms","start":"2026-05-09T16:51:50.485225Z","end":"2026-05-09T16:51:50.685183Z","steps":["trace[1392260037] 'process raft request' (duration: 183.604321ms)","trace[1392260037] 'compare' (duration: 16.17499ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:50.956598Z","caller":"traceutil/trace.go:171","msg":"trace[493464683] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"265.591144ms","start":"2026-05-09T16:51:50.690952Z","end":"2026-05-09T16:51:50.956543Z","steps":["trace[493464683] 'process raft request' (duration: 264.137967ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:53.02567Z","caller":"traceutil/trace.go:171","msg":"trace[1497981315] transaction","detail":"{read_only:false; number_of_response:1; response_revision:548; }","duration":"208.450635ms","start":"2026-05-09T16:51:52.817194Z","end":"2026-05-09T16:51:53.025645Z","steps":["trace[1497981315] 'process raft request' (duration: 193.998055ms)","trace[1497981315] 'compare' (duration: 14.264697ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:53.026099Z","caller":"traceutil/trace.go:171","msg":"trace[1019978733] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"205.125721ms","start":"2026-05-09T16:51:52.820961Z","end":"2026-05-09T16:51:53.026087Z","steps":["trace[1019978733] 'process raft request' (duration: 204.717914ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:53.026287Z","caller":"traceutil/trace.go:171","msg":"trace[1792195577] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"192.952853ms","start":"2026-05-09T16:51:52.833324Z","end":"2026-05-09T16:51:53.026277Z","steps":["trace[1792195577] 'process raft request' (duration: 192.528725ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:53.026485Z","caller":"traceutil/trace.go:171","msg":"trace[1201802880] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"194.9489ms","start":"2026-05-09T16:51:52.831525Z","end":"2026-05-09T16:51:53.026474Z","steps":["trace[1201802880] 'read index received' (duration: 179.624065ms)","trace[1201802880] 'applied index is now lower than readState.Index' (duration: 15.323695ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:53.026659Z","caller":"traceutil/trace.go:171","msg":"trace[1343727290] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"186.307006ms","start":"2026-05-09T16:51:52.840341Z","end":"2026-05-09T16:51:53.026648Z","steps":["trace[1343727290] 'process raft request' (duration: 185.649794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.098058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.501297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:995"} {"level":"info","ts":"2026-05-09T16:51:53.09816Z","caller":"traceutil/trace.go:171","msg":"trace[1479640981] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:551; }","duration":"266.640349ms","start":"2026-05-09T16:51:52.831496Z","end":"2026-05-09T16:51:53.098136Z","steps":["trace[1479640981] 'agreement among raft nodes before linearized reading' (duration: 195.115733ms)","trace[1479640981] 'range keys from in-memory index tree' (duration: 71.295392ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:51:53.098678Z","caller":"traceutil/trace.go:171","msg":"trace[301738135] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"148.734106ms","start":"2026-05-09T16:51:52.949936Z","end":"2026-05-09T16:51:53.09867Z","steps":["trace[301738135] 'process raft request' (duration: 147.507483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.098797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.23543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-05-09T16:51:53.098815Z","caller":"traceutil/trace.go:171","msg":"trace[273064940] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:553; }","duration":"267.259811ms","start":"2026-05-09T16:51:52.831549Z","end":"2026-05-09T16:51:53.098809Z","steps":["trace[273064940] 'agreement among raft nodes before linearized reading' (duration: 267.187229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.098927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.820382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:51:53.098948Z","caller":"traceutil/trace.go:171","msg":"trace[506215660] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:553; }","duration":"111.865512ms","start":"2026-05-09T16:51:52.987077Z","end":"2026-05-09T16:51:53.098942Z","steps":["trace[506215660] 'agreement among raft nodes before linearized reading' (duration: 111.827041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.099048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.038586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:51:53.099073Z","caller":"traceutil/trace.go:171","msg":"trace[1972916788] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:553; }","duration":"112.105596ms","start":"2026-05-09T16:51:52.986962Z","end":"2026-05-09T16:51:53.099067Z","steps":["trace[1972916788] 'agreement among raft nodes before linearized reading' (duration: 112.068496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.099163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.620116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:51:53.099184Z","caller":"traceutil/trace.go:171","msg":"trace[2132463883] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:553; }","duration":"112.662487ms","start":"2026-05-09T16:51:52.986517Z","end":"2026-05-09T16:51:53.09918Z","steps":["trace[2132463883] 'agreement among raft nodes before linearized reading' (duration: 112.626917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:53.967657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410281724106650,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:51:54.468534Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410281724106650,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:51:54.700826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.546976178s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:51:54.700975Z","caller":"traceutil/trace.go:171","msg":"trace[2011816538] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.610052476s","start":"2026-05-09T16:51:53.090902Z","end":"2026-05-09T16:51:54.700954Z","steps":["trace[2011816538] 'process raft request' (duration: 61.351046ms)","trace[2011816538] 'compare' (duration: 1.546848376s)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:54.703409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:53.090872Z","time spent":"1.610143029s","remote":"127.0.0.1:48802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:54.721706Z","caller":"traceutil/trace.go:171","msg":"trace[1011257703] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.587618884s","start":"2026-05-09T16:51:53.134069Z","end":"2026-05-09T16:51:54.721688Z","steps":["trace[1011257703] 'process raft request' (duration: 1.587485062s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:54.721865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:53.134041Z","time spent":"1.587744417s","remote":"127.0.0.1:48802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:54.721977Z","caller":"traceutil/trace.go:171","msg":"trace[1804945881] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.61152179s","start":"2026-05-09T16:51:53.110446Z","end":"2026-05-09T16:51:54.721968Z","steps":["trace[1804945881] 'process raft request' (duration: 1.611006201s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:54.722029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:53.110416Z","time spent":"1.611583292s","remote":"127.0.0.1:48802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:51:54.737063Z","caller":"traceutil/trace.go:171","msg":"trace[724443819] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.583374663s","start":"2026-05-09T16:51:53.153671Z","end":"2026-05-09T16:51:54.737046Z","steps":["trace[724443819] 'process raft request' (duration: 1.583081178s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:54.737115Z","caller":"traceutil/trace.go:171","msg":"trace[523186109] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:580; }","duration":"1.270365226s","start":"2026-05-09T16:51:53.46673Z","end":"2026-05-09T16:51:54.737096Z","steps":["trace[523186109] 'read index received' (duration: 1.254752226s)","trace[523186109] 'applied index is now lower than readState.Index' (duration: 15.60876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:54.737166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:53.153587Z","time spent":"1.583526476s","remote":"127.0.0.1:48802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:51:54.737365Z","caller":"traceutil/trace.go:171","msg":"trace[1171110690] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.562744597s","start":"2026-05-09T16:51:53.174612Z","end":"2026-05-09T16:51:54.737356Z","steps":["trace[1171110690] 'process raft request' (duration: 1.562313109s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:54.737425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:53.174582Z","time spent":"1.562800138s","remote":"127.0.0.1:48802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:51:54.737527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270785554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:51:54.737554Z","caller":"traceutil/trace.go:171","msg":"trace[597996599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"1.270863355s","start":"2026-05-09T16:51:53.466683Z","end":"2026-05-09T16:51:54.737546Z","steps":["trace[597996599] 'agreement among raft nodes before linearized reading' (duration: 1.270809344s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:54.737574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:53.466659Z","time spent":"1.270910406s","remote":"127.0.0.1:48746","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-05-09T16:51:54.746382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.822782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-09T16:51:54.746609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.70932ms","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-05-09T16:51:54.746732Z","caller":"traceutil/trace.go:171","msg":"trace[718170429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"699.848422ms","start":"2026-05-09T16:51:54.046842Z","end":"2026-05-09T16:51:54.74669Z","steps":["trace[718170429] 'agreement among raft nodes before linearized reading' (duration: 699.424645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:54.746726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.933548ms","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-05-09T16:51:54.746804Z","caller":"traceutil/trace.go:171","msg":"trace[1266428902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:559; }","duration":"768.051599ms","start":"2026-05-09T16:51:53.978735Z","end":"2026-05-09T16:51:54.746787Z","steps":["trace[1266428902] 'agreement among raft nodes before linearized reading' (duration: 767.879106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:54.746825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:54.046836Z","time spent":"699.939754ms","remote":"127.0.0.1:49018","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":"warn","ts":"2026-05-09T16:51:54.746858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:53.978711Z","time spent":"768.136501ms","remote":"127.0.0.1:49018","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-05-09T16:51:54.747089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.941014ms","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-05-09T16:51:54.747166Z","caller":"traceutil/trace.go:171","msg":"trace[2043729818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"824.039156ms","start":"2026-05-09T16:51:53.923103Z","end":"2026-05-09T16:51:54.747143Z","steps":["trace[2043729818] 'agreement among raft nodes before linearized reading' (duration: 823.902603ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:54.746425Z","caller":"traceutil/trace.go:171","msg":"trace[2089916544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"699.912423ms","start":"2026-05-09T16:51:54.046504Z","end":"2026-05-09T16:51:54.746417Z","steps":["trace[2089916544] 'agreement among raft nodes before linearized reading' (duration: 699.755781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:51:54.747215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:53.923083Z","time spent":"824.124727ms","remote":"127.0.0.1:49018","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-05-09T16:51:54.7473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:51:54.046484Z","time spent":"700.730629ms","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T16:52:01.965719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410281724106803,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T16:52:02.142319Z","caller":"traceutil/trace.go:171","msg":"trace[47786998] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"677.539524ms","start":"2026-05-09T16:52:01.464752Z","end":"2026-05-09T16:52:02.142292Z","steps":["trace[47786998] 'read index received' (duration: 676.73499ms)","trace[47786998] 'applied index is now lower than readState.Index' (duration: 803.634µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:52:02.142374Z","caller":"traceutil/trace.go:171","msg":"trace[169833322] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"761.088184ms","start":"2026-05-09T16:52:01.381264Z","end":"2026-05-09T16:52:02.142352Z","steps":["trace[169833322] 'process raft request' (duration: 760.517514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:02.14257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:01.381232Z","time spent":"761.195345ms","remote":"127.0.0.1:48860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:52:02.142547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.787988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:02.142662Z","caller":"traceutil/trace.go:171","msg":"trace[612301675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"677.955742ms","start":"2026-05-09T16:52:01.464674Z","end":"2026-05-09T16:52:02.142629Z","steps":["trace[612301675] 'agreement among raft nodes before linearized reading' (duration: 677.743258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:02.142731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:01.464634Z","time spent":"678.085763ms","remote":"127.0.0.1:48746","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-05-09T16:52:02.142804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.511403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nskql\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-09T16:52:02.143682Z","caller":"traceutil/trace.go:171","msg":"trace[1950652273] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nskql; range_end:; response_count:1; response_revision:640; }","duration":"528.42775ms","start":"2026-05-09T16:52:01.61523Z","end":"2026-05-09T16:52:02.143658Z","steps":["trace[1950652273] 'agreement among raft nodes before linearized reading' (duration: 527.271959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:02.143752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:01.615215Z","time spent":"528.518721ms","remote":"127.0.0.1:48944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-nskql\" "} {"level":"warn","ts":"2026-05-09T16:52:02.966925Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410281724106807,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T16:52:03.176523Z","caller":"traceutil/trace.go:171","msg":"trace[1851172593] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.01730487s","start":"2026-05-09T16:52:02.159186Z","end":"2026-05-09T16:52:03.176491Z","steps":["trace[1851172593] 'process raft request' (duration: 1.017140887s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:03.176671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:02.159168Z","time spent":"1.017427132s","remote":"127.0.0.1:48944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:52:04.101836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.522124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:52:04.102123Z","caller":"traceutil/trace.go:171","msg":"trace[1757660920] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.904466308s","start":"2026-05-09T16:52:02.19763Z","end":"2026-05-09T16:52:04.102097Z","steps":["trace[1757660920] 'process raft request' (duration: 1.085608478s)","trace[1757660920] 'compare' (duration: 818.29913ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:52:04.102235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:02.197608Z","time spent":"1.904563779s","remote":"127.0.0.1:49008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:52:04.466704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000164275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-09T16:52:04.466807Z","caller":"traceutil/trace.go:171","msg":"trace[927656089] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000319738s","start":"2026-05-09T16:52:02.466464Z","end":"2026-05-09T16:52:04.466784Z","steps":["trace[927656089] 'agreement among raft nodes before linearized reading' (duration: 2.000194005s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:04.46686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:02.466445Z","time spent":"2.000403819s","remote":"127.0.0.1:48746","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-05-09T16:52:05.367679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.265719079s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-09T16:52:05.36777Z","caller":"traceutil/trace.go:171","msg":"trace[1153100511] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:667; }","duration":"2.901238888s","start":"2026-05-09T16:52:02.466514Z","end":"2026-05-09T16:52:05.367753Z","steps":["trace[1153100511] 'read index received' (duration: 709.726511ms)","trace[1153100511] 'applied index is now lower than readState.Index' (duration: 2.191511047s)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:52:05.367977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.560990873s","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-05-09T16:52:05.36797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262360032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-09T16:52:05.368006Z","caller":"traceutil/trace.go:171","msg":"trace[192205510] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"2.561075795s","start":"2026-05-09T16:52:02.80692Z","end":"2026-05-09T16:52:05.367996Z","steps":["trace[192205510] 'agreement among raft nodes before linearized reading' (duration: 2.560935892s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:05.368043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:02.806899Z","time spent":"2.561136366s","remote":"127.0.0.1:49018","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-05-09T16:52:05.368058Z","caller":"traceutil/trace.go:171","msg":"trace[779670646] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:642; }","duration":"1.262456443s","start":"2026-05-09T16:52:04.105567Z","end":"2026-05-09T16:52:05.368024Z","steps":["trace[779670646] 'agreement among raft nodes before linearized reading' (duration: 1.262300731s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:05.368105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:04.105508Z","time spent":"1.262586225s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-09T16:52:05.368139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.538037865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-09T16:52:05.36816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.537253511s","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-05-09T16:52:05.368214Z","caller":"traceutil/trace.go:171","msg":"trace[644082776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"2.537338393s","start":"2026-05-09T16:52:02.830862Z","end":"2026-05-09T16:52:05.3682Z","steps":["trace[644082776] 'agreement among raft nodes before linearized reading' (duration: 2.5371678s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:05.368216Z","caller":"traceutil/trace.go:171","msg":"trace[385046740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"2.538143167s","start":"2026-05-09T16:52:02.830051Z","end":"2026-05-09T16:52:05.368195Z","steps":["trace[385046740] 'agreement among raft nodes before linearized reading' (duration: 2.537913673s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:05.368245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:02.83082Z","time spent":"2.537419765s","remote":"127.0.0.1:49018","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-05-09T16:52:05.368255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:02.830038Z","time spent":"2.538208838s","remote":"127.0.0.1:49018","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":"warn","ts":"2026-05-09T16:52:05.368249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.770838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:05.368299Z","caller":"traceutil/trace.go:171","msg":"trace[1263187596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"900.85589ms","start":"2026-05-09T16:52:04.467426Z","end":"2026-05-09T16:52:05.368282Z","steps":["trace[1263187596] 'agreement among raft nodes before linearized reading' (duration: 900.774268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:05.368332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:04.467407Z","time spent":"900.91744ms","remote":"127.0.0.1:48734","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-05-09T16:52:05.368348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.542720316s","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-05-09T16:52:05.368379Z","caller":"traceutil/trace.go:171","msg":"trace[801556285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"2.542778497s","start":"2026-05-09T16:52:02.825593Z","end":"2026-05-09T16:52:05.368371Z","steps":["trace[801556285] 'agreement among raft nodes before linearized reading' (duration: 2.542689346s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:05.368405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:02.825577Z","time spent":"2.542822717s","remote":"127.0.0.1:49018","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-05-09T16:52:05.872017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410281724106812,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:52:06.372989Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410281724106812,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:52:06.485487Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.114015118s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T16:52:06.485859Z","caller":"traceutil/trace.go:171","msg":"trace[540831138] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.114436455s","start":"2026-05-09T16:52:05.371397Z","end":"2026-05-09T16:52:06.485833Z","steps":["trace[540831138] 'process raft request' (duration: 1.114272122s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:06.486008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:05.37138Z","time spent":"1.114546446s","remote":"127.0.0.1:49018","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-05-09T16:52:06.488662Z","caller":"traceutil/trace.go:171","msg":"trace[837507254] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:670; }","duration":"1.117068459s","start":"2026-05-09T16:52:05.371565Z","end":"2026-05-09T16:52:06.488634Z","steps":["trace[837507254] 'read index received' (duration: 1.11417547s)","trace[837507254] 'applied index is now lower than readState.Index' (duration: 2.892329ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:52:06.488751Z","caller":"traceutil/trace.go:171","msg":"trace[1104493829] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.114820391s","start":"2026-05-09T16:52:05.373921Z","end":"2026-05-09T16:52:06.488741Z","steps":["trace[1104493829] 'process raft request' (duration: 1.114644838s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:06.488831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:05.37391Z","time spent":"1.114873341s","remote":"127.0.0.1:49018","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-05-09T16:52:06.488667Z","caller":"traceutil/trace.go:171","msg":"trace[1628679399] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.115585034s","start":"2026-05-09T16:52:05.373065Z","end":"2026-05-09T16:52:06.48865Z","steps":["trace[1628679399] 'process raft request' (duration: 1.115424702s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:06.489045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:05.373042Z","time spent":"1.115963251s","remote":"127.0.0.1:49018","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-05-09T16:52:06.489085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115988791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18adf3cca4dd39a1\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-05-09T16:52:06.489158Z","caller":"traceutil/trace.go:171","msg":"trace[495290979] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18adf3cca4dd39a1; range_end:; response_count:1; response_revision:646; }","duration":"1.116077442s","start":"2026-05-09T16:52:05.373062Z","end":"2026-05-09T16:52:06.489139Z","steps":["trace[495290979] 'agreement among raft nodes before linearized reading' (duration: 1.1159731s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:06.489187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117618758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-09T16:52:06.489219Z","caller":"traceutil/trace.go:171","msg":"trace[485803391] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:646; }","duration":"1.117663998s","start":"2026-05-09T16:52:05.371544Z","end":"2026-05-09T16:52:06.489208Z","steps":["trace[485803391] 'agreement among raft nodes before linearized reading' (duration: 1.117610947s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:06.488964Z","caller":"traceutil/trace.go:171","msg":"trace[1022847759] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.114347553s","start":"2026-05-09T16:52:05.374606Z","end":"2026-05-09T16:52:06.488953Z","steps":["trace[1022847759] 'process raft request' (duration: 1.113997937s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:06.489254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:05.371532Z","time spent":"1.11771552s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-09T16:52:06.48929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113209173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:06.489342Z","caller":"traceutil/trace.go:171","msg":"trace[1781399704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"1.113270334s","start":"2026-05-09T16:52:05.376058Z","end":"2026-05-09T16:52:06.489328Z","steps":["trace[1781399704] 'agreement among raft nodes before linearized reading' (duration: 1.113192433s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:06.489213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:05.373054Z","time spent":"1.116144233s","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18adf3cca4dd39a1\" "} {"level":"warn","ts":"2026-05-09T16:52:06.489381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:05.376051Z","time spent":"1.113319826s","remote":"127.0.0.1:48746","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-05-09T16:52:06.489325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:05.374583Z","time spent":"1.114687348s","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:52:08.18196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.435081ms","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-05-09T16:52:08.182126Z","caller":"traceutil/trace.go:171","msg":"trace[768893200] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:662; }","duration":"347.638604ms","start":"2026-05-09T16:52:07.834466Z","end":"2026-05-09T16:52:08.182105Z","steps":["trace[768893200] 'range keys from in-memory index tree' (duration: 347.398911ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:10.96853Z","caller":"traceutil/trace.go:171","msg":"trace[1617752083] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"106.974131ms","start":"2026-05-09T16:52:10.861528Z","end":"2026-05-09T16:52:10.968502Z","steps":["trace[1617752083] 'read index received' (duration: 106.790578ms)","trace[1617752083] 'applied index is now lower than readState.Index' (duration: 182.473µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:52:10.968802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.240415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-05-09T16:52:10.968847Z","caller":"traceutil/trace.go:171","msg":"trace[64950708] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:719; }","duration":"107.362567ms","start":"2026-05-09T16:52:10.861471Z","end":"2026-05-09T16:52:10.968833Z","steps":["trace[64950708] 'agreement among raft nodes before linearized reading' (duration: 107.183794ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:10.969336Z","caller":"traceutil/trace.go:171","msg":"trace[431629203] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"117.888769ms","start":"2026-05-09T16:52:10.850738Z","end":"2026-05-09T16:52:10.968627Z","steps":["trace[431629203] 'process raft request' (duration: 117.617024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.071253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.88637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:52:23.071545Z","caller":"traceutil/trace.go:171","msg":"trace[640395456] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"361.07818ms","start":"2026-05-09T16:52:22.710415Z","end":"2026-05-09T16:52:23.071493Z","steps":["trace[640395456] 'process raft request' (duration: 159.864335ms)","trace[640395456] 'compare' (duration: 200.760838ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:52:23.071732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.228362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:23.071757Z","caller":"traceutil/trace.go:171","msg":"trace[1529859552] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"359.008319ms","start":"2026-05-09T16:52:22.712675Z","end":"2026-05-09T16:52:23.071684Z","steps":["trace[1529859552] 'process raft request' (duration: 358.890067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.071803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:22.710406Z","time spent":"361.270442ms","remote":"127.0.0.1:49018","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-05-09T16:52:23.071586Z","caller":"traceutil/trace.go:171","msg":"trace[1599652111] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"361.07773ms","start":"2026-05-09T16:52:22.710486Z","end":"2026-05-09T16:52:23.071564Z","steps":["trace[1599652111] 'read index received' (duration: 159.839265ms)","trace[1599652111] 'applied index is now lower than readState.Index' (duration: 201.236125ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:52:23.071821Z","caller":"traceutil/trace.go:171","msg":"trace[1875306704] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:803; }","duration":"361.320913ms","start":"2026-05-09T16:52:22.710469Z","end":"2026-05-09T16:52:23.07179Z","steps":["trace[1875306704] 'agreement among raft nodes before linearized reading' (duration: 361.158431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.07189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:22.712664Z","time spent":"359.14978ms","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:52:23.07205Z","caller":"traceutil/trace.go:171","msg":"trace[735538026] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"359.257653ms","start":"2026-05-09T16:52:22.71278Z","end":"2026-05-09T16:52:23.072038Z","steps":["trace[735538026] 'process raft request' (duration: 359.138901ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:23.072062Z","caller":"traceutil/trace.go:171","msg":"trace[1600063562] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"359.264552ms","start":"2026-05-09T16:52:22.712747Z","end":"2026-05-09T16:52:23.072011Z","steps":["trace[1600063562] 'process raft request' (duration: 358.900807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.07216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:22.712776Z","time spent":"359.318754ms","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:52:23.072199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:22.712742Z","time spent":"359.384414ms","remote":"127.0.0.1:49018","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-05-09T16:52:23.07212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:22.710466Z","time spent":"361.512856ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T16:52:23.311626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.585246ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-09T16:52:23.311785Z","caller":"traceutil/trace.go:171","msg":"trace[1968332822] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"239.861554ms","start":"2026-05-09T16:52:23.071896Z","end":"2026-05-09T16:52:23.311757Z","steps":["trace[1968332822] 'read index received' (duration: 5.46593ms)","trace[1968332822] 'applied index is now lower than readState.Index' (duration: 234.393564ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:52:23.313656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.201071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:23.313729Z","caller":"traceutil/trace.go:171","msg":"trace[1313870413] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:805; }","duration":"437.302012ms","start":"2026-05-09T16:52:22.876414Z","end":"2026-05-09T16:52:23.313716Z","steps":["trace[1313870413] 'agreement among raft nodes before linearized reading' (duration: 437.17202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.313781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:22.876404Z","time spent":"437.353232ms","remote":"127.0.0.1:34636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T16:52:23.314032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.488987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:23.31406Z","caller":"traceutil/trace.go:171","msg":"trace[114645545] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:805; }","duration":"303.533468ms","start":"2026-05-09T16:52:23.01052Z","end":"2026-05-09T16:52:23.314054Z","steps":["trace[114645545] 'agreement among raft nodes before linearized reading' (duration: 303.485727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.314086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:23.010514Z","time spent":"303.561608ms","remote":"127.0.0.1:34642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-09T16:52:23.314037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.186387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:23.314205Z","caller":"traceutil/trace.go:171","msg":"trace[1319673363] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:805; }","duration":"601.3969ms","start":"2026-05-09T16:52:22.712789Z","end":"2026-05-09T16:52:23.314186Z","steps":["trace[1319673363] 'agreement among raft nodes before linearized reading' (duration: 601.155296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.314265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.953167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:23.314298Z","caller":"traceutil/trace.go:171","msg":"trace[1426906420] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:805; }","duration":"436.996317ms","start":"2026-05-09T16:52:22.877293Z","end":"2026-05-09T16:52:23.314289Z","steps":["trace[1426906420] 'agreement among raft nodes before linearized reading' (duration: 436.951146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.314342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:22.877287Z","time spent":"437.046698ms","remote":"127.0.0.1:34636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-09T16:52:23.314319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:22.712781Z","time spent":"601.502802ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-09T16:52:23.314458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.999661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:23.314488Z","caller":"traceutil/trace.go:171","msg":"trace[759613487] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:805; }","duration":"438.052093ms","start":"2026-05-09T16:52:22.876429Z","end":"2026-05-09T16:52:23.314481Z","steps":["trace[759613487] 'agreement among raft nodes before linearized reading' (duration: 438.003862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.314513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:22.876417Z","time spent":"438.088003ms","remote":"127.0.0.1:34636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-09T16:52:23.31472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.592672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:23.31479Z","caller":"traceutil/trace.go:171","msg":"trace[1871691500] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:805; }","duration":"479.663403ms","start":"2026-05-09T16:52:22.835117Z","end":"2026-05-09T16:52:23.31478Z","steps":["trace[1871691500] 'agreement among raft nodes before linearized reading' (duration: 479.575142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.314858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:22.83507Z","time spent":"479.755335ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-09T16:52:23.314227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.72661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:23.315042Z","caller":"traceutil/trace.go:171","msg":"trace[1660844496] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:805; }","duration":"304.556573ms","start":"2026-05-09T16:52:23.010475Z","end":"2026-05-09T16:52:23.315032Z","steps":["trace[1660844496] 'agreement among raft nodes before linearized reading' (duration: 303.724661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.315072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:23.010466Z","time spent":"304.597103ms","remote":"127.0.0.1:34642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-09T16:52:23.315217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.329822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:23.315245Z","caller":"traceutil/trace.go:171","msg":"trace[1192529492] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:805; }","duration":"480.372154ms","start":"2026-05-09T16:52:22.834866Z","end":"2026-05-09T16:52:23.315238Z","steps":["trace[1192529492] 'agreement among raft nodes before linearized reading' (duration: 480.321313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.315269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:22.834863Z","time spent":"480.398114ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T16:52:23.315494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.638077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:23.315526Z","caller":"traceutil/trace.go:171","msg":"trace[783752648] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:805; }","duration":"480.670178ms","start":"2026-05-09T16:52:22.834845Z","end":"2026-05-09T16:52:23.315515Z","steps":["trace[783752648] 'agreement among raft nodes before linearized reading' (duration: 480.621057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.31555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:22.834802Z","time spent":"480.740479ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-09T16:52:23.315647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.105061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:23.315709Z","caller":"traceutil/trace.go:171","msg":"trace[1276870971] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:805; }","duration":"162.219592ms","start":"2026-05-09T16:52:23.153479Z","end":"2026-05-09T16:52:23.315698Z","steps":["trace[1276870971] 'agreement among raft nodes before linearized reading' (duration: 162.114561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.315923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.451032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:23.315958Z","caller":"traceutil/trace.go:171","msg":"trace[1062198525] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:805; }","duration":"161.494603ms","start":"2026-05-09T16:52:23.154455Z","end":"2026-05-09T16:52:23.31595Z","steps":["trace[1062198525] 'agreement among raft nodes before linearized reading' (duration: 161.434051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:23.316041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.492317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:52:23.316113Z","caller":"traceutil/trace.go:171","msg":"trace[133422986] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:805; }","duration":"162.571878ms","start":"2026-05-09T16:52:23.153529Z","end":"2026-05-09T16:52:23.316101Z","steps":["trace[133422986] 'agreement among raft nodes before linearized reading' (duration: 162.485917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:25.972335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410281724107217,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:52:26.473039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410281724107217,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:52:26.662364Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.583690495s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T16:52:26.662884Z","caller":"traceutil/trace.go:171","msg":"trace[1625777770] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"1.584300893s","start":"2026-05-09T16:52:25.078563Z","end":"2026-05-09T16:52:26.662864Z","steps":["trace[1625777770] 'process raft request' (duration: 1.584162591s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:26.663061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:25.078545Z","time spent":"1.584406655s","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:52:26.698828Z","caller":"traceutil/trace.go:171","msg":"trace[474197724] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:849; }","duration":"1.226884346s","start":"2026-05-09T16:52:25.471925Z","end":"2026-05-09T16:52:26.698809Z","steps":["trace[474197724] 'read index received' (duration: 1.190802382s)","trace[474197724] 'applied index is now lower than readState.Index' (duration: 36.081174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:52:26.69918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227238181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T16:52:26.699293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024726531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T16:52:26.699282Z","caller":"traceutil/trace.go:171","msg":"trace[792526088] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"1.620318177s","start":"2026-05-09T16:52:25.078927Z","end":"2026-05-09T16:52:26.699246Z","steps":["trace[792526088] 'process raft request' (duration: 1.619646707s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:26.699401Z","caller":"traceutil/trace.go:171","msg":"trace[882995816] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"1.618285197s","start":"2026-05-09T16:52:25.081026Z","end":"2026-05-09T16:52:26.699311Z","steps":["trace[882995816] 'process raft request' (duration: 1.617703288s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:26.699563Z","caller":"traceutil/trace.go:171","msg":"trace[591973494] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:820; }","duration":"1.024800223s","start":"2026-05-09T16:52:25.674513Z","end":"2026-05-09T16:52:26.699314Z","steps":["trace[591973494] 'agreement among raft nodes before linearized reading' (duration: 1.024741692s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:26.699561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:25.081011Z","time spent":"1.618466419s","remote":"127.0.0.1:49018","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-05-09T16:52:26.699614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:25.674489Z","time spent":"1.025107747s","remote":"127.0.0.1:48960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-05-09T16:52:26.699593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:25.078918Z","time spent":"1.620527879s","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:52:26.699855Z","caller":"traceutil/trace.go:171","msg":"trace[859915563] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"944.927095ms","start":"2026-05-09T16:52:25.754905Z","end":"2026-05-09T16:52:26.699832Z","steps":["trace[859915563] 'process raft request' (duration: 943.84707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:26.699197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045607576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-09T16:52:26.699957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:25.754856Z","time spent":"945.035096ms","remote":"127.0.0.1:49008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:52:26.699979Z","caller":"traceutil/trace.go:171","msg":"trace[79098552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:820; }","duration":"1.227351812s","start":"2026-05-09T16:52:25.471877Z","end":"2026-05-09T16:52:26.699229Z","steps":["trace[79098552] 'agreement among raft nodes before linearized reading' (duration: 1.22717517s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:52:26.700002Z","caller":"traceutil/trace.go:171","msg":"trace[380226723] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:820; }","duration":"1.046429709s","start":"2026-05-09T16:52:25.653556Z","end":"2026-05-09T16:52:26.699985Z","steps":["trace[380226723] 'agreement among raft nodes before linearized reading' (duration: 1.045535855s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:26.700011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:25.471857Z","time spent":"1.228148445s","remote":"127.0.0.1:48746","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-05-09T16:52:26.700147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:25.653474Z","time spent":"1.046566491s","remote":"127.0.0.1:49136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-05-09T16:52:26.699204Z","caller":"traceutil/trace.go:171","msg":"trace[1081191601] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"1.619970082s","start":"2026-05-09T16:52:25.079202Z","end":"2026-05-09T16:52:26.699173Z","steps":["trace[1081191601] 'process raft request' (duration: 1.619501655s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:52:26.701061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:52:25.079187Z","time spent":"1.621796058s","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:53:27.389194Z","caller":"traceutil/trace.go:171","msg":"trace[440179485] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"171.046989ms","start":"2026-05-09T16:53:27.218131Z","end":"2026-05-09T16:53:27.389178Z","steps":["trace[440179485] 'process raft request' (duration: 170.948958ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:19.615221Z","caller":"traceutil/trace.go:171","msg":"trace[1913660082] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"169.665351ms","start":"2026-05-09T16:54:19.44553Z","end":"2026-05-09T16:54:19.615196Z","steps":["trace[1913660082] 'process raft request' (duration: 169.51561ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:19.628176Z","caller":"traceutil/trace.go:171","msg":"trace[859807332] linearizableReadLoop","detail":"{readStateIndex:1416; appliedIndex:1414; }","duration":"181.03353ms","start":"2026-05-09T16:54:19.447118Z","end":"2026-05-09T16:54:19.628151Z","steps":["trace[859807332] 'read index received' (duration: 167.956096ms)","trace[859807332] 'applied index is now lower than readState.Index' (duration: 13.076004ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:54:19.628342Z","caller":"traceutil/trace.go:171","msg":"trace[1715260206] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"182.643365ms","start":"2026-05-09T16:54:19.445651Z","end":"2026-05-09T16:54:19.628294Z","steps":["trace[1715260206] 'process raft request' (duration: 182.12167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:19.6285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.208282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T16:54:19.628604Z","caller":"traceutil/trace.go:171","msg":"trace[1783579209] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1356; }","duration":"162.373064ms","start":"2026-05-09T16:54:19.466207Z","end":"2026-05-09T16:54:19.62858Z","steps":["trace[1783579209] 'agreement among raft nodes before linearized reading' (duration: 162.197273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:19.628972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.140812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:54:19.629014Z","caller":"traceutil/trace.go:171","msg":"trace[932884547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"162.202082ms","start":"2026-05-09T16:54:19.466802Z","end":"2026-05-09T16:54:19.629004Z","steps":["trace[932884547] 'agreement among raft nodes before linearized reading' (duration: 162.148302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:19.62936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.212731ms","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-05-09T16:54:19.62944Z","caller":"traceutil/trace.go:171","msg":"trace[1032407513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1356; }","duration":"182.321552ms","start":"2026-05-09T16:54:19.447099Z","end":"2026-05-09T16:54:19.62942Z","steps":["trace[1032407513] 'agreement among raft nodes before linearized reading' (duration: 182.08113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:19.927387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.969116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:54:19.927537Z","caller":"traceutil/trace.go:171","msg":"trace[860541439] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"149.424362ms","start":"2026-05-09T16:54:19.778091Z","end":"2026-05-09T16:54:19.927515Z","steps":["trace[860541439] 'process raft request' (duration: 33.91541ms)","trace[860541439] 'compare' (duration: 114.758534ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:54:40.05345Z","caller":"traceutil/trace.go:171","msg":"trace[1359650331] linearizableReadLoop","detail":"{readStateIndex:1494; appliedIndex:1492; }","duration":"172.427501ms","start":"2026-05-09T16:54:39.880998Z","end":"2026-05-09T16:54:40.053425Z","steps":["trace[1359650331] 'read index received' (duration: 80.66203ms)","trace[1359650331] 'applied index is now lower than readState.Index' (duration: 91.764201ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:54:40.053517Z","caller":"traceutil/trace.go:171","msg":"trace[1262463379] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"187.70018ms","start":"2026-05-09T16:54:39.865771Z","end":"2026-05-09T16:54:40.053472Z","steps":["trace[1262463379] 'process raft request' (duration: 98.042239ms)","trace[1262463379] 'compare' (duration: 89.52263ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:54:40.053623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.595212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-node-lease/\" range_end:\"/registry/services/endpoints/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:54:40.053715Z","caller":"traceutil/trace.go:171","msg":"trace[98854745] range","detail":"{range_begin:/registry/services/endpoints/kube-node-lease/; range_end:/registry/services/endpoints/kube-node-lease0; response_count:0; response_revision:1430; }","duration":"172.710213ms","start":"2026-05-09T16:54:39.880987Z","end":"2026-05-09T16:54:40.053697Z","steps":["trace[98854745] 'agreement among raft nodes before linearized reading' (duration: 172.544042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:40.078865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.704929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-rate-limit\" ","response":"range_response_count:1 size:4456"} {"level":"info","ts":"2026-05-09T16:54:40.078955Z","caller":"traceutil/trace.go:171","msg":"trace[866826373] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-rate-limit; range_end:; response_count:1; response_revision:1431; }","duration":"188.82119ms","start":"2026-05-09T16:54:39.890112Z","end":"2026-05-09T16:54:40.078933Z","steps":["trace[866826373] 'agreement among raft nodes before linearized reading' (duration: 188.581558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:40.078951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.819562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/kube-public/\" range_end:\"/registry/cronjobs/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:54:40.079125Z","caller":"traceutil/trace.go:171","msg":"trace[603623240] range","detail":"{range_begin:/registry/cronjobs/kube-public/; range_end:/registry/cronjobs/kube-public0; response_count:0; response_revision:1431; }","duration":"185.968983ms","start":"2026-05-09T16:54:39.893097Z","end":"2026-05-09T16:54:40.079066Z","steps":["trace[603623240] 'agreement among raft nodes before linearized reading' (duration: 185.732451ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:54.263061Z","caller":"traceutil/trace.go:171","msg":"trace[1649812889] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"203.417802ms","start":"2026-05-09T16:54:54.059556Z","end":"2026-05-09T16:54:54.262974Z","steps":["trace[1649812889] 'process raft request' (duration: 118.664769ms)","trace[1649812889] 'compare' (duration: 84.519551ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:54:54.263199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.39362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nskql\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-05-09T16:54:54.263276Z","caller":"traceutil/trace.go:171","msg":"trace[443917490] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nskql; range_end:; response_count:1; response_revision:1472; }","duration":"195.5116ms","start":"2026-05-09T16:54:54.067748Z","end":"2026-05-09T16:54:54.26326Z","steps":["trace[443917490] 'agreement among raft nodes before linearized reading' (duration: 195.286318ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:54.263Z","caller":"traceutil/trace.go:171","msg":"trace[2038656279] linearizableReadLoop","detail":"{readStateIndex:1539; appliedIndex:1538; }","duration":"195.185078ms","start":"2026-05-09T16:54:54.067789Z","end":"2026-05-09T16:54:54.262974Z","steps":["trace[2038656279] 'read index received' (duration: 110.432465ms)","trace[2038656279] 'applied index is now lower than readState.Index' (duration: 84.751173ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:54:54.26514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.215371ms","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-05-09T16:54:54.265149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.592388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T16:54:54.2652Z","caller":"traceutil/trace.go:171","msg":"trace[1095877894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1473; }","duration":"161.295902ms","start":"2026-05-09T16:54:54.103886Z","end":"2026-05-09T16:54:54.265182Z","steps":["trace[1095877894] 'agreement among raft nodes before linearized reading' (duration: 161.175751ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:54.265208Z","caller":"traceutil/trace.go:171","msg":"trace[76819000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1473; }","duration":"128.69695ms","start":"2026-05-09T16:54:54.136497Z","end":"2026-05-09T16:54:54.265194Z","steps":["trace[76819000] 'agreement among raft nodes before linearized reading' (duration: 128.480938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:54.265526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.712445ms","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-05-09T16:54:54.265583Z","caller":"traceutil/trace.go:171","msg":"trace[1068840270] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1473; }","duration":"112.838076ms","start":"2026-05-09T16:54:54.152732Z","end":"2026-05-09T16:54:54.26557Z","steps":["trace[1068840270] 'agreement among raft nodes before linearized reading' (duration: 112.683895ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:54.45575Z","caller":"traceutil/trace.go:171","msg":"trace[2050847545] linearizableReadLoop","detail":"{readStateIndex:1543; appliedIndex:1541; }","duration":"105.258438ms","start":"2026-05-09T16:54:54.35047Z","end":"2026-05-09T16:54:54.455729Z","steps":["trace[2050847545] 'read index received' (duration: 80.980479ms)","trace[2050847545] 'applied index is now lower than readState.Index' (duration: 24.277079ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:54:54.455828Z","caller":"traceutil/trace.go:171","msg":"trace[460916919] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"186.021636ms","start":"2026-05-09T16:54:54.269753Z","end":"2026-05-09T16:54:54.455775Z","steps":["trace[460916919] 'process raft request' (duration: 161.811868ms)","trace[460916919] 'compare' (duration: 23.955016ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:54:54.456177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.689441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nskql\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-05-09T16:54:54.455864Z","caller":"traceutil/trace.go:171","msg":"trace[2117424556] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"184.592283ms","start":"2026-05-09T16:54:54.271237Z","end":"2026-05-09T16:54:54.45583Z","steps":["trace[2117424556] 'process raft request' (duration: 184.444482ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:54:54.456284Z","caller":"traceutil/trace.go:171","msg":"trace[1532802882] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nskql; range_end:; response_count:1; response_revision:1476; }","duration":"105.851013ms","start":"2026-05-09T16:54:54.350411Z","end":"2026-05-09T16:54:54.456262Z","steps":["trace[1532802882] 'agreement among raft nodes before linearized reading' (duration: 105.48963ms)"],"step_count":1}