{"level":"warn","ts":"2026-04-03T01:36:12.701831Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-03T01:36:12.702148Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30: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.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-03T01:36:12.702252Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-03T01:36:12.702276Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-04-03T01:36:12.702317Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-03T01:36:12.703019Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-04-03T01:36:12.70317Z","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.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-03T01:36:12.709382Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.972113ms"} {"level":"info","ts":"2026-04-03T01:36:12.72133Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-04-03T01:36:12.721423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-04-03T01:36:12.721504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-04-03T01:36:12.721522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-03T01:36:12.721532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-04-03T01:36:12.721575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-04-03T01:36:12.735842Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-03T01:36:12.739564Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-03T01:36:12.742171Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-03T01:36:12.74751Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-03T01:36:12.747778Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-03T01:36:12.747868Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-03T01:36:12.747974Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-03T01:36:12.747986Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-03T01:36:12.748637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-04-03T01:36:12.748814Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-04-03T01:36:12.753849Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-03T01:36:12.754057Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-03T01:36:12.754099Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-03T01:36:12.754169Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-04-03T01:36:12.754829Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-04-03T01:36:13.122396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-04-03T01:36:13.122455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-03T01:36:13.122534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-04-03T01:36:13.122573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-04-03T01:36:13.122614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-04-03T01:36:13.122641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-04-03T01:36:13.122686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-04-03T01:36:13.123692Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T01:36:13.12431Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-04-03T01:36:13.124672Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T01:36:13.124802Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T01:36:13.124993Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T01:36:13.125034Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T01:36:13.125057Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T01:36:13.12511Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-03T01:36:13.125154Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-03T01:36:13.128924Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-03T01:36:13.131206Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-04-03T01:36:14.779651Z","caller":"traceutil/trace.go:171","msg":"trace[279972866] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"394.634486ms","start":"2026-04-03T01:36:14.384988Z","end":"2026-04-03T01:36:14.779623Z","steps":["trace[279972866] 'process raft request' (duration: 394.402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:14.780215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.384977Z","time spent":"394.758758ms","remote":"127.0.0.1:52672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:36:16.092795Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.132794363s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T01:36:16.093393Z","caller":"traceutil/trace.go:171","msg":"trace[2026473938] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.708212229s","start":"2026-04-03T01:36:14.38514Z","end":"2026-04-03T01:36:16.093352Z","steps":["trace[2026473938] 'process raft request' (duration: 1.707889222s)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:36:16.093604Z","caller":"traceutil/trace.go:171","msg":"trace[1881478228] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.633023611s","start":"2026-04-03T01:36:14.46055Z","end":"2026-04-03T01:36:16.093574Z","steps":["trace[1881478228] 'process raft request' (duration: 1.632835936s)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:36:16.093622Z","caller":"traceutil/trace.go:171","msg":"trace[2038822076] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.633565413s","start":"2026-04-03T01:36:14.459953Z","end":"2026-04-03T01:36:16.093519Z","steps":["trace[2038822076] 'process raft request' (duration: 1.633333058s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.093724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.460537Z","time spent":"1.633125552s","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T01:36:16.093844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.459937Z","time spent":"1.633794799s","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T01:36:16.093881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.385128Z","time spent":"1.708433393s","remote":"127.0.0.1:52674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:36:16.094006Z","caller":"traceutil/trace.go:171","msg":"trace[607661151] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.633318938s","start":"2026-04-03T01:36:14.460631Z","end":"2026-04-03T01:36:16.09395Z","steps":["trace[607661151] 'process raft request' (duration: 1.632780325s)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:36:16.094072Z","caller":"traceutil/trace.go:171","msg":"trace[109409637] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.633869151s","start":"2026-04-03T01:36:14.460177Z","end":"2026-04-03T01:36:16.094046Z","steps":["trace[109409637] 'process raft request' (duration: 1.633145984s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.094135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.460127Z","time spent":"1.633981003s","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T01:36:16.094154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.460618Z","time spent":"1.633467821s","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:36:16.094238Z","caller":"traceutil/trace.go:171","msg":"trace[1367868927] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.633930572s","start":"2026-04-03T01:36:14.46023Z","end":"2026-04-03T01:36:16.094161Z","steps":["trace[1367868927] 'process raft request' (duration: 1.633130364s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.094413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.460209Z","time spent":"1.634125416s","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:36:16.09453Z","caller":"traceutil/trace.go:171","msg":"trace[1083197144] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.633327058s","start":"2026-04-03T01:36:14.461192Z","end":"2026-04-03T01:36:16.094519Z","steps":["trace[1083197144] 'process raft request' (duration: 1.632291225s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.09459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.461174Z","time spent":"1.633391289s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:36:16.094814Z","caller":"traceutil/trace.go:171","msg":"trace[377582550] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.620395911s","start":"2026-04-03T01:36:14.474405Z","end":"2026-04-03T01:36:16.094801Z","steps":["trace[377582550] 'process raft request' (duration: 1.619116702s)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:36:16.094843Z","caller":"traceutil/trace.go:171","msg":"trace[84598118] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.617775954s","start":"2026-04-03T01:36:14.477053Z","end":"2026-04-03T01:36:16.094829Z","steps":["trace[84598118] 'process raft request' (duration: 1.617539499s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.094889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.474396Z","time spent":"1.620458993s","remote":"127.0.0.1:52770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T01:36:16.094903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.47704Z","time spent":"1.617834125s","remote":"127.0.0.1:52860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:36:16.094927Z","caller":"traceutil/trace.go:171","msg":"trace[1754272906] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"1.697191425s","start":"2026-04-03T01:36:14.397721Z","end":"2026-04-03T01:36:16.094912Z","steps":["trace[1754272906] 'read index received' (duration: 381.637907ms)","trace[1754272906] 'applied index is now lower than readState.Index' (duration: 1.315551268s)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:36:16.096931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.366819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-03T01:36:16.097408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.517748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:36:16.097559Z","caller":"traceutil/trace.go:171","msg":"trace[44496301] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"739.657501ms","start":"2026-04-03T01:36:15.357861Z","end":"2026-04-03T01:36:16.097518Z","steps":["trace[44496301] 'agreement among raft nodes before linearized reading' (duration: 739.288283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.097705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.699965606s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:36:16.097789Z","caller":"traceutil/trace.go:171","msg":"trace[965518872] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.700065379s","start":"2026-04-03T01:36:14.397687Z","end":"2026-04-03T01:36:16.097753Z","steps":["trace[965518872] 'agreement among raft nodes before linearized reading' (duration: 1.69740861s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.09779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:15.357811Z","time spent":"739.779753ms","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-03T01:36:16.097835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.397676Z","time spent":"1.70014905s","remote":"127.0.0.1:53000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-03T01:36:16.097667Z","caller":"traceutil/trace.go:171","msg":"trace[1455129002] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"739.459067ms","start":"2026-04-03T01:36:15.357886Z","end":"2026-04-03T01:36:16.097346Z","steps":["trace[1455129002] 'agreement among raft nodes before linearized reading' (duration: 737.314869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.098018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:15.357851Z","time spent":"740.129131ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-03T01:36:16.098348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.567502537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-03T01:36:16.098348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587649945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:36:16.098413Z","caller":"traceutil/trace.go:171","msg":"trace[2057899414] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.567613399s","start":"2026-04-03T01:36:14.53077Z","end":"2026-04-03T01:36:16.098384Z","steps":["trace[2057899414] 'agreement among raft nodes before linearized reading' (duration: 1.567384654s)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:36:16.098427Z","caller":"traceutil/trace.go:171","msg":"trace[1813576087] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.587758268s","start":"2026-04-03T01:36:14.51064Z","end":"2026-04-03T01:36:16.098398Z","steps":["trace[1813576087] 'agreement among raft nodes before linearized reading' (duration: 1.584521056s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.098488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.530725Z","time spent":"1.567719222s","remote":"127.0.0.1:52712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-03T01:36:16.098576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.510633Z","time spent":"1.587933481s","remote":"127.0.0.1:52712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-03T01:36:16.099149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.363344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-03T01:36:16.095266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66138043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:36:16.09953Z","caller":"traceutil/trace.go:171","msg":"trace[1706836116] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.665641555s","start":"2026-04-03T01:36:14.433866Z","end":"2026-04-03T01:36:16.099507Z","steps":["trace[1706836116] 'agreement among raft nodes before linearized reading' (duration: 1.661292268s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.099574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.433753Z","time spent":"1.665809079s","remote":"127.0.0.1:52712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-03T01:36:16.099747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.639652569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:36:16.099809Z","caller":"traceutil/trace.go:171","msg":"trace[711766955] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.63970511s","start":"2026-04-03T01:36:14.460068Z","end":"2026-04-03T01:36:16.099773Z","steps":["trace[711766955] 'agreement among raft nodes before linearized reading' (duration: 1.639650859s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.099849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.460058Z","time spent":"1.639781871s","remote":"127.0.0.1:52712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-03T01:36:16.099939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.641230123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-03T01:36:16.100033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.63885628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:36:16.100028Z","caller":"traceutil/trace.go:171","msg":"trace[965949263] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:0; response_revision:11; }","duration":"1.641317305s","start":"2026-04-03T01:36:14.458672Z","end":"2026-04-03T01:36:16.099989Z","steps":["trace[965949263] 'agreement among raft nodes before linearized reading' (duration: 1.641209373s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.100187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.45866Z","time spent":"1.641416297s","remote":"127.0.0.1:52646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.30\" "} {"level":"info","ts":"2026-04-03T01:36:16.100404Z","caller":"traceutil/trace.go:171","msg":"trace[1450433539] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.638938752s","start":"2026-04-03T01:36:14.461135Z","end":"2026-04-03T01:36:16.100073Z","steps":["trace[1450433539] 'agreement among raft nodes before linearized reading' (duration: 1.638868711s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.100456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.461124Z","time spent":"1.63932262s","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-03T01:36:16.101966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.667581398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:36:16.102041Z","caller":"traceutil/trace.go:171","msg":"trace[1007155054] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.66769969s","start":"2026-04-03T01:36:14.43432Z","end":"2026-04-03T01:36:16.10202Z","steps":["trace[1007155054] 'agreement among raft nodes before linearized reading' (duration: 1.667381363s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.102115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:14.434305Z","time spent":"1.667795514s","remote":"127.0.0.1:52712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-03T01:36:16.101607Z","caller":"traceutil/trace.go:171","msg":"trace[1100164334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"396.472196ms","start":"2026-04-03T01:36:15.702724Z","end":"2026-04-03T01:36:16.099196Z","steps":["trace[1100164334] 'agreement among raft nodes before linearized reading' (duration: 396.242861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:16.102994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:15.702688Z","time spent":"399.703578ms","remote":"127.0.0.1:52620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T01:36:16.605341Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302821976388229,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T01:36:17.018969Z","caller":"traceutil/trace.go:171","msg":"trace[224311474] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"915.782198ms","start":"2026-04-03T01:36:16.103165Z","end":"2026-04-03T01:36:17.018947Z","steps":["trace[224311474] 'process raft request' (duration: 906.559093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.019124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.103139Z","time spent":"915.927502ms","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:36:17.046164Z","caller":"traceutil/trace.go:171","msg":"trace[1127981955] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"942.584613ms","start":"2026-04-03T01:36:16.103549Z","end":"2026-04-03T01:36:17.046133Z","steps":["trace[1127981955] 'process raft request' (duration: 942.283526ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:36:17.046219Z","caller":"traceutil/trace.go:171","msg":"trace[1003092155] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"939.984186ms","start":"2026-04-03T01:36:16.106194Z","end":"2026-04-03T01:36:17.046192Z","steps":["trace[1003092155] 'process raft request' (duration: 939.861623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.04628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.103354Z","time spent":"942.88055ms","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:36:17.046291Z","caller":"traceutil/trace.go:171","msg":"trace[813913761] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:14; }","duration":"941.642163ms","start":"2026-04-03T01:36:16.104623Z","end":"2026-04-03T01:36:17.046266Z","steps":["trace[813913761] 'read index received' (duration: 905.118522ms)","trace[813913761] 'applied index is now lower than readState.Index' (duration: 36.522651ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:36:17.046296Z","caller":"traceutil/trace.go:171","msg":"trace[399770600] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"940.63388ms","start":"2026-04-03T01:36:16.105633Z","end":"2026-04-03T01:36:17.046267Z","steps":["trace[399770600] 'process raft request' (duration: 940.354543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.046367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.106189Z","time spent":"940.116609ms","remote":"127.0.0.1:52712","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:36:17.046393Z","caller":"traceutil/trace.go:171","msg":"trace[1719466278] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"940.128099ms","start":"2026-04-03T01:36:16.106255Z","end":"2026-04-03T01:36:17.046383Z","steps":["trace[1719466278] 'process raft request' (duration: 939.836802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.046455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.105617Z","time spent":"940.754203ms","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:36:17.046214Z","caller":"traceutil/trace.go:171","msg":"trace[79370497] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"940.242092ms","start":"2026-04-03T01:36:16.105936Z","end":"2026-04-03T01:36:17.046178Z","steps":["trace[79370497] 'process raft request' (duration: 940.093718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.046483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.106247Z","time spent":"940.1753ms","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:36:17.046488Z","caller":"traceutil/trace.go:171","msg":"trace[2086090614] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"940.028457ms","start":"2026-04-03T01:36:16.10637Z","end":"2026-04-03T01:36:17.046398Z","steps":["trace[2086090614] 'process raft request' (duration: 939.814022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.046525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.105918Z","time spent":"940.587099ms","remote":"127.0.0.1:52712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T01:36:17.046576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.106362Z","time spent":"940.17168ms","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:36:17.046503Z","caller":"traceutil/trace.go:171","msg":"trace[827550452] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"937.679604ms","start":"2026-04-03T01:36:16.108794Z","end":"2026-04-03T01:36:17.046474Z","steps":["trace[827550452] 'process raft request' (duration: 937.418939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.04665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.108735Z","time spent":"937.879399ms","remote":"127.0.0.1:52770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:36:17.046685Z","caller":"traceutil/trace.go:171","msg":"trace[1159826916] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"940.344844ms","start":"2026-04-03T01:36:16.10633Z","end":"2026-04-03T01:36:17.046675Z","steps":["trace[1159826916] 'process raft request' (duration: 939.803411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.048959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.106325Z","time spent":"942.522271ms","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T01:36:17.049096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.399884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-qv7nj\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-04-03T01:36:17.04916Z","caller":"traceutil/trace.go:171","msg":"trace[1348178968] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-qv7nj; range_end:; response_count:1; response_revision:21; }","duration":"944.504037ms","start":"2026-04-03T01:36:16.104642Z","end":"2026-04-03T01:36:17.049146Z","steps":["trace[1348178968] 'agreement among raft nodes before linearized reading' (duration: 944.367134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.049198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.10463Z","time spent":"944.560878ms","remote":"127.0.0.1:52860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":881,"request content":"key:\"/registry/certificatesigningrequests/csr-qv7nj\" "} {"level":"warn","ts":"2026-04-03T01:36:17.046687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.062013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-04-03T01:36:17.049405Z","caller":"traceutil/trace.go:171","msg":"trace[1473263882] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20; }","duration":"944.793123ms","start":"2026-04-03T01:36:16.104593Z","end":"2026-04-03T01:36:17.049387Z","steps":["trace[1473263882] 'agreement among raft nodes before linearized reading' (duration: 942.009881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.049543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.104583Z","time spent":"944.941816ms","remote":"127.0.0.1:52770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-03T01:36:17.049569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.740959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:36:17.049613Z","caller":"traceutil/trace.go:171","msg":"trace[339832836] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:21; }","duration":"742.860832ms","start":"2026-04-03T01:36:16.306739Z","end":"2026-04-03T01:36:17.0496Z","steps":["trace[339832836] 'agreement among raft nodes before linearized reading' (duration: 742.79933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.049641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.306718Z","time spent":"742.916362ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-04-03T01:36:17.049751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.612297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:36:17.049802Z","caller":"traceutil/trace.go:171","msg":"trace[1009379084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21; }","duration":"943.681438ms","start":"2026-04-03T01:36:16.106104Z","end":"2026-04-03T01:36:17.049786Z","steps":["trace[1009379084] 'agreement among raft nodes before linearized reading' (duration: 943.604846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.049837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.106099Z","time spent":"943.728679ms","remote":"127.0.0.1:52628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T01:36:17.049826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.078126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-03T01:36:17.046737Z","caller":"traceutil/trace.go:171","msg":"trace[559768295] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"940.373725ms","start":"2026-04-03T01:36:16.106349Z","end":"2026-04-03T01:36:17.046723Z","steps":["trace[559768295] 'process raft request' (duration: 939.810632ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:36:17.049916Z","caller":"traceutil/trace.go:171","msg":"trace[352411188] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"889.188729ms","start":"2026-04-03T01:36:16.160694Z","end":"2026-04-03T01:36:17.049883Z","steps":["trace[352411188] 'agreement among raft nodes before linearized reading' (duration: 889.082246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.049939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.106337Z","time spent":"943.571485ms","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T01:36:17.049972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.160677Z","time spent":"889.282591ms","remote":"127.0.0.1:52712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-03T01:36:17.050345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.295239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:36:17.050457Z","caller":"traceutil/trace.go:171","msg":"trace[643838058] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:21; }","duration":"944.007785ms","start":"2026-04-03T01:36:16.106431Z","end":"2026-04-03T01:36:17.050439Z","steps":["trace[643838058] 'agreement among raft nodes before linearized reading' (duration: 943.252328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.05051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:16.106417Z","time spent":"944.076856ms","remote":"127.0.0.1:52972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-04-03T01:36:17.463235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.460765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-03T01:36:17.46347Z","caller":"traceutil/trace.go:171","msg":"trace[2040966523] linearizableReadLoop","detail":"{readStateIndex:72; appliedIndex:69; }","duration":"306.567522ms","start":"2026-04-03T01:36:17.156887Z","end":"2026-04-03T01:36:17.463454Z","steps":["trace[2040966523] 'read index received' (duration: 65.693568ms)","trace[2040966523] 'applied index is now lower than readState.Index' (duration: 240.873034ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:36:17.463528Z","caller":"traceutil/trace.go:171","msg":"trace[2012091790] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"346.855735ms","start":"2026-04-03T01:36:17.116644Z","end":"2026-04-03T01:36:17.4635Z","steps":["trace[2012091790] 'process raft request' (duration: 346.687941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.46361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.717856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:36:17.463639Z","caller":"traceutil/trace.go:171","msg":"trace[1512887112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:68; }","duration":"306.787687ms","start":"2026-04-03T01:36:17.156842Z","end":"2026-04-03T01:36:17.46363Z","steps":["trace[1512887112] 'agreement among raft nodes before linearized reading' (duration: 306.664833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.463665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:17.116632Z","time spent":"346.977128ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":600,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T01:36:17.463675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:17.156829Z","time spent":"306.838148ms","remote":"127.0.0.1:52620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T01:36:17.463674Z","caller":"traceutil/trace.go:171","msg":"trace[298385439] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"347.012209ms","start":"2026-04-03T01:36:17.116644Z","end":"2026-04-03T01:36:17.463656Z","steps":["trace[298385439] 'process raft request' (duration: 346.733352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:17.463854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:17.116632Z","time spent":"347.08187ms","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":622,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:36:17.463821Z","caller":"traceutil/trace.go:171","msg":"trace[333379822] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"350.974187ms","start":"2026-04-03T01:36:17.112825Z","end":"2026-04-03T01:36:17.4638Z","steps":["trace[333379822] 'process raft request' (duration: 109.740294ms)","trace[333379822] 'compare' (duration: 240.333963ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:36:17.464076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:36:17.112818Z","time spent":"351.09882ms","remote":"127.0.0.1:53056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1089,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:36:48.367035Z","caller":"traceutil/trace.go:171","msg":"trace[578464077] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"107.517845ms","start":"2026-04-03T01:36:48.259487Z","end":"2026-04-03T01:36:48.367005Z","steps":["trace[578464077] 'read index received' (duration: 107.389832ms)","trace[578464077] 'applied index is now lower than readState.Index' (duration: 126.283µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:36:48.36708Z","caller":"traceutil/trace.go:171","msg":"trace[859473881] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"263.489526ms","start":"2026-04-03T01:36:48.103568Z","end":"2026-04-03T01:36:48.367058Z","steps":["trace[859473881] 'process raft request' (duration: 263.24665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:48.367201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.684408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-03T01:36:48.367271Z","caller":"traceutil/trace.go:171","msg":"trace[1464870033] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:409; }","duration":"107.820421ms","start":"2026-04-03T01:36:48.259434Z","end":"2026-04-03T01:36:48.367254Z","steps":["trace[1464870033] 'agreement among raft nodes before linearized reading' (duration: 107.689678ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:36:59.244819Z","caller":"traceutil/trace.go:171","msg":"trace[2096298717] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"169.832689ms","start":"2026-04-03T01:36:59.07495Z","end":"2026-04-03T01:36:59.244783Z","steps":["trace[2096298717] 'process raft request' (duration: 169.551292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:04.560988Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302821976389262,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T01:37:04.724221Z","caller":"traceutil/trace.go:171","msg":"trace[1586396477] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"682.337169ms","start":"2026-04-03T01:37:04.041856Z","end":"2026-04-03T01:37:04.724193Z","steps":["trace[1586396477] 'process raft request' (duration: 682.161426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:04.724385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:04.041837Z","time spent":"682.468293ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:37:04.734813Z","caller":"traceutil/trace.go:171","msg":"trace[2061117295] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"688.991267ms","start":"2026-04-03T01:37:04.045792Z","end":"2026-04-03T01:37:04.734783Z","steps":["trace[2061117295] 'process raft request' (duration: 688.69688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:04.735039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:04.045766Z","time spent":"689.147621ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:37:04.735136Z","caller":"traceutil/trace.go:171","msg":"trace[920671600] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:532; }","duration":"675.189491ms","start":"2026-04-03T01:37:04.05993Z","end":"2026-04-03T01:37:04.735119Z","steps":["trace[920671600] 'read index received' (duration: 664.123745ms)","trace[920671600] 'applied index is now lower than readState.Index' (duration: 11.064646ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:37:04.735223Z","caller":"traceutil/trace.go:171","msg":"trace[925645580] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"689.351835ms","start":"2026-04-03T01:37:04.045861Z","end":"2026-04-03T01:37:04.735213Z","steps":["trace[925645580] 'process raft request' (duration: 688.715081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:04.735292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:04.045838Z","time spent":"689.407696ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:37:04.735305Z","caller":"traceutil/trace.go:171","msg":"trace[1668590014] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"662.446147ms","start":"2026-04-03T01:37:04.072812Z","end":"2026-04-03T01:37:04.735258Z","steps":["trace[1668590014] 'process raft request' (duration: 661.993587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:04.735425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:04.072801Z","time spent":"662.568521ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:37:04.735438Z","caller":"traceutil/trace.go:171","msg":"trace[667086330] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"631.43234ms","start":"2026-04-03T01:37:04.103981Z","end":"2026-04-03T01:37:04.735413Z","steps":["trace[667086330] 'process raft request' (duration: 630.966559ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:04.735522Z","caller":"traceutil/trace.go:171","msg":"trace[1576631790] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"667.831847ms","start":"2026-04-03T01:37:04.06766Z","end":"2026-04-03T01:37:04.735492Z","steps":["trace[1576631790] 'process raft request' (duration: 667.0598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:04.73556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.624331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-03T01:37:04.73558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:04.103964Z","time spent":"631.553092ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T01:37:04.735592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:04.06764Z","time spent":"667.920769ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:37:04.735645Z","caller":"traceutil/trace.go:171","msg":"trace[2081768618] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"652.522729ms","start":"2026-04-03T01:37:04.083117Z","end":"2026-04-03T01:37:04.735639Z","steps":["trace[2081768618] 'process raft request' (duration: 651.769472ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:04.735649Z","caller":"traceutil/trace.go:171","msg":"trace[2063766171] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"667.800817ms","start":"2026-04-03T01:37:04.067841Z","end":"2026-04-03T01:37:04.735641Z","steps":["trace[2063766171] 'process raft request' (duration: 666.926118ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:04.735368Z","caller":"traceutil/trace.go:171","msg":"trace[1425873820] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"675.078389ms","start":"2026-04-03T01:37:04.060245Z","end":"2026-04-03T01:37:04.735324Z","steps":["trace[1425873820] 'process raft request' (duration: 674.440945ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:04.735489Z","caller":"traceutil/trace.go:171","msg":"trace[1806198869] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"537.985526ms","start":"2026-04-03T01:37:04.197496Z","end":"2026-04-03T01:37:04.735482Z","steps":["trace[1806198869] 'process raft request' (duration: 537.562277ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:04.735703Z","caller":"traceutil/trace.go:171","msg":"trace[897848423] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"661.455556ms","start":"2026-04-03T01:37:04.074243Z","end":"2026-04-03T01:37:04.735698Z","steps":["trace[897848423] 'process raft request' (duration: 660.605427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:04.735699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:04.067836Z","time spent":"667.833587ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T01:37:04.735734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:04.074207Z","time spent":"661.515437ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:37:04.73559Z","caller":"traceutil/trace.go:171","msg":"trace[522620823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"675.681051ms","start":"2026-04-03T01:37:04.059903Z","end":"2026-04-03T01:37:04.735584Z","steps":["trace[522620823] 'agreement among raft nodes before linearized reading' (duration: 675.60221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:04.735814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:04.059894Z","time spent":"675.915517ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T01:37:04.735777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:04.060243Z","time spent":"675.461397ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T01:37:04.735755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:04.19747Z","time spent":"538.259073ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T01:37:04.735681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:04.0831Z","time spent":"652.565199ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:37:04.73609Z","caller":"traceutil/trace.go:171","msg":"trace[1770528309] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"675.333394ms","start":"2026-04-03T01:37:04.060203Z","end":"2026-04-03T01:37:04.735536Z","steps":["trace[1770528309] 'process raft request' (duration: 674.410574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:04.73626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:04.060195Z","time spent":"675.977608ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T01:37:04.767549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.36939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:37:04.767611Z","caller":"traceutil/trace.go:171","msg":"trace[598927102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"124.477942ms","start":"2026-04-03T01:37:04.643121Z","end":"2026-04-03T01:37:04.767599Z","steps":["trace[598927102] 'agreement among raft nodes before linearized reading' (duration: 123.900369ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:05.850443Z","caller":"traceutil/trace.go:171","msg":"trace[2059603705] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"107.630838ms","start":"2026-04-03T01:37:05.742786Z","end":"2026-04-03T01:37:05.850417Z","steps":["trace[2059603705] 'process raft request' (duration: 41.542641ms)","trace[2059603705] 'compare' (duration: 65.953884ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:37:11.296169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.754302ms","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-04-03T01:37:11.296207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.51225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T01:37:11.29625Z","caller":"traceutil/trace.go:171","msg":"trace[280687176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"501.875304ms","start":"2026-04-03T01:37:10.794356Z","end":"2026-04-03T01:37:11.296232Z","steps":["trace[280687176] 'range keys from in-memory index tree' (duration: 501.601609ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:11.296282Z","caller":"traceutil/trace.go:171","msg":"trace[2112184083] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"382.934746ms","start":"2026-04-03T01:37:10.913324Z","end":"2026-04-03T01:37:11.296259Z","steps":["trace[2112184083] 'read index received' (duration: 154.314584ms)","trace[2112184083] 'applied index is now lower than readState.Index' (duration: 228.619122ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:37:11.29629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:10.794337Z","time spent":"501.944056ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T01:37:11.296407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.084519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bb62n\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-03T01:37:11.29644Z","caller":"traceutil/trace.go:171","msg":"trace[1085512307] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bb62n; range_end:; response_count:1; response_revision:618; }","duration":"383.144831ms","start":"2026-04-03T01:37:10.913286Z","end":"2026-04-03T01:37:11.296431Z","steps":["trace[1085512307] 'agreement among raft nodes before linearized reading' (duration: 383.027908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:11.296472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:10.913269Z","time spent":"383.196352ms","remote":"127.0.0.1:52778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-bb62n\" "} {"level":"info","ts":"2026-04-03T01:37:11.296809Z","caller":"traceutil/trace.go:171","msg":"trace[271979884] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"437.926246ms","start":"2026-04-03T01:37:10.858688Z","end":"2026-04-03T01:37:11.296615Z","steps":["trace[271979884] 'process raft request' (duration: 208.904665ms)","trace[271979884] 'compare' (duration: 228.324386ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:37:11.296964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:10.858655Z","time spent":"438.228032ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:37:11.727584Z","caller":"traceutil/trace.go:171","msg":"trace[1449090183] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"724.407632ms","start":"2026-04-03T01:37:11.003154Z","end":"2026-04-03T01:37:11.727561Z","steps":["trace[1449090183] 'process raft request' (duration: 723.831839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:11.727733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:11.003131Z","time spent":"724.524795ms","remote":"127.0.0.1:52692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:37:12.736773Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.003201239s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T01:37:12.76445Z","caller":"traceutil/trace.go:171","msg":"trace[1136104335] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.463866879s","start":"2026-04-03T01:37:11.300559Z","end":"2026-04-03T01:37:12.764426Z","steps":["trace[1136104335] 'process raft request' (duration: 1.436479451s)","trace[1136104335] 'compare' (duration: 26.956829ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:37:12.764507Z","caller":"traceutil/trace.go:171","msg":"trace[654399573] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"1.461393065s","start":"2026-04-03T01:37:11.30309Z","end":"2026-04-03T01:37:12.764483Z","steps":["trace[654399573] 'read index received' (duration: 423.755402ms)","trace[654399573] 'applied index is now lower than readState.Index' (duration: 1.037636633s)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:37:12.764776Z","caller":"traceutil/trace.go:171","msg":"trace[1314431764] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.45618977s","start":"2026-04-03T01:37:11.308492Z","end":"2026-04-03T01:37:12.764682Z","steps":["trace[1314431764] 'process raft request' (duration: 1.455826642s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:12.764974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:11.308477Z","time spent":"1.456339603s","remote":"127.0.0.1:52778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:37:12.764581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:11.300535Z","time spent":"1.463977472s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:37:12.765058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461958157s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-03T01:37:12.765254Z","caller":"traceutil/trace.go:171","msg":"trace[538229733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"1.462179423s","start":"2026-04-03T01:37:11.303049Z","end":"2026-04-03T01:37:12.765228Z","steps":["trace[538229733] 'agreement among raft nodes before linearized reading' (duration: 1.461867546s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:12.765344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:11.303033Z","time spent":"1.462280965s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T01:37:13.64342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000380064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-03T01:37:13.643524Z","caller":"traceutil/trace.go:171","msg":"trace[468465006] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000555087s","start":"2026-04-03T01:37:11.642945Z","end":"2026-04-03T01:37:13.6435Z","steps":["trace[468465006] 'agreement among raft nodes before linearized reading' (duration: 2.000425825s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:13.643586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:11.642931Z","time spent":"2.000644219s","remote":"127.0.0.1:52620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/03 01:37:13 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-03T01:37:14.423323Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.100930144s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-03T01:37:14.991148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.916645832s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-03T01:37:14.991309Z","caller":"traceutil/trace.go:171","msg":"trace[2124191113] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"3.209159941s","start":"2026-04-03T01:37:11.782105Z","end":"2026-04-03T01:37:14.991265Z","steps":["trace[2124191113] 'process raft request' (duration: 1.291636029s)","trace[2124191113] 'compare' (duration: 1.916309785s)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:37:14.991402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:11.782084Z","time spent":"3.209275374s","remote":"127.0.0.1:52838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T01:37:14.991503Z","caller":"traceutil/trace.go:171","msg":"trace[1210900388] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"2.226809444s","start":"2026-04-03T01:37:12.764645Z","end":"2026-04-03T01:37:14.991455Z","steps":["trace[1210900388] 'read index received' (duration: 309.275682ms)","trace[1210900388] 'applied index is now lower than readState.Index' (duration: 1.917529022s)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:37:14.991575Z","caller":"traceutil/trace.go:171","msg":"trace[875974500] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"2.219865239s","start":"2026-04-03T01:37:12.771665Z","end":"2026-04-03T01:37:14.991531Z","steps":["trace[875974500] 'process raft request' (duration: 2.219602344s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:14.991938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:12.771641Z","time spent":"2.220072483s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:37:15.168938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.553555ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-03T01:37:15.169058Z","caller":"traceutil/trace.go:171","msg":"trace[2085604443] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"177.443704ms","start":"2026-04-03T01:37:14.991594Z","end":"2026-04-03T01:37:15.169038Z","steps":["trace[2085604443] 'read index received' (duration: 74.601µs)","trace[2085604443] 'applied index is now lower than readState.Index' (duration: 177.367463ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:37:15.169104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.849079074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-03T01:37:15.16938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054255345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:37:15.169396Z","caller":"traceutil/trace.go:171","msg":"trace[1095659715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"3.849448724s","start":"2026-04-03T01:37:11.319918Z","end":"2026-04-03T01:37:15.169367Z","steps":["trace[1095659715] 'agreement among raft nodes before linearized reading' (duration: 3.671662142s)","trace[1095659715] 'range keys from in-memory index tree' (duration: 177.259159ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:37:15.169588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.179274ms","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-04-03T01:37:15.169608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.646826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-03T01:37:15.169631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:11.319906Z","time spent":"3.849704829s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-03T01:37:15.169651Z","caller":"traceutil/trace.go:171","msg":"trace[794936486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"399.291956ms","start":"2026-04-03T01:37:14.770344Z","end":"2026-04-03T01:37:15.169636Z","steps":["trace[794936486] 'agreement among raft nodes before linearized reading' (duration: 398.816656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:15.169707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:14.770315Z","time spent":"399.378738ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-03T01:37:15.169722Z","caller":"traceutil/trace.go:171","msg":"trace[151899686] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:623; }","duration":"106.740088ms","start":"2026-04-03T01:37:15.06294Z","end":"2026-04-03T01:37:15.16968Z","steps":["trace[151899686] 'agreement among raft nodes before linearized reading' (duration: 106.476292ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:15.169426Z","caller":"traceutil/trace.go:171","msg":"trace[391611701] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:623; }","duration":"1.054331046s","start":"2026-04-03T01:37:14.115079Z","end":"2026-04-03T01:37:15.16941Z","steps":["trace[391611701] 'agreement among raft nodes before linearized reading' (duration: 1.054267165s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:15.169984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:14.115057Z","time spent":"1.054878929s","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-03T01:37:15.16931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525584572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:37:15.170119Z","caller":"traceutil/trace.go:171","msg":"trace[913406234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"1.52639233s","start":"2026-04-03T01:37:13.643704Z","end":"2026-04-03T01:37:15.170096Z","steps":["trace[913406234] 'agreement among raft nodes before linearized reading' (duration: 1.525558152s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:15.16988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.301050207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-03T01:37:15.170261Z","caller":"traceutil/trace.go:171","msg":"trace[526529185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"3.301481306s","start":"2026-04-03T01:37:11.868756Z","end":"2026-04-03T01:37:15.170237Z","steps":["trace[526529185] 'agreement among raft nodes before linearized reading' (duration: 3.122781325s)","trace[526529185] 'range keys from in-memory index tree' (duration: 177.990766ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:37:15.170306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:13.643555Z","time spent":"1.526652476s","remote":"127.0.0.1:52628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T01:37:15.170383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:11.868737Z","time spent":"3.301630429s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T01:37:15.170623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.432471ms","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-04-03T01:37:15.170687Z","caller":"traceutil/trace.go:171","msg":"trace[1632335213] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:623; }","duration":"172.536854ms","start":"2026-04-03T01:37:14.998134Z","end":"2026-04-03T01:37:15.170671Z","steps":["trace[1632335213] 'agreement among raft nodes before linearized reading' (duration: 171.194224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:20.29016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.225592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-03T01:37:20.290254Z","caller":"traceutil/trace.go:171","msg":"trace[106320113] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:700; }","duration":"183.372805ms","start":"2026-04-03T01:37:20.106859Z","end":"2026-04-03T01:37:20.290232Z","steps":["trace[106320113] 'range keys from in-memory index tree' (duration: 183.017087ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:21.795568Z","caller":"traceutil/trace.go:171","msg":"trace[91724038] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"126.331454ms","start":"2026-04-03T01:37:21.669213Z","end":"2026-04-03T01:37:21.795545Z","steps":["trace[91724038] 'process raft request' (duration: 126.195661ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:21.92106Z","caller":"traceutil/trace.go:171","msg":"trace[302732398] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:747; }","duration":"227.317424ms","start":"2026-04-03T01:37:21.693719Z","end":"2026-04-03T01:37:21.921036Z","steps":["trace[302732398] 'read index received' (duration: 101.680365ms)","trace[302732398] 'applied index is now lower than readState.Index' (duration: 125.636149ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:37:21.921124Z","caller":"traceutil/trace.go:171","msg":"trace[528032984] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"233.056301ms","start":"2026-04-03T01:37:21.688046Z","end":"2026-04-03T01:37:21.921102Z","steps":["trace[528032984] 'process raft request' (duration: 232.919748ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:21.921105Z","caller":"traceutil/trace.go:171","msg":"trace[1902282575] transaction","detail":"{read_only:false; number_of_response:1; response_revision:718; }","duration":"233.123983ms","start":"2026-04-03T01:37:21.687945Z","end":"2026-04-03T01:37:21.921069Z","steps":["trace[1902282575] 'process raft request' (duration: 195.147736ms)","trace[1902282575] 'compare' (duration: 37.789353ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:37:21.921342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.534519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:37:21.921428Z","caller":"traceutil/trace.go:171","msg":"trace[294065075] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:719; }","duration":"227.720432ms","start":"2026-04-03T01:37:21.693694Z","end":"2026-04-03T01:37:21.921414Z","steps":["trace[294065075] 'agreement among raft nodes before linearized reading' (duration: 227.492017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:21.921879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.450813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:37:21.921921Z","caller":"traceutil/trace.go:171","msg":"trace[1439773149] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:719; }","duration":"177.528374ms","start":"2026-04-03T01:37:21.744383Z","end":"2026-04-03T01:37:21.921911Z","steps":["trace[1439773149] 'agreement among raft nodes before linearized reading' (duration: 177.453752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:21.921973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.290952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T01:37:21.922046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.886121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:37:21.922057Z","caller":"traceutil/trace.go:171","msg":"trace[514497417] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:719; }","duration":"147.426244ms","start":"2026-04-03T01:37:21.77459Z","end":"2026-04-03T01:37:21.922016Z","steps":["trace[514497417] 'agreement among raft nodes before linearized reading' (duration: 147.288121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:21.922143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.438346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T01:37:21.922183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.139471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T01:37:21.922198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.123691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:37:21.922203Z","caller":"traceutil/trace.go:171","msg":"trace[1378482158] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:719; }","duration":"196.494447ms","start":"2026-04-03T01:37:21.725689Z","end":"2026-04-03T01:37:21.922184Z","steps":["trace[1378482158] 'agreement among raft nodes before linearized reading' (duration: 196.420785ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:21.92223Z","caller":"traceutil/trace.go:171","msg":"trace[2134935887] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:719; }","duration":"228.164322ms","start":"2026-04-03T01:37:21.694059Z","end":"2026-04-03T01:37:21.922224Z","steps":["trace[2134935887] 'agreement among raft nodes before linearized reading' (duration: 228.122991ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:21.922258Z","caller":"traceutil/trace.go:171","msg":"trace[1331409252] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:719; }","duration":"197.274004ms","start":"2026-04-03T01:37:21.724946Z","end":"2026-04-03T01:37:21.92222Z","steps":["trace[1331409252] 'agreement among raft nodes before linearized reading' (duration: 197.140481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:21.922096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.614366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:37:21.922326Z","caller":"traceutil/trace.go:171","msg":"trace[761335717] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:719; }","duration":"177.863642ms","start":"2026-04-03T01:37:21.744457Z","end":"2026-04-03T01:37:21.92232Z","steps":["trace[761335717] 'agreement among raft nodes before linearized reading' (duration: 177.626187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:21.922064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.004268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:37:21.922408Z","caller":"traceutil/trace.go:171","msg":"trace[573054221] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:719; }","duration":"197.402037ms","start":"2026-04-03T01:37:21.725Z","end":"2026-04-03T01:37:21.922402Z","steps":["trace[573054221] 'agreement among raft nodes before linearized reading' (duration: 196.995008ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:21.922071Z","caller":"traceutil/trace.go:171","msg":"trace[1396463645] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:719; }","duration":"176.926611ms","start":"2026-04-03T01:37:21.745138Z","end":"2026-04-03T01:37:21.922065Z","steps":["trace[1396463645] 'agreement among raft nodes before linearized reading' (duration: 176.889731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:21.922569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.07105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:37:21.922751Z","caller":"traceutil/trace.go:171","msg":"trace[1013798576] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:719; }","duration":"228.99926ms","start":"2026-04-03T01:37:21.693744Z","end":"2026-04-03T01:37:21.922743Z","steps":["trace[1013798576] 'agreement among raft nodes before linearized reading' (duration: 228.05291ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:29.241045Z","caller":"traceutil/trace.go:171","msg":"trace[2030508135] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"184.418998ms","start":"2026-04-03T01:37:29.056597Z","end":"2026-04-03T01:37:29.241016Z","steps":["trace[2030508135] 'process raft request' (duration: 184.137432ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:29.357657Z","caller":"traceutil/trace.go:171","msg":"trace[1299994988] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"111.025782ms","start":"2026-04-03T01:37:29.246608Z","end":"2026-04-03T01:37:29.357634Z","steps":["trace[1299994988] 'process raft request' (duration: 105.727844ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:29.359481Z","caller":"traceutil/trace.go:171","msg":"trace[636080106] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:807; }","duration":"111.146496ms","start":"2026-04-03T01:37:29.248312Z","end":"2026-04-03T01:37:29.359459Z","steps":["trace[636080106] 'read index received' (duration: 104.017637ms)","trace[636080106] 'applied index is now lower than readState.Index' (duration: 7.127839ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:37:29.359543Z","caller":"traceutil/trace.go:171","msg":"trace[522076522] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"112.410825ms","start":"2026-04-03T01:37:29.247096Z","end":"2026-04-03T01:37:29.359507Z","steps":["trace[522076522] 'process raft request' (duration: 112.259221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:29.359673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.338041ms","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-04-03T01:37:29.359711Z","caller":"traceutil/trace.go:171","msg":"trace[1150860310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:775; }","duration":"111.404382ms","start":"2026-04-03T01:37:29.248297Z","end":"2026-04-03T01:37:29.359701Z","steps":["trace[1150860310] 'agreement among raft nodes before linearized reading' (duration: 111.243178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:31.868707Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302821976389851,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T01:37:31.884838Z","caller":"traceutil/trace.go:171","msg":"trace[1064639633] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"516.881895ms","start":"2026-04-03T01:37:31.367935Z","end":"2026-04-03T01:37:31.884817Z","steps":["trace[1064639633] 'process raft request' (duration: 516.726731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:31.885001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:31.367922Z","time spent":"516.976257ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:37:32.799634Z","caller":"traceutil/trace.go:171","msg":"trace[394776862] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:821; }","duration":"1.431507789s","start":"2026-04-03T01:37:31.368108Z","end":"2026-04-03T01:37:32.799616Z","steps":["trace[394776862] 'read index received' (duration: 516.620268ms)","trace[394776862] 'applied index is now lower than readState.Index' (duration: 914.886681ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:37:32.799696Z","caller":"traceutil/trace.go:171","msg":"trace[1732934490] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"1.43152253s","start":"2026-04-03T01:37:31.368129Z","end":"2026-04-03T01:37:32.799651Z","steps":["trace[1732934490] 'process raft request' (duration: 1.431340576s)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:32.799704Z","caller":"traceutil/trace.go:171","msg":"trace[526758941] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"1.160581143s","start":"2026-04-03T01:37:31.639091Z","end":"2026-04-03T01:37:32.799672Z","steps":["trace[526758941] 'process raft request' (duration: 1.160493751s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:32.800037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:31.368116Z","time spent":"1.431835056s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:37:32.800294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:31.639062Z","time spent":"1.161045284s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:37:32.799794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431668042s","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-04-03T01:37:32.800576Z","caller":"traceutil/trace.go:171","msg":"trace[1619335226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"1.43246791s","start":"2026-04-03T01:37:31.368072Z","end":"2026-04-03T01:37:32.80054Z","steps":["trace[1619335226] 'agreement among raft nodes before linearized reading' (duration: 1.431633392s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:32.80079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:31.368058Z","time spent":"1.432687025s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T01:37:32.816614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.180757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T01:37:32.816658Z","caller":"traceutil/trace.go:171","msg":"trace[364303993] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:791; }","duration":"445.274259ms","start":"2026-04-03T01:37:32.371375Z","end":"2026-04-03T01:37:32.816649Z","steps":["trace[364303993] 'agreement among raft nodes before linearized reading' (duration: 445.144386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:32.816686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:32.371352Z","time spent":"445.323501ms","remote":"127.0.0.1:52838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":31,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-03T01:37:32.816759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.81065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:37:32.816857Z","caller":"traceutil/trace.go:171","msg":"trace[1383169089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"172.941502ms","start":"2026-04-03T01:37:32.643909Z","end":"2026-04-03T01:37:32.816851Z","steps":["trace[1383169089] 'agreement among raft nodes before linearized reading' (duration: 172.83165ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:32.817119Z","caller":"traceutil/trace.go:171","msg":"trace[1828134737] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"779.393522ms","start":"2026-04-03T01:37:32.037691Z","end":"2026-04-03T01:37:32.817084Z","steps":["trace[1828134737] 'process raft request' (duration: 778.599934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:37:32.81728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:37:32.037666Z","time spent":"779.522295ms","remote":"127.0.0.1:52838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:37:42.990855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.12818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-03T01:37:42.990936Z","caller":"traceutil/trace.go:171","msg":"trace[96011115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:908; }","duration":"126.253033ms","start":"2026-04-03T01:37:42.864666Z","end":"2026-04-03T01:37:42.990919Z","steps":["trace[96011115] 'agreement among raft nodes before linearized reading' (duration: 125.876945ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:58.707767Z","caller":"traceutil/trace.go:171","msg":"trace[632024736] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"168.695048ms","start":"2026-04-03T01:37:58.539041Z","end":"2026-04-03T01:37:58.707736Z","steps":["trace[632024736] 'process raft request' (duration: 168.469053ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:03.635179Z","caller":"traceutil/trace.go:171","msg":"trace[1468670846] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"175.300184ms","start":"2026-04-03T01:38:03.459855Z","end":"2026-04-03T01:38:03.635156Z","steps":["trace[1468670846] 'process raft request' (duration: 175.141901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:07.440552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.589454ms","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-04-03T01:38:07.440658Z","caller":"traceutil/trace.go:171","msg":"trace[862025347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"308.764398ms","start":"2026-04-03T01:38:07.131869Z","end":"2026-04-03T01:38:07.440634Z","steps":["trace[862025347] 'range keys from in-memory index tree' (duration: 308.371439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:07.440651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.420993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-03T01:38:07.44071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:07.131838Z","time spent":"308.86216ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-03T01:38:07.440753Z","caller":"traceutil/trace.go:171","msg":"trace[1215390472] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"252.587387ms","start":"2026-04-03T01:38:07.188143Z","end":"2026-04-03T01:38:07.44073Z","steps":["trace[1215390472] 'range keys from in-memory index tree' (duration: 252.208759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:08.731855Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302821976390302,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T01:38:08.907375Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.247367576s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T01:38:08.907994Z","caller":"traceutil/trace.go:171","msg":"trace[1041159306] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"1.248024642s","start":"2026-04-03T01:38:07.659924Z","end":"2026-04-03T01:38:08.907949Z","steps":["trace[1041159306] 'process raft request' (duration: 1.247601212s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:08.908178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:07.659905Z","time spent":"1.248184285s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:38:08.908238Z","caller":"traceutil/trace.go:171","msg":"trace[597264048] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"676.410157ms","start":"2026-04-03T01:38:08.231276Z","end":"2026-04-03T01:38:08.907686Z","steps":["trace[597264048] 'read index received' (duration: 676.275635ms)","trace[597264048] 'applied index is now lower than readState.Index' (duration: 133.302µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:38:08.908322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.024142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-03T01:38:08.908409Z","caller":"traceutil/trace.go:171","msg":"trace[419646918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1005; }","duration":"677.154485ms","start":"2026-04-03T01:38:08.231234Z","end":"2026-04-03T01:38:08.908388Z","steps":["trace[419646918] 'agreement among raft nodes before linearized reading' (duration: 676.90983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:08.908463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:08.231213Z","time spent":"677.237226ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T01:38:09.40911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302821976390305,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T01:38:09.910018Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302821976390305,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T01:38:10.226081Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.318522803s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T01:38:10.226437Z","caller":"traceutil/trace.go:171","msg":"trace[68775379] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"1.346204048s","start":"2026-04-03T01:38:08.880206Z","end":"2026-04-03T01:38:10.22641Z","steps":["trace[68775379] 'process raft request' (duration: 1.346064875s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:10.22658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:08.880183Z","time spent":"1.34632322s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:38:10.345474Z","caller":"traceutil/trace.go:171","msg":"trace[163778606] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"1.431156191s","start":"2026-04-03T01:38:08.9143Z","end":"2026-04-03T01:38:10.345457Z","steps":["trace[163778606] 'process raft request' (duration: 1.430158728s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:10.345575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:08.914266Z","time spent":"1.431254013s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:38:10.345572Z","caller":"traceutil/trace.go:171","msg":"trace[2056181773] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"897.796721ms","start":"2026-04-03T01:38:09.447733Z","end":"2026-04-03T01:38:10.34553Z","steps":["trace[2056181773] 'process raft request' (duration: 897.621137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:10.345633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.081488439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-03T01:38:10.345476Z","caller":"traceutil/trace.go:171","msg":"trace[1592318940] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1047; }","duration":"1.437145765s","start":"2026-04-03T01:38:08.908311Z","end":"2026-04-03T01:38:10.345457Z","steps":["trace[1592318940] 'read index received' (duration: 1.31799215s)","trace[1592318940] 'applied index is now lower than readState.Index' (duration: 119.152475ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:38:10.345679Z","caller":"traceutil/trace.go:171","msg":"trace[367073846] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:1008; }","duration":"2.081579901s","start":"2026-04-03T01:38:08.264089Z","end":"2026-04-03T01:38:10.345668Z","steps":["trace[367073846] 'agreement among raft nodes before linearized reading' (duration: 2.081424587s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:10.34571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:08.264058Z","time spent":"2.081644681s","remote":"127.0.0.1:52646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.30\" "} {"level":"warn","ts":"2026-04-03T01:38:10.345725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:09.447715Z","time spent":"897.929723ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:38:10.345785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273310596s","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-04-03T01:38:10.345806Z","caller":"traceutil/trace.go:171","msg":"trace[292618707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1008; }","duration":"1.273379158s","start":"2026-04-03T01:38:09.07242Z","end":"2026-04-03T01:38:10.345799Z","steps":["trace[292618707] 'agreement among raft nodes before linearized reading' (duration: 1.273321266s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:10.345853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:09.072386Z","time spent":"1.273463659s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T01:38:10.345916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.70222535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:38:10.345945Z","caller":"traceutil/trace.go:171","msg":"trace[2012018742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"1.702288752s","start":"2026-04-03T01:38:08.643647Z","end":"2026-04-03T01:38:10.345936Z","steps":["trace[2012018742] 'agreement among raft nodes before linearized reading' (duration: 1.702245251s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:10.345953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.012893ms","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-04-03T01:38:10.34597Z","caller":"traceutil/trace.go:171","msg":"trace[259449685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"849.041044ms","start":"2026-04-03T01:38:09.496924Z","end":"2026-04-03T01:38:10.345965Z","steps":["trace[259449685] 'agreement among raft nodes before linearized reading' (duration: 848.991283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:10.34597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:08.643629Z","time spent":"1.702335772s","remote":"127.0.0.1:52620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T01:38:10.345985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:09.496917Z","time spent":"849.065135ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T01:38:10.34606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.26954ms","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-04-03T01:38:10.346074Z","caller":"traceutil/trace.go:171","msg":"trace[1801622709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1008; }","duration":"876.302571ms","start":"2026-04-03T01:38:09.469767Z","end":"2026-04-03T01:38:10.34607Z","steps":["trace[1801622709] 'agreement among raft nodes before linearized reading' (duration: 876.259581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:10.346089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:09.46976Z","time spent":"876.325762ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-03T01:38:16.529692Z","caller":"traceutil/trace.go:171","msg":"trace[753650605] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"123.242926ms","start":"2026-04-03T01:38:16.406432Z","end":"2026-04-03T01:38:16.529674Z","steps":["trace[753650605] 'process raft request' (duration: 123.209385ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:16.52979Z","caller":"traceutil/trace.go:171","msg":"trace[1439066986] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"124.465192ms","start":"2026-04-03T01:38:16.405293Z","end":"2026-04-03T01:38:16.529758Z","steps":["trace[1439066986] 'process raft request' (duration: 124.261127ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:22.621251Z","caller":"traceutil/trace.go:171","msg":"trace[1928514308] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1091; }","duration":"194.552613ms","start":"2026-04-03T01:38:22.426675Z","end":"2026-04-03T01:38:22.621228Z","steps":["trace[1928514308] 'read index received' (duration: 194.383529ms)","trace[1928514308] 'applied index is now lower than readState.Index' (duration: 167.894µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:38:22.621345Z","caller":"traceutil/trace.go:171","msg":"trace[1056663133] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"208.406225ms","start":"2026-04-03T01:38:22.412915Z","end":"2026-04-03T01:38:22.621322Z","steps":["trace[1056663133] 'process raft request' (duration: 208.16201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:22.621543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.835069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-03T01:38:22.62159Z","caller":"traceutil/trace.go:171","msg":"trace[101356150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1047; }","duration":"194.940752ms","start":"2026-04-03T01:38:22.426635Z","end":"2026-04-03T01:38:22.621576Z","steps":["trace[101356150] 'agreement among raft nodes before linearized reading' (duration: 194.727957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:22.621822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.035234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T01:38:22.621913Z","caller":"traceutil/trace.go:171","msg":"trace[1031959852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1047; }","duration":"108.180087ms","start":"2026-04-03T01:38:22.513715Z","end":"2026-04-03T01:38:22.621895Z","steps":["trace[1031959852] 'agreement among raft nodes before linearized reading' (duration: 107.686456ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:22.745814Z","caller":"traceutil/trace.go:171","msg":"trace[1135852271] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"120.121906ms","start":"2026-04-03T01:38:22.625667Z","end":"2026-04-03T01:38:22.745788Z","steps":["trace[1135852271] 'process raft request' (duration: 80.409932ms)","trace[1135852271] 'compare' (duration: 39.577251ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:38:22.74592Z","caller":"traceutil/trace.go:171","msg":"trace[1645639253] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"120.227408ms","start":"2026-04-03T01:38:22.625679Z","end":"2026-04-03T01:38:22.745906Z","steps":["trace[1645639253] 'process raft request' (duration: 120.070074ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:22.746013Z","caller":"traceutil/trace.go:171","msg":"trace[379721826] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"120.162887ms","start":"2026-04-03T01:38:22.625838Z","end":"2026-04-03T01:38:22.746Z","steps":["trace[379721826] 'process raft request' (duration: 120.021204ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:22.745988Z","caller":"traceutil/trace.go:171","msg":"trace[1376818121] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1092; }","duration":"102.851696ms","start":"2026-04-03T01:38:22.643105Z","end":"2026-04-03T01:38:22.745957Z","steps":["trace[1376818121] 'read index received' (duration: 25.065325ms)","trace[1376818121] 'applied index is now lower than readState.Index' (duration: 77.784251ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:38:22.746102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.987829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:38:22.746136Z","caller":"traceutil/trace.go:171","msg":"trace[716038419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1051; }","duration":"103.03197ms","start":"2026-04-03T01:38:22.643092Z","end":"2026-04-03T01:38:22.746124Z","steps":["trace[716038419] 'agreement among raft nodes before linearized reading' (duration: 102.957658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:33.183616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302821976390495,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T01:38:33.684524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302821976390495,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T01:38:34.126375Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.462441803s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T01:38:34.126739Z","caller":"traceutil/trace.go:171","msg":"trace[1438181665] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"1.462921243s","start":"2026-04-03T01:38:32.663792Z","end":"2026-04-03T01:38:34.126713Z","steps":["trace[1438181665] 'process raft request' (duration: 1.46277204s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:34.126962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:32.663763Z","time spent":"1.463059737s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:38:34.702762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.051462ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-03T01:38:34.703085Z","caller":"traceutil/trace.go:171","msg":"trace[1149091276] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1126; }","duration":"2.020126507s","start":"2026-04-03T01:38:32.68294Z","end":"2026-04-03T01:38:34.703066Z","steps":["trace[1149091276] 'read index received' (duration: 1.443581199s)","trace[1149091276] 'applied index is now lower than readState.Index' (duration: 576.544308ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:38:34.703363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.020395632s","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":"warn","ts":"2026-04-03T01:38:34.703753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.670027409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:38:34.70381Z","caller":"traceutil/trace.go:171","msg":"trace[80857876] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1081; }","duration":"1.670148022s","start":"2026-04-03T01:38:33.033648Z","end":"2026-04-03T01:38:34.703796Z","steps":["trace[80857876] 'agreement among raft nodes before linearized reading' (duration: 1.670022749s)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:34.703811Z","caller":"traceutil/trace.go:171","msg":"trace[337157874] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"526.726085ms","start":"2026-04-03T01:38:34.177063Z","end":"2026-04-03T01:38:34.70379Z","steps":["trace[337157874] 'process raft request' (duration: 525.881736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:34.70392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060119271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T01:38:34.703937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.913236938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-03T01:38:34.703963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.914222829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-03T01:38:34.703979Z","caller":"traceutil/trace.go:171","msg":"trace[251663601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1081; }","duration":"1.060207513s","start":"2026-04-03T01:38:33.643757Z","end":"2026-04-03T01:38:34.703965Z","steps":["trace[251663601] 'agreement among raft nodes before linearized reading' (duration: 1.060118061s)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:34.703995Z","caller":"traceutil/trace.go:171","msg":"trace[1839110407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1081; }","duration":"1.913342661s","start":"2026-04-03T01:38:32.790631Z","end":"2026-04-03T01:38:34.703974Z","steps":["trace[1839110407] 'agreement among raft nodes before linearized reading' (duration: 1.913247329s)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:34.704003Z","caller":"traceutil/trace.go:171","msg":"trace[2060910022] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1081; }","duration":"1.914299551s","start":"2026-04-03T01:38:32.789691Z","end":"2026-04-03T01:38:34.70399Z","steps":["trace[2060910022] 'agreement among raft nodes before linearized reading' (duration: 1.914185809s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:34.704024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:33.643742Z","time spent":"1.060274065s","remote":"127.0.0.1:52628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T01:38:34.704032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:34.17703Z","time spent":"526.884048ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:38:34.70407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:32.790614Z","time spent":"1.913443433s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T01:38:34.70385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:33.033638Z","time spent":"1.670201053s","remote":"127.0.0.1:52832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-03T01:38:34.703828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.994830536s","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-04-03T01:38:34.70406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:32.789667Z","time spent":"1.914385794s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-03T01:38:34.704294Z","caller":"traceutil/trace.go:171","msg":"trace[1655298632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1081; }","duration":"1.995329377s","start":"2026-04-03T01:38:32.70893Z","end":"2026-04-03T01:38:34.704259Z","steps":["trace[1655298632] 'agreement among raft nodes before linearized reading' (duration: 1.994727284s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:34.704339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:32.708911Z","time spent":"1.995415189s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-03T01:38:34.703501Z","caller":"traceutil/trace.go:171","msg":"trace[750868579] 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:1080; }","duration":"2.020567966s","start":"2026-04-03T01:38:32.682865Z","end":"2026-04-03T01:38:34.703433Z","steps":["trace[750868579] 'agreement among raft nodes before linearized reading' (duration: 2.02029094s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:34.704534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:32.682847Z","time spent":"2.021671411s","remote":"127.0.0.1:52910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-03T01:38:34.705057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.913487114s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-03T01:38:34.705114Z","caller":"traceutil/trace.go:171","msg":"trace[1612530452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1081; }","duration":"1.913588066s","start":"2026-04-03T01:38:32.791512Z","end":"2026-04-03T01:38:34.7051Z","steps":["trace[1612530452] 'agreement among raft nodes before linearized reading' (duration: 1.912168144s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:34.705148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:32.791501Z","time spent":"1.913639917s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T01:38:34.706123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.68068259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-03T01:38:34.706182Z","caller":"traceutil/trace.go:171","msg":"trace[1057244618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1081; }","duration":"1.680776871s","start":"2026-04-03T01:38:33.02539Z","end":"2026-04-03T01:38:34.706167Z","steps":["trace[1057244618] 'agreement among raft nodes before linearized reading' (duration: 1.678349607s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:34.706224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:33.025376Z","time spent":"1.680837893s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-03T01:38:37.024762Z","caller":"traceutil/trace.go:171","msg":"trace[4137228] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"379.501079ms","start":"2026-04-03T01:38:36.645234Z","end":"2026-04-03T01:38:37.024735Z","steps":["trace[4137228] 'read index received' (duration: 379.402506ms)","trace[4137228] 'applied index is now lower than readState.Index' (duration: 97.233µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:38:37.024875Z","caller":"traceutil/trace.go:171","msg":"trace[1809265999] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"892.33009ms","start":"2026-04-03T01:38:36.132517Z","end":"2026-04-03T01:38:37.024847Z","steps":["trace[1809265999] 'process raft request' (duration: 892.051434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:37.02497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.704183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:38:37.025037Z","caller":"traceutil/trace.go:171","msg":"trace[1277538132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"379.861537ms","start":"2026-04-03T01:38:36.645161Z","end":"2026-04-03T01:38:37.025022Z","steps":["trace[1277538132] 'agreement among raft nodes before linearized reading' (duration: 379.730454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:37.025028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:36.132495Z","time spent":"892.455783ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:38:37.02508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:36.645133Z","time spent":"379.935948ms","remote":"127.0.0.1:52620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T01:38:37.025122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.696834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-03T01:38:37.025219Z","caller":"traceutil/trace.go:171","msg":"trace[705396133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1087; }","duration":"285.839309ms","start":"2026-04-03T01:38:36.739357Z","end":"2026-04-03T01:38:37.025197Z","steps":["trace[705396133] 'agreement among raft nodes before linearized reading' (duration: 285.603043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:37.025363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.278141ms","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-04-03T01:38:37.025375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.861922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-03T01:38:37.025402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.296621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T01:38:37.025471Z","caller":"traceutil/trace.go:171","msg":"trace[834130597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1087; }","duration":"258.961554ms","start":"2026-04-03T01:38:36.766465Z","end":"2026-04-03T01:38:37.025427Z","steps":["trace[834130597] 'agreement among raft nodes before linearized reading' (duration: 258.713068ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:37.025476Z","caller":"traceutil/trace.go:171","msg":"trace[830902085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1087; }","duration":"259.388714ms","start":"2026-04-03T01:38:36.76603Z","end":"2026-04-03T01:38:37.025418Z","steps":["trace[830902085] 'agreement among raft nodes before linearized reading' (duration: 259.182899ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:37.025519Z","caller":"traceutil/trace.go:171","msg":"trace[869806339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1087; }","duration":"259.458124ms","start":"2026-04-03T01:38:36.766042Z","end":"2026-04-03T01:38:37.0255Z","steps":["trace[869806339] 'agreement among raft nodes before linearized reading' (duration: 259.182388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:37.026184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.079788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-03T01:38:37.026234Z","caller":"traceutil/trace.go:171","msg":"trace[422277552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1087; }","duration":"260.1667ms","start":"2026-04-03T01:38:36.766054Z","end":"2026-04-03T01:38:37.026221Z","steps":["trace[422277552] 'agreement among raft nodes before linearized reading' (duration: 259.101397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:37.298412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.753962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T01:38:37.298859Z","caller":"traceutil/trace.go:171","msg":"trace[1757693603] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"269.238754ms","start":"2026-04-03T01:38:37.029569Z","end":"2026-04-03T01:38:37.298808Z","steps":["trace[1757693603] 'process raft request' (duration: 167.008862ms)","trace[1757693603] 'compare' (duration: 101.637999ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:38:37.298909Z","caller":"traceutil/trace.go:171","msg":"trace[1598768762] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"269.01995ms","start":"2026-04-03T01:38:37.029867Z","end":"2026-04-03T01:38:37.298887Z","steps":["trace[1598768762] 'process raft request' (duration: 268.881737ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:37.298964Z","caller":"traceutil/trace.go:171","msg":"trace[2021656883] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"268.132749ms","start":"2026-04-03T01:38:37.030786Z","end":"2026-04-03T01:38:37.298919Z","steps":["trace[2021656883] 'process raft request' (duration: 268.092468ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:37.299133Z","caller":"traceutil/trace.go:171","msg":"trace[855871167] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"268.752274ms","start":"2026-04-03T01:38:37.030369Z","end":"2026-04-03T01:38:37.299121Z","steps":["trace[855871167] 'process raft request' (duration: 268.442958ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:37.299273Z","caller":"traceutil/trace.go:171","msg":"trace[1718368559] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"268.678643ms","start":"2026-04-03T01:38:37.030584Z","end":"2026-04-03T01:38:37.299262Z","steps":["trace[1718368559] 'process raft request' (duration: 268.264923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:38.863272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.770608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T01:38:38.86335Z","caller":"traceutil/trace.go:171","msg":"trace[1325120157] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"219.802602ms","start":"2026-04-03T01:38:38.643537Z","end":"2026-04-03T01:38:38.86334Z","steps":["trace[1325120157] 'read index received' (duration: 32.641µs)","trace[1325120157] 'applied index is now lower than readState.Index' (duration: 219.769341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:38:38.863454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.918814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:38:38.863471Z","caller":"traceutil/trace.go:171","msg":"trace[1163758461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"219.971126ms","start":"2026-04-03T01:38:38.643496Z","end":"2026-04-03T01:38:38.863467Z","steps":["trace[1163758461] 'agreement among raft nodes before linearized reading' (duration: 219.870274ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:38.86369Z","caller":"traceutil/trace.go:171","msg":"trace[798368994] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"552.895185ms","start":"2026-04-03T01:38:38.310782Z","end":"2026-04-03T01:38:38.863677Z","steps":["trace[798368994] 'process raft request' (duration: 135.668757ms)","trace[798368994] 'compare' (duration: 416.691017ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:38:38.86374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:38.310769Z","time spent":"552.948646ms","remote":"127.0.0.1:52646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:38:39.137856Z","caller":"traceutil/trace.go:171","msg":"trace[1289021794] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"106.420607ms","start":"2026-04-03T01:38:39.031414Z","end":"2026-04-03T01:38:39.137835Z","steps":["trace[1289021794] 'process raft request' (duration: 106.200372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:39.89259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302821976390533,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T01:38:40.344249Z","caller":"traceutil/trace.go:171","msg":"trace[1411755957] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"1.037261905s","start":"2026-04-03T01:38:39.30697Z","end":"2026-04-03T01:38:40.344232Z","steps":["trace[1411755957] 'process raft request' (duration: 1.037161503s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:40.344349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:39.306817Z","time spent":"1.03748203s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:38:40.749289Z","caller":"traceutil/trace.go:171","msg":"trace[118549458] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"1.441578373s","start":"2026-04-03T01:38:39.307688Z","end":"2026-04-03T01:38:40.749266Z","steps":["trace[118549458] 'process raft request' (duration: 1.441508592s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:40.749424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:39.307646Z","time spent":"1.441711116s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:38:40.749423Z","caller":"traceutil/trace.go:171","msg":"trace[430365834] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"1.442287819s","start":"2026-04-03T01:38:39.307107Z","end":"2026-04-03T01:38:40.749395Z","steps":["trace[430365834] 'process raft request' (duration: 1.44188647s)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:40.749472Z","caller":"traceutil/trace.go:171","msg":"trace[1423000476] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1143; }","duration":"1.357343627s","start":"2026-04-03T01:38:39.392076Z","end":"2026-04-03T01:38:40.749419Z","steps":["trace[1423000476] 'read index received' (duration: 952.105238ms)","trace[1423000476] 'applied index is now lower than readState.Index' (duration: 405.236189ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:38:40.749491Z","caller":"traceutil/trace.go:171","msg":"trace[262626537] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"1.442023434s","start":"2026-04-03T01:38:39.307444Z","end":"2026-04-03T01:38:40.749468Z","steps":["trace[262626537] 'process raft request' (duration: 1.441690586s)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:40.749592Z","caller":"traceutil/trace.go:171","msg":"trace[801699071] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"1.441777898s","start":"2026-04-03T01:38:39.307802Z","end":"2026-04-03T01:38:40.74958Z","steps":["trace[801699071] 'process raft request' (duration: 1.441431161s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:40.749605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:39.307085Z","time spent":"1.442440693s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:38:40.749607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:39.307434Z","time spent":"1.442117246s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:38:40.749666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10580529s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T01:38:40.749694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:39.307794Z","time spent":"1.441828379s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:38:40.749734Z","caller":"traceutil/trace.go:171","msg":"trace[1695802405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1099; }","duration":"1.105907141s","start":"2026-04-03T01:38:39.643809Z","end":"2026-04-03T01:38:40.749716Z","steps":["trace[1695802405] 'agreement among raft nodes before linearized reading' (duration: 1.105792499s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:40.749764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.032641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-03T01:38:40.749772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.60252ms","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":"warn","ts":"2026-04-03T01:38:40.749784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:39.64379Z","time spent":"1.105984044s","remote":"127.0.0.1:52620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T01:38:40.749799Z","caller":"traceutil/trace.go:171","msg":"trace[425797434] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1099; }","duration":"412.115623ms","start":"2026-04-03T01:38:40.337673Z","end":"2026-04-03T01:38:40.749788Z","steps":["trace[425797434] 'agreement among raft nodes before linearized reading' (duration: 412.052342ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:40.749815Z","caller":"traceutil/trace.go:171","msg":"trace[601784430] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1099; }","duration":"735.683582ms","start":"2026-04-03T01:38:40.014119Z","end":"2026-04-03T01:38:40.749803Z","steps":["trace[601784430] 'agreement among raft nodes before linearized reading' (duration: 735.609861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:40.749829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:40.337649Z","time spent":"412.171895ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-03T01:38:40.749856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:40.014101Z","time spent":"735.744054ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-03T01:38:40.749997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357915499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T01:38:40.750042Z","caller":"traceutil/trace.go:171","msg":"trace[1900964714] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1099; }","duration":"1.35795768s","start":"2026-04-03T01:38:39.39207Z","end":"2026-04-03T01:38:40.750028Z","steps":["trace[1900964714] 'agreement among raft nodes before linearized reading' (duration: 1.357870888s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:40.750077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:39.392014Z","time spent":"1.358055132s","remote":"127.0.0.1:53130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-04-03T01:38:44.946853Z","caller":"traceutil/trace.go:171","msg":"trace[1561200004] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"176.992827ms","start":"2026-04-03T01:38:44.769819Z","end":"2026-04-03T01:38:44.946812Z","steps":["trace[1561200004] 'process raft request' (duration: 127.629816ms)","trace[1561200004] 'compare' (duration: 49.049464ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:38:44.946853Z","caller":"traceutil/trace.go:171","msg":"trace[754781602] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"170.50006ms","start":"2026-04-03T01:38:44.776317Z","end":"2026-04-03T01:38:44.946817Z","steps":["trace[754781602] 'process raft request' (duration: 170.438249ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:44.946879Z","caller":"traceutil/trace.go:171","msg":"trace[1666210222] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"176.980747ms","start":"2026-04-03T01:38:44.76986Z","end":"2026-04-03T01:38:44.94684Z","steps":["trace[1666210222] 'process raft request' (duration: 176.840784ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:46.91612Z","caller":"traceutil/trace.go:171","msg":"trace[368782959] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"139.081573ms","start":"2026-04-03T01:38:46.776999Z","end":"2026-04-03T01:38:46.916081Z","steps":["trace[368782959] 'process raft request' (duration: 138.739395ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:47.038091Z","caller":"traceutil/trace.go:171","msg":"trace[569407670] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"117.423175ms","start":"2026-04-03T01:38:46.920618Z","end":"2026-04-03T01:38:47.038041Z","steps":["trace[569407670] 'process raft request' (duration: 38.286773ms)","trace[569407670] 'compare' (duration: 78.863636ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:38:47.408871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.116851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T01:38:47.409053Z","caller":"traceutil/trace.go:171","msg":"trace[1482933024] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"246.129165ms","start":"2026-04-03T01:38:47.162907Z","end":"2026-04-03T01:38:47.409036Z","steps":["trace[1482933024] 'process raft request' (duration: 246.073044ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:47.409388Z","caller":"traceutil/trace.go:171","msg":"trace[1684457442] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"366.27334ms","start":"2026-04-03T01:38:47.043083Z","end":"2026-04-03T01:38:47.409356Z","steps":["trace[1684457442] 'process raft request' (duration: 235.591337ms)","trace[1684457442] 'compare' (duration: 130.002538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:38:47.409561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:47.043056Z","time spent":"366.428763ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:38:53.452286Z","caller":"traceutil/trace.go:171","msg":"trace[1645214729] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"110.311945ms","start":"2026-04-03T01:38:53.341948Z","end":"2026-04-03T01:38:53.45226Z","steps":["trace[1645214729] 'process raft request' (duration: 109.803334ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:55.354166Z","caller":"traceutil/trace.go:171","msg":"trace[1480381695] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"279.158117ms","start":"2026-04-03T01:38:55.074989Z","end":"2026-04-03T01:38:55.354147Z","steps":["trace[1480381695] 'read index received' (duration: 279.041615ms)","trace[1480381695] 'applied index is now lower than readState.Index' (duration: 115.762µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:38:55.35432Z","caller":"traceutil/trace.go:171","msg":"trace[124233447] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"372.04972ms","start":"2026-04-03T01:38:54.982239Z","end":"2026-04-03T01:38:55.354288Z","steps":["trace[124233447] 'process raft request' (duration: 371.795214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:55.354381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.376952ms","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-04-03T01:38:55.354411Z","caller":"traceutil/trace.go:171","msg":"trace[1133973450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1193; }","duration":"279.445294ms","start":"2026-04-03T01:38:55.074956Z","end":"2026-04-03T01:38:55.354401Z","steps":["trace[1133973450] 'agreement among raft nodes before linearized reading' (duration: 279.311241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:55.354499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:54.982229Z","time spent":"372.174862ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:38:55.355028Z","caller":"traceutil/trace.go:171","msg":"trace[1046245028] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"220.597369ms","start":"2026-04-03T01:38:55.134405Z","end":"2026-04-03T01:38:55.355002Z","steps":["trace[1046245028] 'process raft request' (duration: 220.519608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:55.355049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.00812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-03T01:38:55.35508Z","caller":"traceutil/trace.go:171","msg":"trace[1158221375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1194; }","duration":"107.065532ms","start":"2026-04-03T01:38:55.248005Z","end":"2026-04-03T01:38:55.355071Z","steps":["trace[1158221375] 'agreement among raft nodes before linearized reading' (duration: 106.95992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:57.14568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302821976390766,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T01:38:57.216084Z","caller":"traceutil/trace.go:171","msg":"trace[615655373] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1252; }","duration":"571.398801ms","start":"2026-04-03T01:38:56.644666Z","end":"2026-04-03T01:38:57.216065Z","steps":["trace[615655373] 'read index received' (duration: 571.244148ms)","trace[615655373] 'applied index is now lower than readState.Index' (duration: 153.993µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:38:57.216193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.515594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:38:57.216215Z","caller":"traceutil/trace.go:171","msg":"trace[1563458426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1199; }","duration":"571.594006ms","start":"2026-04-03T01:38:56.644614Z","end":"2026-04-03T01:38:57.216208Z","steps":["trace[1563458426] 'agreement among raft nodes before linearized reading' (duration: 571.520605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:57.216237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:56.644594Z","time spent":"571.638247ms","remote":"127.0.0.1:52620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T01:38:57.216212Z","caller":"traceutil/trace.go:171","msg":"trace[1189886464] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"788.82808ms","start":"2026-04-03T01:38:56.427355Z","end":"2026-04-03T01:38:57.216183Z","steps":["trace[1189886464] 'process raft request' (duration: 788.595374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:38:57.216359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:38:56.42733Z","time spent":"788.948193ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:39:09.515543Z","caller":"traceutil/trace.go:171","msg":"trace[1507013771] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"181.078779ms","start":"2026-04-03T01:39:09.334441Z","end":"2026-04-03T01:39:09.51552Z","steps":["trace[1507013771] 'process raft request' (duration: 180.930005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:12.543906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.153912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T01:39:12.544078Z","caller":"traceutil/trace.go:171","msg":"trace[649955454] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"844.782621ms","start":"2026-04-03T01:39:11.699278Z","end":"2026-04-03T01:39:12.544061Z","steps":["trace[649955454] 'process raft request' (duration: 844.72635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:12.544145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:11.699259Z","time spent":"844.857012ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:39:12.544242Z","caller":"traceutil/trace.go:171","msg":"trace[598177109] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"963.134187ms","start":"2026-04-03T01:39:11.580873Z","end":"2026-04-03T01:39:12.544007Z","steps":["trace[598177109] 'process raft request' (duration: 393.780681ms)","trace[598177109] 'compare' (duration: 569.028767ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:39:12.544334Z","caller":"traceutil/trace.go:171","msg":"trace[84470383] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"963.071085ms","start":"2026-04-03T01:39:11.581253Z","end":"2026-04-03T01:39:12.544324Z","steps":["trace[84470383] 'read index received' (duration: 393.413652ms)","trace[84470383] 'applied index is now lower than readState.Index' (duration: 569.656143ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:39:12.54448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:11.580857Z","time spent":"963.457353ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:39:12.544541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.273929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-03T01:39:12.544595Z","caller":"traceutil/trace.go:171","msg":"trace[878098297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1255; }","duration":"963.369591ms","start":"2026-04-03T01:39:11.581213Z","end":"2026-04-03T01:39:12.544583Z","steps":["trace[878098297] 'agreement among raft nodes before linearized reading' (duration: 963.200938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:12.544618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:11.581188Z","time spent":"963.426173ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T01:39:12.544542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.012033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-03T01:39:12.544686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.655748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:39:12.544705Z","caller":"traceutil/trace.go:171","msg":"trace[159319188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1255; }","duration":"963.200298ms","start":"2026-04-03T01:39:11.581494Z","end":"2026-04-03T01:39:12.544694Z","steps":["trace[159319188] 'agreement among raft nodes before linearized reading' (duration: 962.950312ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:39:12.544734Z","caller":"traceutil/trace.go:171","msg":"trace[764173900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"900.72812ms","start":"2026-04-03T01:39:11.643993Z","end":"2026-04-03T01:39:12.544721Z","steps":["trace[764173900] 'agreement among raft nodes before linearized reading' (duration: 900.649459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:12.544784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.915833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-03T01:39:12.544804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:11.643979Z","time spent":"900.814522ms","remote":"127.0.0.1:52620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T01:39:12.544831Z","caller":"traceutil/trace.go:171","msg":"trace[1340437732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1255; }","duration":"927.995044ms","start":"2026-04-03T01:39:11.616823Z","end":"2026-04-03T01:39:12.544818Z","steps":["trace[1340437732] 'agreement among raft nodes before linearized reading' (duration: 927.901622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:12.544861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:11.616799Z","time spent":"928.054965ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T01:39:12.544866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.640656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-03T01:39:12.544895Z","caller":"traceutil/trace.go:171","msg":"trace[1562993312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1255; }","duration":"962.679136ms","start":"2026-04-03T01:39:11.582208Z","end":"2026-04-03T01:39:12.544887Z","steps":["trace[1562993312] 'agreement among raft nodes before linearized reading' (duration: 962.567874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:12.544921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:11.582204Z","time spent":"962.711417ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T01:39:12.544746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:11.581489Z","time spent":"963.245179ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-03T01:39:12.545015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.963523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T01:39:12.545049Z","caller":"traceutil/trace.go:171","msg":"trace[1175799268] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1255; }","duration":"710.041265ms","start":"2026-04-03T01:39:11.834999Z","end":"2026-04-03T01:39:12.545041Z","steps":["trace[1175799268] 'agreement among raft nodes before linearized reading' (duration: 709.983213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:12.545076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:11.834971Z","time spent":"710.098035ms","remote":"127.0.0.1:52942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-03T01:39:13.317558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.162772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T01:39:13.317646Z","caller":"traceutil/trace.go:171","msg":"trace[521963426] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"769.064054ms","start":"2026-04-03T01:39:12.548569Z","end":"2026-04-03T01:39:13.317633Z","steps":["trace[521963426] 'process raft request' (duration: 630.773299ms)","trace[521963426] 'compare' (duration: 138.070031ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:39:13.317676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:12.548545Z","time spent":"769.117206ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:39:13.35013Z","caller":"traceutil/trace.go:171","msg":"trace[359629948] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1312; }","duration":"550.386528ms","start":"2026-04-03T01:39:12.799728Z","end":"2026-04-03T01:39:13.350114Z","steps":["trace[359629948] 'read index received' (duration: 379.627061ms)","trace[359629948] 'applied index is now lower than readState.Index' (duration: 170.751286ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:39:13.350156Z","caller":"traceutil/trace.go:171","msg":"trace[809054725] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"801.224829ms","start":"2026-04-03T01:39:12.548914Z","end":"2026-04-03T01:39:13.350139Z","steps":["trace[809054725] 'process raft request' (duration: 801.032915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:13.350273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:12.548909Z","time spent":"801.295451ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:39:13.350472Z","caller":"traceutil/trace.go:171","msg":"trace[976964631] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"800.681195ms","start":"2026-04-03T01:39:12.54978Z","end":"2026-04-03T01:39:13.350461Z","steps":["trace[976964631] 'process raft request' (duration: 800.286696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:13.350531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:12.549772Z","time spent":"800.731068ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:39:13.350758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.971341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T01:39:13.350811Z","caller":"traceutil/trace.go:171","msg":"trace[142906804] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1259; }","duration":"551.102405ms","start":"2026-04-03T01:39:12.799697Z","end":"2026-04-03T01:39:13.350799Z","steps":["trace[142906804] 'agreement among raft nodes before linearized reading' (duration: 550.969502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:13.350844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:12.799683Z","time spent":"551.152765ms","remote":"127.0.0.1:53080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-04-03T01:39:13.352158Z","caller":"traceutil/trace.go:171","msg":"trace[639929610] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"801.153687ms","start":"2026-04-03T01:39:12.548854Z","end":"2026-04-03T01:39:13.350008Z","steps":["trace[639929610] 'process raft request' (duration: 800.957703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:13.352322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:12.548838Z","time spent":"803.387657ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:39:15.748048Z","caller":"traceutil/trace.go:171","msg":"trace[208016956] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"392.091773ms","start":"2026-04-03T01:39:15.355944Z","end":"2026-04-03T01:39:15.748036Z","steps":["trace[208016956] 'read index received' (duration: 392.017341ms)","trace[208016956] 'applied index is now lower than readState.Index' (duration: 73.732µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:39:15.748123Z","caller":"traceutil/trace.go:171","msg":"trace[718472764] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"421.254899ms","start":"2026-04-03T01:39:15.326832Z","end":"2026-04-03T01:39:15.748087Z","steps":["trace[718472764] 'process raft request' (duration: 421.114056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:15.74827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.338803ms","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":"info","ts":"2026-04-03T01:39:15.748331Z","caller":"traceutil/trace.go:171","msg":"trace[1868355975] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1262; }","duration":"133.449976ms","start":"2026-04-03T01:39:15.614857Z","end":"2026-04-03T01:39:15.748307Z","steps":["trace[1868355975] 'agreement among raft nodes before linearized reading' (duration: 133.361914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:15.748324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:15.326814Z","time spent":"421.407433ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:39:15.748377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.031215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T01:39:15.748362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.394678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T01:39:15.748362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.390779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-03T01:39:15.748434Z","caller":"traceutil/trace.go:171","msg":"trace[2141066825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"103.526521ms","start":"2026-04-03T01:39:15.64489Z","end":"2026-04-03T01:39:15.748416Z","steps":["trace[2141066825] 'agreement among raft nodes before linearized reading' (duration: 103.384318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:15.74849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.582679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T01:39:15.748508Z","caller":"traceutil/trace.go:171","msg":"trace[2107499410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1262; }","duration":"390.62508ms","start":"2026-04-03T01:39:15.357878Z","end":"2026-04-03T01:39:15.748503Z","steps":["trace[2107499410] 'agreement among raft nodes before linearized reading' (duration: 390.583378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:15.748532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:15.357865Z","time spent":"390.662621ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T01:39:15.748573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.06729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-03T01:39:15.74833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.238606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-03T01:39:15.748609Z","caller":"traceutil/trace.go:171","msg":"trace[335584528] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1262; }","duration":"392.595524ms","start":"2026-04-03T01:39:15.356009Z","end":"2026-04-03T01:39:15.748605Z","steps":["trace[335584528] 'agreement among raft nodes before linearized reading' (duration: 392.186344ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:39:15.748609Z","caller":"traceutil/trace.go:171","msg":"trace[581325590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1262; }","duration":"130.139912ms","start":"2026-04-03T01:39:15.61846Z","end":"2026-04-03T01:39:15.7486Z","steps":["trace[581325590] 'agreement among raft nodes before linearized reading' (duration: 130.03186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:15.748625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:15.355988Z","time spent":"392.633466ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-03T01:39:15.748457Z","caller":"traceutil/trace.go:171","msg":"trace[717831159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1262; }","duration":"392.545903ms","start":"2026-04-03T01:39:15.355897Z","end":"2026-04-03T01:39:15.748442Z","steps":["trace[717831159] 'agreement among raft nodes before linearized reading' (duration: 392.199725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:15.74845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.29204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:39:15.748723Z","caller":"traceutil/trace.go:171","msg":"trace[1866617212] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1262; }","duration":"334.541516ms","start":"2026-04-03T01:39:15.414152Z","end":"2026-04-03T01:39:15.748694Z","steps":["trace[1866617212] 'agreement among raft nodes before linearized reading' (duration: 334.28106ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:39:15.748417Z","caller":"traceutil/trace.go:171","msg":"trace[1801944342] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1262; }","duration":"180.099337ms","start":"2026-04-03T01:39:15.568303Z","end":"2026-04-03T01:39:15.748402Z","steps":["trace[1801944342] 'agreement among raft nodes before linearized reading' (duration: 180.057676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:15.748825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:15.41413Z","time spent":"334.656809ms","remote":"127.0.0.1:52664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-04-03T01:39:15.749373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:15.355873Z","time spent":"392.989973ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T01:39:16.498878Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302821976390927,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T01:39:16.650524Z","caller":"traceutil/trace.go:171","msg":"trace[217190668] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"898.049219ms","start":"2026-04-03T01:39:15.752449Z","end":"2026-04-03T01:39:16.650499Z","steps":["trace[217190668] 'process raft request' (duration: 859.417069ms)","trace[217190668] 'compare' (duration: 37.30362ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:39:16.650655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:15.752428Z","time spent":"898.164333ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:39:16.742402Z","caller":"traceutil/trace.go:171","msg":"trace[2068638040] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"989.821616ms","start":"2026-04-03T01:39:15.752558Z","end":"2026-04-03T01:39:16.742379Z","steps":["trace[2068638040] 'process raft request' (duration: 989.663803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:16.74255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:15.752548Z","time spent":"989.923508ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:39:16.742856Z","caller":"traceutil/trace.go:171","msg":"trace[472101871] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"989.405947ms","start":"2026-04-03T01:39:15.753419Z","end":"2026-04-03T01:39:16.742825Z","steps":["trace[472101871] 'process raft request' (duration: 988.912796ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:39:16.743011Z","caller":"traceutil/trace.go:171","msg":"trace[627365944] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"989.078711ms","start":"2026-04-03T01:39:15.753917Z","end":"2026-04-03T01:39:16.742996Z","steps":["trace[627365944] 'process raft request' (duration: 988.771203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:16.74302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:15.753414Z","time spent":"989.52592ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:39:16.743083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:15.753912Z","time spent":"989.137082ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:39:16.743312Z","caller":"traceutil/trace.go:171","msg":"trace[1583168559] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"735.153911ms","start":"2026-04-03T01:39:16.008145Z","end":"2026-04-03T01:39:16.743299Z","steps":["trace[1583168559] 'process raft request' (duration: 734.802362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:16.743402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:16.00813Z","time spent":"735.231132ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:39:16.743408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.076984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:39:16.743285Z","caller":"traceutil/trace.go:171","msg":"trace[1182816658] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1320; }","duration":"744.953581ms","start":"2026-04-03T01:39:15.998304Z","end":"2026-04-03T01:39:16.743258Z","steps":["trace[1182816658] 'read index received' (duration: 613.547141ms)","trace[1182816658] 'applied index is now lower than readState.Index' (duration: 131.40527ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:39:16.743467Z","caller":"traceutil/trace.go:171","msg":"trace[1847837953] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1268; }","duration":"745.186456ms","start":"2026-04-03T01:39:15.998261Z","end":"2026-04-03T01:39:16.743447Z","steps":["trace[1847837953] 'agreement among raft nodes before linearized reading' (duration: 745.089114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:16.743515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:15.998233Z","time spent":"745.268998ms","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-03T01:39:16.743562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.323011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-03T01:39:16.743597Z","caller":"traceutil/trace.go:171","msg":"trace[1417526132] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1268; }","duration":"635.387993ms","start":"2026-04-03T01:39:16.108196Z","end":"2026-04-03T01:39:16.743584Z","steps":["trace[1417526132] 'agreement among raft nodes before linearized reading' (duration: 635.328542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:16.74363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:16.108176Z","time spent":"635.445964ms","remote":"127.0.0.1:52674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-04-03T01:39:16.743703Z","caller":"traceutil/trace.go:171","msg":"trace[1308704220] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"173.402956ms","start":"2026-04-03T01:39:16.570282Z","end":"2026-04-03T01:39:16.743685Z","steps":["trace[1308704220] 'process raft request' (duration: 172.869955ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:39:18.3971Z","caller":"traceutil/trace.go:171","msg":"trace[144587614] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"129.108618ms","start":"2026-04-03T01:39:18.267975Z","end":"2026-04-03T01:39:18.397084Z","steps":["trace[144587614] 'read index received' (duration: 128.971985ms)","trace[144587614] 'applied index is now lower than readState.Index' (duration: 136.053µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:39:18.397213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.235131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-03T01:39:18.39723Z","caller":"traceutil/trace.go:171","msg":"trace[1564379751] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:1269; }","duration":"129.302872ms","start":"2026-04-03T01:39:18.267922Z","end":"2026-04-03T01:39:18.397225Z","steps":["trace[1564379751] 'agreement among raft nodes before linearized reading' (duration: 129.22248ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:39:18.397391Z","caller":"traceutil/trace.go:171","msg":"trace[1280140711] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"639.979466ms","start":"2026-04-03T01:39:17.757377Z","end":"2026-04-03T01:39:18.397357Z","steps":["trace[1280140711] 'process raft request' (duration: 639.613528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:18.397582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:17.757353Z","time spent":"640.142669ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:39:19.035709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.158453ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-03T01:39:19.03581Z","caller":"traceutil/trace.go:171","msg":"trace[1924667177] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"391.607112ms","start":"2026-04-03T01:39:18.644186Z","end":"2026-04-03T01:39:19.035793Z","steps":["trace[1924667177] 'read index received' (duration: 213.375207ms)","trace[1924667177] 'applied index is now lower than readState.Index' (duration: 178.230465ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:39:19.035895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.712664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T01:39:19.035827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:18.397899Z","time spent":"637.920511ms","remote":"127.0.0.1:52646","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-03T01:39:19.035927Z","caller":"traceutil/trace.go:171","msg":"trace[727771580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1269; }","duration":"391.785756ms","start":"2026-04-03T01:39:18.644132Z","end":"2026-04-03T01:39:19.035918Z","steps":["trace[727771580] 'agreement among raft nodes before linearized reading' (duration: 391.726384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:19.035962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:18.644111Z","time spent":"391.844117ms","remote":"127.0.0.1:52620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T01:39:19.305818Z","caller":"traceutil/trace.go:171","msg":"trace[2017774456] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"269.892139ms","start":"2026-04-03T01:39:19.035835Z","end":"2026-04-03T01:39:19.305727Z","steps":["trace[2017774456] 'read index received' (duration: 251.946595ms)","trace[2017774456] 'applied index is now lower than readState.Index' (duration: 17.944574ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:39:19.305991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.157903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-03T01:39:19.306082Z","caller":"traceutil/trace.go:171","msg":"trace[42008324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1270; }","duration":"558.285696ms","start":"2026-04-03T01:39:18.747779Z","end":"2026-04-03T01:39:19.306065Z","steps":["trace[42008324] 'agreement among raft nodes before linearized reading' (duration: 557.977819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:19.306086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.783376ms","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-04-03T01:39:19.306126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:18.747766Z","time spent":"558.351727ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-03T01:39:19.306145Z","caller":"traceutil/trace.go:171","msg":"trace[389501675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1270; }","duration":"558.856738ms","start":"2026-04-03T01:39:18.747272Z","end":"2026-04-03T01:39:19.306129Z","steps":["trace[389501675] 'agreement among raft nodes before linearized reading' (duration: 558.659154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:19.306197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:18.747193Z","time spent":"558.991111ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-03T01:39:19.306429Z","caller":"traceutil/trace.go:171","msg":"trace[245849227] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"558.384929ms","start":"2026-04-03T01:39:18.748027Z","end":"2026-04-03T01:39:19.306412Z","steps":["trace[245849227] 'process raft request' (duration: 539.77503ms)","trace[245849227] 'compare' (duration: 17.810101ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:39:19.306519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:18.748008Z","time spent":"558.45591ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:39:19.306955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.287781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-03T01:39:19.306998Z","caller":"traceutil/trace.go:171","msg":"trace[2064751782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1270; }","duration":"560.383853ms","start":"2026-04-03T01:39:18.746604Z","end":"2026-04-03T01:39:19.306988Z","steps":["trace[2064751782] 'agreement among raft nodes before linearized reading' (duration: 560.276341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:19.307026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:18.746576Z","time spent":"560.443755ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-03T01:39:19.307262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.543339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T01:39:19.307298Z","caller":"traceutil/trace.go:171","msg":"trace[1520152347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1270; }","duration":"652.632521ms","start":"2026-04-03T01:39:18.654656Z","end":"2026-04-03T01:39:19.307289Z","steps":["trace[1520152347] 'agreement among raft nodes before linearized reading' (duration: 652.548109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:19.307327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:18.654624Z","time spent":"652.694152ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-03T01:39:19.319388Z","caller":"traceutil/trace.go:171","msg":"trace[130893181] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"282.492023ms","start":"2026-04-03T01:39:19.03687Z","end":"2026-04-03T01:39:19.319362Z","steps":["trace[130893181] 'process raft request' (duration: 282.3333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:19.319408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.643017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:39:19.319474Z","caller":"traceutil/trace.go:171","msg":"trace[648877357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"282.75397ms","start":"2026-04-03T01:39:19.036706Z","end":"2026-04-03T01:39:19.31946Z","steps":["trace[648877357] 'agreement among raft nodes before linearized reading' (duration: 282.634587ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:39:26.942834Z","caller":"traceutil/trace.go:171","msg":"trace[572894019] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"299.000574ms","start":"2026-04-03T01:39:26.643798Z","end":"2026-04-03T01:39:26.942799Z","steps":["trace[572894019] 'read index received' (duration: 298.700976ms)","trace[572894019] 'applied index is now lower than readState.Index' (duration: 298.648µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:39:26.942912Z","caller":"traceutil/trace.go:171","msg":"trace[1580368551] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"510.651927ms","start":"2026-04-03T01:39:26.432206Z","end":"2026-04-03T01:39:26.942858Z","steps":["trace[1580368551] 'process raft request' (duration: 510.38391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:26.943051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.24436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:39:26.943094Z","caller":"traceutil/trace.go:171","msg":"trace[1642786960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"299.336152ms","start":"2026-04-03T01:39:26.643745Z","end":"2026-04-03T01:39:26.943082Z","steps":["trace[1642786960] 'agreement among raft nodes before linearized reading' (duration: 299.193469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:26.943129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:26.43219Z","time spent":"510.7984ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:39:26.943201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.986888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:39:26.943314Z","caller":"traceutil/trace.go:171","msg":"trace[274703713] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1349; }","duration":"215.153892ms","start":"2026-04-03T01:39:26.728147Z","end":"2026-04-03T01:39:26.943301Z","steps":["trace[274703713] 'agreement among raft nodes before linearized reading' (duration: 214.811124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:27.734709Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302821976391086,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T01:39:28.207931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147033295s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-03T01:39:28.208012Z","caller":"traceutil/trace.go:171","msg":"trace[1017181868] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1349; }","duration":"1.147152138s","start":"2026-04-03T01:39:27.060836Z","end":"2026-04-03T01:39:28.207988Z","steps":["trace[1017181868] 'range keys from in-memory index tree' (duration: 1.146861132s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:28.208062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:27.060816Z","time spent":"1.14723375s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-03T01:39:28.209785Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.118778682s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T01:39:28.21014Z","caller":"traceutil/trace.go:171","msg":"trace[481051809] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"1.119263532s","start":"2026-04-03T01:39:27.090858Z","end":"2026-04-03T01:39:28.210121Z","steps":["trace[481051809] 'process raft request' (duration: 1.119113299s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:28.21026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:27.090841Z","time spent":"1.119360135s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:39:28.211625Z","caller":"traceutil/trace.go:171","msg":"trace[1641725754] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1409; }","duration":"977.088029ms","start":"2026-04-03T01:39:27.234515Z","end":"2026-04-03T01:39:28.211603Z","steps":["trace[1641725754] 'read index received' (duration: 975.479973ms)","trace[1641725754] 'applied index is now lower than readState.Index' (duration: 1.606976ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:39:28.211784Z","caller":"traceutil/trace.go:171","msg":"trace[716133917] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"879.663282ms","start":"2026-04-03T01:39:27.332089Z","end":"2026-04-03T01:39:28.211752Z","steps":["trace[716133917] 'process raft request' (duration: 879.417047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:28.211868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.347305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-03T01:39:28.211941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:27.332067Z","time spent":"879.790346ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:39:28.211921Z","caller":"traceutil/trace.go:171","msg":"trace[1923624243] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1351; }","duration":"977.439398ms","start":"2026-04-03T01:39:27.234463Z","end":"2026-04-03T01:39:28.211903Z","steps":["trace[1923624243] 'agreement among raft nodes before linearized reading' (duration: 977.271813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:28.212001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:27.234444Z","time spent":"977.543371ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-03T01:39:28.212156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.205319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-03T01:39:28.212183Z","caller":"traceutil/trace.go:171","msg":"trace[185681027] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1351; }","duration":"841.24567ms","start":"2026-04-03T01:39:27.37093Z","end":"2026-04-03T01:39:28.212175Z","steps":["trace[185681027] 'agreement among raft nodes before linearized reading' (duration: 841.166098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:28.212202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:27.370923Z","time spent":"841.274561ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-03T01:39:28.212268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.219049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:39:28.2123Z","caller":"traceutil/trace.go:171","msg":"trace[353092356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1351; }","duration":"568.28544ms","start":"2026-04-03T01:39:27.644007Z","end":"2026-04-03T01:39:28.212293Z","steps":["trace[353092356] 'agreement among raft nodes before linearized reading' (duration: 568.241399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:28.211922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.87743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-03T01:39:28.212323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:27.643987Z","time spent":"568.329832ms","remote":"127.0.0.1:52620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-03T01:39:28.212359Z","caller":"traceutil/trace.go:171","msg":"trace[1310804770] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1351; }","duration":"934.374251ms","start":"2026-04-03T01:39:27.27796Z","end":"2026-04-03T01:39:28.212334Z","steps":["trace[1310804770] 'agreement among raft nodes before linearized reading' (duration: 933.824519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:28.212436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:27.277931Z","time spent":"934.481304ms","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-04-03T01:39:28.212501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.160119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T01:39:28.212529Z","caller":"traceutil/trace.go:171","msg":"trace[613250894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1351; }","duration":"841.222549ms","start":"2026-04-03T01:39:27.371298Z","end":"2026-04-03T01:39:28.212521Z","steps":["trace[613250894] 'agreement among raft nodes before linearized reading' (duration: 841.130617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:28.212551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:27.37128Z","time spent":"841.264941ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T01:39:28.212767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.407526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-03T01:39:28.2128Z","caller":"traceutil/trace.go:171","msg":"trace[624905803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1351; }","duration":"842.488587ms","start":"2026-04-03T01:39:27.370301Z","end":"2026-04-03T01:39:28.21279Z","steps":["trace[624905803] 'agreement among raft nodes before linearized reading' (duration: 842.385435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:28.212847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:27.370273Z","time spent":"842.552939ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T01:39:28.212936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.947446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-03T01:39:28.212986Z","caller":"traceutil/trace.go:171","msg":"trace[1555038588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1351; }","duration":"842.093199ms","start":"2026-04-03T01:39:27.370881Z","end":"2026-04-03T01:39:28.212974Z","steps":["trace[1555038588] 'agreement among raft nodes before linearized reading' (duration: 841.871705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:28.21302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:27.370871Z","time spent":"842.1408ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-03T01:40:48.852341Z","caller":"traceutil/trace.go:171","msg":"trace[765182428] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"145.158194ms","start":"2026-04-03T01:40:48.707137Z","end":"2026-04-03T01:40:48.852295Z","steps":["trace[765182428] 'process raft request' (duration: 144.99639ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:40:49.143884Z","caller":"traceutil/trace.go:171","msg":"trace[483597499] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"101.339286ms","start":"2026-04-03T01:40:49.042521Z","end":"2026-04-03T01:40:49.143861Z","steps":["trace[483597499] 'process raft request' (duration: 96.284492ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:41:01.082043Z","caller":"traceutil/trace.go:171","msg":"trace[2037890716] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"105.614931ms","start":"2026-04-03T01:41:00.976391Z","end":"2026-04-03T01:41:01.082006Z","steps":["trace[2037890716] 'process raft request' (duration: 84.343383ms)","trace[2037890716] 'compare' (duration: 21.127075ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:41:01.083854Z","caller":"traceutil/trace.go:171","msg":"trace[702167190] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"107.35773ms","start":"2026-04-03T01:41:00.976445Z","end":"2026-04-03T01:41:01.083802Z","steps":["trace[702167190] 'process raft request' (duration: 107.169386ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:41:01.083947Z","caller":"traceutil/trace.go:171","msg":"trace[1461212286] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"106.275797ms","start":"2026-04-03T01:41:00.977591Z","end":"2026-04-03T01:41:01.083867Z","steps":["trace[1461212286] 'process raft request' (duration: 106.107513ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:41:18.686112Z","caller":"traceutil/trace.go:171","msg":"trace[1582147020] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"328.278432ms","start":"2026-04-03T01:41:18.357799Z","end":"2026-04-03T01:41:18.686078Z","steps":["trace[1582147020] 'process raft request' (duration: 328.072577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:41:18.686351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:41:18.357756Z","time spent":"328.430965ms","remote":"127.0.0.1:52646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:41:18.918403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.69888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T01:41:18.918518Z","caller":"traceutil/trace.go:171","msg":"trace[451274496] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1941; }","duration":"227.436598ms","start":"2026-04-03T01:41:18.691061Z","end":"2026-04-03T01:41:18.918498Z","steps":["trace[451274496] 'read index received' (duration: 79.454501ms)","trace[451274496] 'applied index is now lower than readState.Index' (duration: 147.980657ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:41:18.918689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.636043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-03T01:41:18.918728Z","caller":"traceutil/trace.go:171","msg":"trace[1686032403] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1859; }","duration":"227.720385ms","start":"2026-04-03T01:41:18.690997Z","end":"2026-04-03T01:41:18.918718Z","steps":["trace[1686032403] 'agreement among raft nodes before linearized reading' (duration: 227.562911ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:41:18.918724Z","caller":"traceutil/trace.go:171","msg":"trace[753855684] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"227.904849ms","start":"2026-04-03T01:41:18.690786Z","end":"2026-04-03T01:41:18.918691Z","steps":["trace[753855684] 'process raft request' (duration: 79.8259ms)","trace[753855684] 'compare' (duration: 147.559457ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:41:19.171434Z","caller":"traceutil/trace.go:171","msg":"trace[634683503] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"180.657644ms","start":"2026-04-03T01:41:18.990756Z","end":"2026-04-03T01:41:19.171414Z","steps":["trace[634683503] 'read index received' (duration: 131.570567ms)","trace[634683503] 'applied index is now lower than readState.Index' (duration: 49.086137ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:41:19.1715Z","caller":"traceutil/trace.go:171","msg":"trace[607242970] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"191.654572ms","start":"2026-04-03T01:41:18.979828Z","end":"2026-04-03T01:41:19.171482Z","steps":["trace[607242970] 'process raft request' (duration: 142.487022ms)","trace[607242970] 'compare' (duration: 49.010455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:41:19.171525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.757506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T01:41:19.171573Z","caller":"traceutil/trace.go:171","msg":"trace[126559604] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1860; }","duration":"180.832117ms","start":"2026-04-03T01:41:18.99073Z","end":"2026-04-03T01:41:19.171562Z","steps":["trace[126559604] 'agreement among raft nodes before linearized reading' (duration: 180.746185ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:41:19.233002Z","caller":"traceutil/trace.go:171","msg":"trace[1273152706] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"174.290141ms","start":"2026-04-03T01:41:19.058677Z","end":"2026-04-03T01:41:19.232967Z","steps":["trace[1273152706] 'process raft request' (duration: 174.069055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:41:19.777073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.004941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T01:41:19.777315Z","caller":"traceutil/trace.go:171","msg":"trace[465833751] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"539.598988ms","start":"2026-04-03T01:41:19.237573Z","end":"2026-04-03T01:41:19.777172Z","steps":["trace[465833751] 'process raft request' (duration: 310.86595ms)","trace[465833751] 'compare' (duration: 227.420968ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:41:19.77754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:41:19.237549Z","time spent":"539.810132ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:41:19.786285Z","caller":"traceutil/trace.go:171","msg":"trace[741252111] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1944; }","duration":"524.71512ms","start":"2026-04-03T01:41:19.261552Z","end":"2026-04-03T01:41:19.786267Z","steps":["trace[741252111] 'read index received' (duration: 286.856037ms)","trace[741252111] 'applied index is now lower than readState.Index' (duration: 237.858223ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:41:19.786327Z","caller":"traceutil/trace.go:171","msg":"trace[1970236434] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"548.679211ms","start":"2026-04-03T01:41:19.23763Z","end":"2026-04-03T01:41:19.786309Z","steps":["trace[1970236434] 'process raft request' (duration: 548.519638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:41:19.786406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:41:19.237604Z","time spent":"548.766114ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:41:19.786418Z","caller":"traceutil/trace.go:171","msg":"trace[751799744] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"547.875184ms","start":"2026-04-03T01:41:19.238502Z","end":"2026-04-03T01:41:19.786377Z","steps":["trace[751799744] 'process raft request' (duration: 547.738561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:41:19.786484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.920316ms","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-04-03T01:41:19.786511Z","caller":"traceutil/trace.go:171","msg":"trace[1047356927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1864; }","duration":"524.989847ms","start":"2026-04-03T01:41:19.261514Z","end":"2026-04-03T01:41:19.786504Z","steps":["trace[1047356927] 'agreement among raft nodes before linearized reading' (duration: 524.841783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:41:19.786537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:41:19.2615Z","time spent":"525.029538ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-03T01:41:19.786559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.303093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-04-03T01:41:19.786589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:41:19.238492Z","time spent":"548.000196ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:41:19.7866Z","caller":"traceutil/trace.go:171","msg":"trace[1729229917] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1864; }","duration":"497.342923ms","start":"2026-04-03T01:41:19.289245Z","end":"2026-04-03T01:41:19.786588Z","steps":["trace[1729229917] 'agreement among raft nodes before linearized reading' (duration: 497.248571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:41:19.786625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.635926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T01:41:19.78664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:41:19.289211Z","time spent":"497.420445ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-03T01:41:19.786654Z","caller":"traceutil/trace.go:171","msg":"trace[932884229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1864; }","duration":"142.704968ms","start":"2026-04-03T01:41:19.643938Z","end":"2026-04-03T01:41:19.786643Z","steps":["trace[932884229] 'agreement among raft nodes before linearized reading' (duration: 142.663657ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:41:20.368834Z","caller":"traceutil/trace.go:171","msg":"trace[767706153] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"131.481275ms","start":"2026-04-03T01:41:20.237328Z","end":"2026-04-03T01:41:20.368809Z","steps":["trace[767706153] 'process raft request' (duration: 131.341152ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:41:21.341148Z","caller":"traceutil/trace.go:171","msg":"trace[780533894] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"102.514942ms","start":"2026-04-03T01:41:21.238617Z","end":"2026-04-03T01:41:21.341132Z","steps":["trace[780533894] 'process raft request' (duration: 102.411529ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:41:36.035366Z","caller":"traceutil/trace.go:171","msg":"trace[819687995] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"102.980601ms","start":"2026-04-03T01:41:35.932362Z","end":"2026-04-03T01:41:36.035342Z","steps":["trace[819687995] 'process raft request' (duration: 82.464819ms)","trace[819687995] 'compare' (duration: 20.198155ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:42:01.681141Z","caller":"traceutil/trace.go:171","msg":"trace[2114680769] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"147.918185ms","start":"2026-04-03T01:42:01.533187Z","end":"2026-04-03T01:42:01.681105Z","steps":["trace[2114680769] 'process raft request' (duration: 97.981749ms)","trace[2114680769] 'compare' (duration: 49.783772ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:42:34.558527Z","caller":"traceutil/trace.go:171","msg":"trace[135385746] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"280.754789ms","start":"2026-04-03T01:42:34.277745Z","end":"2026-04-03T01:42:34.5585Z","steps":["trace[135385746] 'process raft request' (duration: 280.512814ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:42:34.559523Z","caller":"traceutil/trace.go:171","msg":"trace[1834473344] linearizableReadLoop","detail":"{readStateIndex:2328; appliedIndex:2325; }","duration":"236.494983ms","start":"2026-04-03T01:42:34.323001Z","end":"2026-04-03T01:42:34.559496Z","steps":["trace[1834473344] 'read index received' (duration: 235.339637ms)","trace[1834473344] 'applied index is now lower than readState.Index' (duration: 1.153446ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:42:34.55977Z","caller":"traceutil/trace.go:171","msg":"trace[1922237] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"281.60301ms","start":"2026-04-03T01:42:34.278141Z","end":"2026-04-03T01:42:34.559744Z","steps":["trace[1922237] 'process raft request' (duration: 281.1384ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:42:34.559902Z","caller":"traceutil/trace.go:171","msg":"trace[869022825] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"280.942304ms","start":"2026-04-03T01:42:34.278946Z","end":"2026-04-03T01:42:34.559888Z","steps":["trace[869022825] 'process raft request' (duration: 280.500944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:42:34.560101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.089306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-03T01:42:34.560149Z","caller":"traceutil/trace.go:171","msg":"trace[1299625020] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2229; }","duration":"237.198589ms","start":"2026-04-03T01:42:34.322937Z","end":"2026-04-03T01:42:34.560136Z","steps":["trace[1299625020] 'agreement among raft nodes before linearized reading' (duration: 237.030535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:42:34.560344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.525001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-03T01:42:34.560671Z","caller":"traceutil/trace.go:171","msg":"trace[1836493338] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2229; }","duration":"196.640133ms","start":"2026-04-03T01:42:34.363759Z","end":"2026-04-03T01:42:34.560399Z","steps":["trace[1836493338] 'agreement among raft nodes before linearized reading' (duration: 196.361887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:42:34.777981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.064917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T01:42:34.778283Z","caller":"traceutil/trace.go:171","msg":"trace[180990974] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"212.582413ms","start":"2026-04-03T01:42:34.565687Z","end":"2026-04-03T01:42:34.778269Z","steps":["trace[180990974] 'process raft request' (duration: 212.524242ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:42:34.778317Z","caller":"traceutil/trace.go:171","msg":"trace[1697188923] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"212.649054ms","start":"2026-04-03T01:42:34.565635Z","end":"2026-04-03T01:42:34.778284Z","steps":["trace[1697188923] 'process raft request' (duration: 112.21374ms)","trace[1697188923] 'compare' (duration: 99.984324ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:42:36.727311Z","caller":"traceutil/trace.go:171","msg":"trace[1647033069] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"159.363213ms","start":"2026-04-03T01:42:36.567917Z","end":"2026-04-03T01:42:36.72728Z","steps":["trace[1647033069] 'process raft request' (duration: 159.289311ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:42:36.72756Z","caller":"traceutil/trace.go:171","msg":"trace[229289551] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"159.716331ms","start":"2026-04-03T01:42:36.567814Z","end":"2026-04-03T01:42:36.72753Z","steps":["trace[229289551] 'process raft request' (duration: 119.542255ms)","trace[229289551] 'compare' (duration: 39.716505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:42:40.937685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.75545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-03T01:42:40.93773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.053859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-03T01:42:40.937755Z","caller":"traceutil/trace.go:171","msg":"trace[1030935651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2260; }","duration":"196.866222ms","start":"2026-04-03T01:42:40.740871Z","end":"2026-04-03T01:42:40.937737Z","steps":["trace[1030935651] 'range keys from in-memory index tree' (duration: 196.616257ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:42:40.937791Z","caller":"traceutil/trace.go:171","msg":"trace[692950109] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2260; }","duration":"372.14327ms","start":"2026-04-03T01:42:40.565626Z","end":"2026-04-03T01:42:40.93777Z","steps":["trace[692950109] 'range keys from in-memory index tree' (duration: 371.911405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:42:40.937836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:42:40.565609Z","time spent":"372.213523ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-03T01:42:40.93785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.550362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-03T01:42:40.937896Z","caller":"traceutil/trace.go:171","msg":"trace[514300391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2260; }","duration":"290.619294ms","start":"2026-04-03T01:42:40.647263Z","end":"2026-04-03T01:42:40.937882Z","steps":["trace[514300391] 'range keys from in-memory index tree' (duration: 290.389728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:42:40.937669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.733903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:42:40.938009Z","caller":"traceutil/trace.go:171","msg":"trace[1239826930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2260; }","duration":"294.117532ms","start":"2026-04-03T01:42:40.643879Z","end":"2026-04-03T01:42:40.937997Z","steps":["trace[1239826930] 'range keys from in-memory index tree' (duration: 293.664071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:42:40.938142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.273165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-03T01:42:40.938185Z","caller":"traceutil/trace.go:171","msg":"trace[1867123432] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2260; }","duration":"194.354667ms","start":"2026-04-03T01:42:40.743818Z","end":"2026-04-03T01:42:40.938172Z","steps":["trace[1867123432] 'range keys from in-memory index tree' (duration: 194.150013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:42:40.93822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.9837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-03T01:42:40.938296Z","caller":"traceutil/trace.go:171","msg":"trace[896326126] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2260; }","duration":"116.068822ms","start":"2026-04-03T01:42:40.822205Z","end":"2026-04-03T01:42:40.938274Z","steps":["trace[896326126] 'range keys from in-memory index tree' (duration: 115.827267ms)"],"step_count":1}