{"level":"warn","ts":"2026-07-27T20:10:32.955095Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-27T20:10:32.955547Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.31:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.31:2380","--initial-cluster=instance=https://199.19.213.31:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.31:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.31:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-27T20:10:32.955618Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-27T20:10:32.955627Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.31:2380"]} {"level":"info","ts":"2026-07-27T20:10:32.955647Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-27T20:10:32.956547Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.31:2379"]} {"level":"info","ts":"2026-07-27T20:10:32.956635Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.31:2380"],"listen-peer-urls":["https://199.19.213.31:2380"],"advertise-client-urls":["https://199.19.213.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.31:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-27T20:10:32.958517Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.681855ms"} {"level":"info","ts":"2026-07-27T20:10:32.966843Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b3aa5b67741e7c5e","cluster-id":"9896be1db1fb000"} {"level":"info","ts":"2026-07-27T20:10:32.966924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e switched to configuration voters=()"} {"level":"info","ts":"2026-07-27T20:10:32.966946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became follower at term 0"} {"level":"info","ts":"2026-07-27T20:10:32.966957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b3aa5b67741e7c5e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-27T20:10:32.966978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became follower at term 1"} {"level":"info","ts":"2026-07-27T20:10:32.967012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e switched to configuration voters=(12946260578717826142)"} {"level":"warn","ts":"2026-07-27T20:10:32.975024Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-27T20:10:32.976403Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-27T20:10:32.977483Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-27T20:10:32.979728Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b3aa5b67741e7c5e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-27T20:10:32.979991Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b3aa5b67741e7c5e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-27T20:10:32.980556Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-27T20:10:32.980637Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-27T20:10:32.980658Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-27T20:10:32.981755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e switched to configuration voters=(12946260578717826142)"} {"level":"info","ts":"2026-07-27T20:10:32.981892Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9896be1db1fb000","local-member-id":"b3aa5b67741e7c5e","added-peer-id":"b3aa5b67741e7c5e","added-peer-peer-urls":["https://199.19.213.31:2380"]} {"level":"info","ts":"2026-07-27T20:10:32.984759Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-27T20:10:32.985Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.31:2380"} {"level":"info","ts":"2026-07-27T20:10:32.985055Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.31:2380"} {"level":"info","ts":"2026-07-27T20:10:32.985753Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b3aa5b67741e7c5e","initial-advertise-peer-urls":["https://199.19.213.31:2380"],"listen-peer-urls":["https://199.19.213.31:2380"],"advertise-client-urls":["https://199.19.213.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-27T20:10:32.985813Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-27T20:10:33.468203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e is starting a new election at term 1"} {"level":"info","ts":"2026-07-27T20:10:33.468247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became pre-candidate at term 1"} {"level":"info","ts":"2026-07-27T20:10:33.468271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e received MsgPreVoteResp from b3aa5b67741e7c5e at term 1"} {"level":"info","ts":"2026-07-27T20:10:33.468282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became candidate at term 2"} {"level":"info","ts":"2026-07-27T20:10:33.468288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e received MsgVoteResp from b3aa5b67741e7c5e at term 2"} {"level":"info","ts":"2026-07-27T20:10:33.468296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b3aa5b67741e7c5e became leader at term 2"} {"level":"info","ts":"2026-07-27T20:10:33.468303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b3aa5b67741e7c5e elected leader b3aa5b67741e7c5e at term 2"} {"level":"info","ts":"2026-07-27T20:10:33.470228Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b3aa5b67741e7c5e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.31:2379]}","request-path":"/0/members/b3aa5b67741e7c5e/attributes","cluster-id":"9896be1db1fb000","publish-timeout":"7s"} {"level":"info","ts":"2026-07-27T20:10:33.470255Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T20:10:33.470278Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:10:33.470547Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T20:10:33.471253Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9896be1db1fb000","local-member-id":"b3aa5b67741e7c5e","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:10:33.47137Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:10:33.471405Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:10:33.471656Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-27T20:10:33.471681Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-27T20:10:33.471997Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-27T20:10:33.47669Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.31:2379"} {"level":"warn","ts":"2026-07-27T20:10:35.123639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.444651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-27T20:10:35.123884Z","caller":"traceutil/trace.go:171","msg":"trace[1638084618] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"283.423908ms","start":"2026-07-27T20:10:34.84044Z","end":"2026-07-27T20:10:35.123864Z","steps":["trace[1638084618] 'process raft request' (duration: 181.882823ms)","trace[1638084618] 'compare' (duration: 100.251968ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:10:35.396413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.179388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:4"} {"level":"info","ts":"2026-07-27T20:10:35.396656Z","caller":"traceutil/trace.go:171","msg":"trace[1288229912] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"553.862901ms","start":"2026-07-27T20:10:34.842749Z","end":"2026-07-27T20:10:35.396612Z","steps":["trace[1288229912] 'process raft request' (duration: 389.416987ms)","trace[1288229912] 'compare' (duration: 164.104946ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:10:35.39687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:34.842736Z","time spent":"554.013994ms","remote":"127.0.0.1:43940","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:10:35.396973Z","caller":"traceutil/trace.go:171","msg":"trace[1760016076] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"553.605936ms","start":"2026-07-27T20:10:34.843332Z","end":"2026-07-27T20:10:35.396938Z","steps":["trace[1760016076] 'process raft request' (duration: 553.185917ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:35.397089Z","caller":"traceutil/trace.go:171","msg":"trace[2135689017] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"553.173477ms","start":"2026-07-27T20:10:34.843872Z","end":"2026-07-27T20:10:35.397046Z","steps":["trace[2135689017] 'process raft request' (duration: 552.715588ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:35.396956Z","caller":"traceutil/trace.go:171","msg":"trace[289351330] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"552.911912ms","start":"2026-07-27T20:10:34.843961Z","end":"2026-07-27T20:10:35.396873Z","steps":["trace[289351330] 'process raft request' (duration: 552.699857ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:35.39724Z","caller":"traceutil/trace.go:171","msg":"trace[1867920523] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"553.127966ms","start":"2026-07-27T20:10:34.844104Z","end":"2026-07-27T20:10:35.397232Z","steps":["trace[1867920523] 'process raft request' (duration: 552.610535ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:35.397267Z","caller":"traceutil/trace.go:171","msg":"trace[1727238900] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"552.962143ms","start":"2026-07-27T20:10:34.844289Z","end":"2026-07-27T20:10:35.397252Z","steps":["trace[1727238900] 'process raft request' (duration: 552.523514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:35.397287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:34.844096Z","time spent":"553.173357ms","remote":"127.0.0.1:44332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T20:10:35.397317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:34.844279Z","time spent":"553.017204ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:10:35.397327Z","caller":"traceutil/trace.go:171","msg":"trace[1885846500] linearizableReadLoop","detail":"{readStateIndex:19; appliedIndex:17; }","duration":"553.865502ms","start":"2026-07-27T20:10:34.843009Z","end":"2026-07-27T20:10:35.396874Z","steps":["trace[1885846500] 'read index received' (duration: 179.317189ms)","trace[1885846500] 'applied index is now lower than readState.Index' (duration: 374.547423ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:10:35.397457Z","caller":"traceutil/trace.go:171","msg":"trace[447937552] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"552.613985ms","start":"2026-07-27T20:10:34.844832Z","end":"2026-07-27T20:10:35.397446Z","steps":["trace[447937552] 'process raft request' (duration: 552.009962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:35.397508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:34.844812Z","time spent":"552.674765ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T20:10:35.397549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.44754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-07-27T20:10:35.397614Z","caller":"traceutil/trace.go:171","msg":"trace[725299642] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"498.566392ms","start":"2026-07-27T20:10:34.899013Z","end":"2026-07-27T20:10:35.39758Z","steps":["trace[725299642] 'agreement among raft nodes before linearized reading' (duration: 498.43327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:35.397686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:34.898997Z","time spent":"498.646945ms","remote":"127.0.0.1:43940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-27T20:10:35.397686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.478292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-27T20:10:35.397717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.235612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-27T20:10:35.397757Z","caller":"traceutil/trace.go:171","msg":"trace[910665731] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:21; }","duration":"269.589795ms","start":"2026-07-27T20:10:35.128142Z","end":"2026-07-27T20:10:35.397731Z","steps":["trace[910665731] 'agreement among raft nodes before linearized reading' (duration: 269.513593ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:35.397801Z","caller":"traceutil/trace.go:171","msg":"trace[967803049] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:21; }","duration":"202.383106ms","start":"2026-07-27T20:10:35.195396Z","end":"2026-07-27T20:10:35.397779Z","steps":["trace[967803049] 'agreement among raft nodes before linearized reading' (duration: 202.245743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:35.397004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.949993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-bmw69\" ","response":"range_response_count:1 size:861"} {"level":"warn","ts":"2026-07-27T20:10:35.397929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.331714ms","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-07-27T20:10:35.398092Z","caller":"traceutil/trace.go:171","msg":"trace[85177686] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:21; }","duration":"353.511238ms","start":"2026-07-27T20:10:35.04455Z","end":"2026-07-27T20:10:35.398061Z","steps":["trace[85177686] 'agreement among raft nodes before linearized reading' (duration: 353.181712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:35.397497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:34.843953Z","time spent":"553.31351ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T20:10:35.39719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:34.843853Z","time spent":"553.30077ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T20:10:35.397155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:34.84332Z","time spent":"553.767719ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T20:10:35.397947Z","caller":"traceutil/trace.go:171","msg":"trace[292776340] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-bmw69; range_end:; response_count:1; response_revision:21; }","duration":"554.927504ms","start":"2026-07-27T20:10:34.843003Z","end":"2026-07-27T20:10:35.397931Z","steps":["trace[292776340] 'agreement among raft nodes before linearized reading' (duration: 553.875852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:35.398561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:34.842941Z","time spent":"555.601237ms","remote":"127.0.0.1:44104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-bmw69\" "} {"level":"warn","ts":"2026-07-27T20:10:35.398869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:10:35.044534Z","time spent":"353.728613ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"info","ts":"2026-07-27T20:11:16.966834Z","caller":"traceutil/trace.go:171","msg":"trace[1568461729] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"122.812895ms","start":"2026-07-27T20:11:16.843998Z","end":"2026-07-27T20:11:16.966811Z","steps":["trace[1568461729] 'process raft request' (duration: 121.360574ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:11:17.686628Z","caller":"traceutil/trace.go:171","msg":"trace[1438669018] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"498.203735ms","start":"2026-07-27T20:11:17.188393Z","end":"2026-07-27T20:11:17.686597Z","steps":["trace[1438669018] 'read index received' (duration: 497.93868ms)","trace[1438669018] 'applied index is now lower than readState.Index' (duration: 263.865µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:11:17.686707Z","caller":"traceutil/trace.go:171","msg":"trace[1272796853] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"657.636894ms","start":"2026-07-27T20:11:17.02904Z","end":"2026-07-27T20:11:17.686677Z","steps":["trace[1272796853] 'process raft request' (duration: 657.377278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:17.686814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.4186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-27T20:11:17.68686Z","caller":"traceutil/trace.go:171","msg":"trace[376474333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"498.471501ms","start":"2026-07-27T20:11:17.188378Z","end":"2026-07-27T20:11:17.686849Z","steps":["trace[376474333] 'agreement among raft nodes before linearized reading' (duration: 498.315278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:17.686888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:17.188311Z","time spent":"498.571153ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-27T20:11:17.686827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:17.029023Z","time spent":"657.737245ms","remote":"127.0.0.1:44372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T20:11:17.686907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.55261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-27T20:11:17.687084Z","caller":"traceutil/trace.go:171","msg":"trace[404309303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"496.783675ms","start":"2026-07-27T20:11:17.190291Z","end":"2026-07-27T20:11:17.687075Z","steps":["trace[404309303] 'agreement among raft nodes before linearized reading' (duration: 496.483349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:17.687107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:17.190284Z","time spent":"496.816466ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-27T20:11:17.687178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.254585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pqjg2\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-27T20:11:17.687295Z","caller":"traceutil/trace.go:171","msg":"trace[1136086139] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pqjg2; range_end:; response_count:1; response_revision:592; }","duration":"461.391278ms","start":"2026-07-27T20:11:17.225865Z","end":"2026-07-27T20:11:17.687256Z","steps":["trace[1136086139] 'agreement among raft nodes before linearized reading' (duration: 460.947999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:17.68736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:11:17.225845Z","time spent":"461.50411ms","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-pqjg2\" "} {"level":"info","ts":"2026-07-27T20:11:18.228811Z","caller":"traceutil/trace.go:171","msg":"trace[336507313] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"107.123773ms","start":"2026-07-27T20:11:18.121668Z","end":"2026-07-27T20:11:18.228792Z","steps":["trace[336507313] 'process raft request' (duration: 106.653453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:18.501353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.817404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:11:18.501445Z","caller":"traceutil/trace.go:171","msg":"trace[1505566679] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:600; }","duration":"121.959587ms","start":"2026-07-27T20:11:18.379465Z","end":"2026-07-27T20:11:18.501425Z","steps":["trace[1505566679] 'range keys from in-memory index tree' (duration: 121.731882ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:06.039508Z","caller":"traceutil/trace.go:171","msg":"trace[752449192] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"119.532704ms","start":"2026-07-27T20:12:05.919944Z","end":"2026-07-27T20:12:06.039477Z","steps":["trace[752449192] 'read index received' (duration: 119.214918ms)","trace[752449192] 'applied index is now lower than readState.Index' (duration: 316.546µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:12:06.039564Z","caller":"traceutil/trace.go:171","msg":"trace[210839824] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"126.837959ms","start":"2026-07-27T20:12:05.912701Z","end":"2026-07-27T20:12:06.039539Z","steps":["trace[210839824] 'process raft request' (duration: 126.540602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:06.039749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.781689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-27T20:12:06.039794Z","caller":"traceutil/trace.go:171","msg":"trace[254631024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:966; }","duration":"119.875151ms","start":"2026-07-27T20:12:05.919905Z","end":"2026-07-27T20:12:06.03978Z","steps":["trace[254631024] 'agreement among raft nodes before linearized reading' (duration: 119.719808ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:06.260856Z","caller":"traceutil/trace.go:171","msg":"trace[191434184] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"217.080656ms","start":"2026-07-27T20:12:06.043748Z","end":"2026-07-27T20:12:06.260829Z","steps":["trace[191434184] 'process raft request' (duration: 131.802773ms)","trace[191434184] 'compare' (duration: 85.173221ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:13:01.388541Z","caller":"traceutil/trace.go:171","msg":"trace[636843828] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"103.967254ms","start":"2026-07-27T20:13:01.284537Z","end":"2026-07-27T20:13:01.388505Z","steps":["trace[636843828] 'read index received' (duration: 103.77211ms)","trace[636843828] 'applied index is now lower than readState.Index' (duration: 194.234µs)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:13:01.388762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.193179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:13:01.388815Z","caller":"traceutil/trace.go:171","msg":"trace[330974020] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1210; }","duration":"104.330271ms","start":"2026-07-27T20:13:01.284468Z","end":"2026-07-27T20:13:01.388798Z","steps":["trace[330974020] 'agreement among raft nodes before linearized reading' (duration: 104.175228ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:01.389208Z","caller":"traceutil/trace.go:171","msg":"trace[653856201] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"180.089452ms","start":"2026-07-27T20:13:01.2091Z","end":"2026-07-27T20:13:01.38919Z","steps":["trace[653856201] 'process raft request' (duration: 179.232915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:01.889073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.594801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:13:01.889191Z","caller":"traceutil/trace.go:171","msg":"trace[94387993] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"123.922182ms","start":"2026-07-27T20:13:01.765251Z","end":"2026-07-27T20:13:01.889173Z","steps":["trace[94387993] 'process raft request' (duration: 15.155948ms)","trace[94387993] 'compare' (duration: 108.445688ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:13:02.822824Z","caller":"traceutil/trace.go:171","msg":"trace[1960348392] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"103.916413ms","start":"2026-07-27T20:13:02.718884Z","end":"2026-07-27T20:13:02.822801Z","steps":["trace[1960348392] 'process raft request' (duration: 103.746289ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:02.976904Z","caller":"traceutil/trace.go:171","msg":"trace[2036307122] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"254.18971ms","start":"2026-07-27T20:13:02.722691Z","end":"2026-07-27T20:13:02.97688Z","steps":["trace[2036307122] 'process raft request' (duration: 253.887714ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:02.977024Z","caller":"traceutil/trace.go:171","msg":"trace[1708269755] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"197.508789ms","start":"2026-07-27T20:13:02.7795Z","end":"2026-07-27T20:13:02.977009Z","steps":["trace[1708269755] 'process raft request' (duration: 197.131341ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:02.977196Z","caller":"traceutil/trace.go:171","msg":"trace[356510018] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"258.385837ms","start":"2026-07-27T20:13:02.718797Z","end":"2026-07-27T20:13:02.977183Z","steps":["trace[356510018] 'process raft request' (duration: 257.588951ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:14.86366Z","caller":"traceutil/trace.go:171","msg":"trace[954542065] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"134.970575ms","start":"2026-07-27T20:13:14.728663Z","end":"2026-07-27T20:13:14.863634Z","steps":["trace[954542065] 'process raft request' (duration: 65.401354ms)","trace[954542065] 'compare' (duration: 69.466029ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:16:57.64271Z","caller":"traceutil/trace.go:171","msg":"trace[1807970153] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"226.524788ms","start":"2026-07-27T20:16:57.416156Z","end":"2026-07-27T20:16:57.64268Z","steps":["trace[1807970153] 'process raft request' (duration: 225.962276ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:16:57.643051Z","caller":"traceutil/trace.go:171","msg":"trace[580226903] linearizableReadLoop","detail":"{readStateIndex:2546; appliedIndex:2544; }","duration":"136.547187ms","start":"2026-07-27T20:16:57.506481Z","end":"2026-07-27T20:16:57.643028Z","steps":["trace[580226903] 'read index received' (duration: 135.670659ms)","trace[580226903] 'applied index is now lower than readState.Index' (duration: 875.188µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:16:57.643172Z","caller":"traceutil/trace.go:171","msg":"trace[37539576] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"224.308474ms","start":"2026-07-27T20:16:57.418842Z","end":"2026-07-27T20:16:57.643151Z","steps":["trace[37539576] 'process raft request' (duration: 223.999417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:16:57.643461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.968747ms","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:528"} {"level":"info","ts":"2026-07-27T20:16:57.643502Z","caller":"traceutil/trace.go:171","msg":"trace[574668194] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2451; }","duration":"137.064128ms","start":"2026-07-27T20:16:57.506425Z","end":"2026-07-27T20:16:57.643489Z","steps":["trace[574668194] 'agreement among raft nodes before linearized reading' (duration: 136.69093ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:17:02.857727Z","caller":"traceutil/trace.go:171","msg":"trace[549828297] transaction","detail":"{read_only:false; response_revision:2479; number_of_response:1; }","duration":"201.407317ms","start":"2026-07-27T20:17:02.656287Z","end":"2026-07-27T20:17:02.857695Z","steps":["trace[549828297] 'process raft request' (duration: 201.158732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:04.384002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8961775840393600482,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T20:17:04.863316Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.118121904s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-27T20:17:04.885564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.937203486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:17:04.885657Z","caller":"traceutil/trace.go:171","msg":"trace[156598958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2479; }","duration":"1.937347468s","start":"2026-07-27T20:17:02.948289Z","end":"2026-07-27T20:17:04.885636Z","steps":["trace[156598958] 'range keys from in-memory index tree' (duration: 1.937094494s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:04.885697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:17:02.948265Z","time spent":"1.93742282s","remote":"127.0.0.1:43826","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-07-27T20:17:04.886255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.204604ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:17:04.886363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218292371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-27T20:17:04.88639Z","caller":"traceutil/trace.go:171","msg":"trace[1673213236] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2479; }","duration":"292.356617ms","start":"2026-07-27T20:17:04.594013Z","end":"2026-07-27T20:17:04.886369Z","steps":["trace[1673213236] 'range keys from in-memory index tree' (duration: 292.186873ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:17:04.886403Z","caller":"traceutil/trace.go:171","msg":"trace[2118825145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2479; }","duration":"1.218331562s","start":"2026-07-27T20:17:03.66806Z","end":"2026-07-27T20:17:04.886392Z","steps":["trace[2118825145] 'range keys from in-memory index tree' (duration: 1.218119629s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:04.886436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:17:03.668047Z","time spent":"1.218381024s","remote":"127.0.0.1:44120","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-07-27T20:17:04.886548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167323325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-27T20:17:04.886608Z","caller":"traceutil/trace.go:171","msg":"trace[1340772508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2479; }","duration":"1.167428007s","start":"2026-07-27T20:17:03.719163Z","end":"2026-07-27T20:17:04.886591Z","steps":["trace[1340772508] 'range keys from in-memory index tree' (duration: 1.167102319s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:04.886666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:17:03.719141Z","time spent":"1.167503979s","remote":"127.0.0.1:44120","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-07-27T20:17:04.886811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216795111s","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-07-27T20:17:04.886845Z","caller":"traceutil/trace.go:171","msg":"trace[208387965] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2479; }","duration":"1.216868152s","start":"2026-07-27T20:17:03.669967Z","end":"2026-07-27T20:17:04.886836Z","steps":["trace[208387965] 'range keys from in-memory index tree' (duration: 1.216633307s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:04.886937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:17:03.669945Z","time spent":"1.216919824s","remote":"127.0.0.1:44120","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-07-27T20:17:04.88719Z","caller":"traceutil/trace.go:171","msg":"trace[1225885563] linearizableReadLoop","detail":"{readStateIndex:2576; appliedIndex:2575; }","duration":"1.003567324s","start":"2026-07-27T20:17:03.883603Z","end":"2026-07-27T20:17:04.88717Z","steps":["trace[1225885563] 'read index received' (duration: 979.955514ms)","trace[1225885563] 'applied index is now lower than readState.Index' (duration: 23.61063ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:17:04.8879Z","caller":"traceutil/trace.go:171","msg":"trace[1091930735] transaction","detail":"{read_only:false; response_revision:2480; number_of_response:1; }","duration":"1.142764206s","start":"2026-07-27T20:17:03.745103Z","end":"2026-07-27T20:17:04.887867Z","steps":["trace[1091930735] 'process raft request' (duration: 1.118558383s)","trace[1091930735] 'compare' (duration: 23.372305ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:17:04.88809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004445042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-27T20:17:04.888209Z","caller":"traceutil/trace.go:171","msg":"trace[2032356128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2480; }","duration":"1.004637406s","start":"2026-07-27T20:17:03.883537Z","end":"2026-07-27T20:17:04.888175Z","steps":["trace[2032356128] 'agreement among raft nodes before linearized reading' (duration: 1.00433909s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:04.888284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:17:03.883517Z","time spent":"1.004750858s","remote":"127.0.0.1:44120","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-07-27T20:17:04.888451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:17:03.745093Z","time spent":"1.142903758s","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:17:04.889318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.98353ms","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-07-27T20:17:04.889372Z","caller":"traceutil/trace.go:171","msg":"trace[1421015226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2480; }","duration":"413.070531ms","start":"2026-07-27T20:17:04.476284Z","end":"2026-07-27T20:17:04.889355Z","steps":["trace[1421015226] 'agreement among raft nodes before linearized reading' (duration: 412.413238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:04.889408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:17:04.476265Z","time spent":"413.135113ms","remote":"127.0.0.1:44120","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-07-27T20:17:04.889481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.188188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-07-27T20:17:04.889666Z","caller":"traceutil/trace.go:171","msg":"trace[652056121] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2480; }","duration":"306.438403ms","start":"2026-07-27T20:17:04.583162Z","end":"2026-07-27T20:17:04.889601Z","steps":["trace[652056121] 'agreement among raft nodes before linearized reading' (duration: 305.534765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:04.889814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:17:04.583143Z","time spent":"306.623347ms","remote":"127.0.0.1:43930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":644,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-27T20:17:05.086147Z","caller":"traceutil/trace.go:171","msg":"trace[239071794] transaction","detail":"{read_only:false; response_revision:2482; number_of_response:1; }","duration":"192.554626ms","start":"2026-07-27T20:17:04.893544Z","end":"2026-07-27T20:17:05.086099Z","steps":["trace[239071794] 'process raft request' (duration: 171.08925ms)","trace[239071794] 'compare' (duration: 21.208221ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:17:05.086287Z","caller":"traceutil/trace.go:171","msg":"trace[1527108583] linearizableReadLoop","detail":"{readStateIndex:2581; appliedIndex:2577; }","duration":"138.597938ms","start":"2026-07-27T20:17:04.947658Z","end":"2026-07-27T20:17:05.086256Z","steps":["trace[1527108583] 'read index received' (duration: 117.062491ms)","trace[1527108583] 'applied index is now lower than readState.Index' (duration: 21.533987ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:17:05.086465Z","caller":"traceutil/trace.go:171","msg":"trace[523886938] transaction","detail":"{read_only:false; response_revision:2484; number_of_response:1; }","duration":"191.653999ms","start":"2026-07-27T20:17:04.8948Z","end":"2026-07-27T20:17:05.086454Z","steps":["trace[523886938] 'process raft request' (duration: 191.347342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:05.086467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.993194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:17:05.086509Z","caller":"traceutil/trace.go:171","msg":"trace[1056425015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2485; }","duration":"189.081647ms","start":"2026-07-27T20:17:04.897414Z","end":"2026-07-27T20:17:05.086495Z","steps":["trace[1056425015] 'agreement among raft nodes before linearized reading' (duration: 188.977445ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:17:05.086561Z","caller":"traceutil/trace.go:171","msg":"trace[1900017418] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"192.16361ms","start":"2026-07-27T20:17:04.894373Z","end":"2026-07-27T20:17:05.086536Z","steps":["trace[1900017418] 'process raft request' (duration: 191.648758ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:17:05.086706Z","caller":"traceutil/trace.go:171","msg":"trace[1747790843] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"191.767541ms","start":"2026-07-27T20:17:04.894929Z","end":"2026-07-27T20:17:05.086696Z","steps":["trace[1747790843] 'process raft request' (duration: 191.27413ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:17:05.126578Z","caller":"traceutil/trace.go:171","msg":"trace[91380187] transaction","detail":"{read_only:false; response_revision:2486; number_of_response:1; }","duration":"174.409427ms","start":"2026-07-27T20:17:04.952126Z","end":"2026-07-27T20:17:05.126535Z","steps":["trace[91380187] 'process raft request' (duration: 174.227054ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:17:06.886417Z","caller":"traceutil/trace.go:171","msg":"trace[1945225061] transaction","detail":"{read_only:false; response_revision:2489; number_of_response:1; }","duration":"247.749149ms","start":"2026-07-27T20:17:06.638627Z","end":"2026-07-27T20:17:06.886376Z","steps":["trace[1945225061] 'process raft request' (duration: 247.551335ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:17:07.033256Z","caller":"traceutil/trace.go:171","msg":"trace[377518778] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"142.716042ms","start":"2026-07-27T20:17:06.890512Z","end":"2026-07-27T20:17:07.033228Z","steps":["trace[377518778] 'process raft request' (duration: 98.934062ms)","trace[377518778] 'compare' (duration: 43.597106ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:17:07.033454Z","caller":"traceutil/trace.go:171","msg":"trace[1838712775] transaction","detail":"{read_only:false; response_revision:2491; number_of_response:1; }","duration":"138.432906ms","start":"2026-07-27T20:17:06.895011Z","end":"2026-07-27T20:17:07.033444Z","steps":["trace[1838712775] 'process raft request' (duration: 138.17401ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:17:07.293548Z","caller":"traceutil/trace.go:171","msg":"trace[1006062903] transaction","detail":"{read_only:false; response_revision:2494; number_of_response:1; }","duration":"198.545578ms","start":"2026-07-27T20:17:07.094976Z","end":"2026-07-27T20:17:07.293522Z","steps":["trace[1006062903] 'process raft request' (duration: 186.481363ms)","trace[1006062903] 'compare' (duration: 11.719348ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:17:07.293839Z","caller":"traceutil/trace.go:171","msg":"trace[1780532315] transaction","detail":"{read_only:false; response_revision:2495; number_of_response:1; }","duration":"198.721493ms","start":"2026-07-27T20:17:07.095082Z","end":"2026-07-27T20:17:07.293804Z","steps":["trace[1780532315] 'process raft request' (duration: 198.352924ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:17:07.29419Z","caller":"traceutil/trace.go:171","msg":"trace[1748492512] linearizableReadLoop","detail":"{readStateIndex:2593; appliedIndex:2590; }","duration":"124.103065ms","start":"2026-07-27T20:17:07.170074Z","end":"2026-07-27T20:17:07.294177Z","steps":["trace[1748492512] 'read index received' (duration: 111.411286ms)","trace[1748492512] 'applied index is now lower than readState.Index' (duration: 12.690469ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:17:07.294233Z","caller":"traceutil/trace.go:171","msg":"trace[1138020781] transaction","detail":"{read_only:false; response_revision:2496; number_of_response:1; }","duration":"198.829915ms","start":"2026-07-27T20:17:07.09538Z","end":"2026-07-27T20:17:07.29421Z","steps":["trace[1138020781] 'process raft request' (duration: 198.346594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:07.294401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.307459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-07-27T20:17:07.294457Z","caller":"traceutil/trace.go:171","msg":"trace[1090595200] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2496; }","duration":"124.427421ms","start":"2026-07-27T20:17:07.170011Z","end":"2026-07-27T20:17:07.294439Z","steps":["trace[1090595200] 'agreement among raft nodes before linearized reading' (duration: 124.207837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:07.540923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.242453ms","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:536"} {"level":"info","ts":"2026-07-27T20:17:07.540994Z","caller":"traceutil/trace.go:171","msg":"trace[1470947518] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2498; }","duration":"138.359215ms","start":"2026-07-27T20:17:07.402616Z","end":"2026-07-27T20:17:07.540975Z","steps":["trace[1470947518] 'range keys from in-memory index tree' (duration: 138.08479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:07.541114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.357104ms","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:510"} {"level":"info","ts":"2026-07-27T20:17:07.541245Z","caller":"traceutil/trace.go:171","msg":"trace[1967114944] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2498; }","duration":"135.547878ms","start":"2026-07-27T20:17:07.405669Z","end":"2026-07-27T20:17:07.541217Z","steps":["trace[1967114944] 'range keys from in-memory index tree' (duration: 135.093969ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:17:07.709545Z","caller":"traceutil/trace.go:171","msg":"trace[151155064] transaction","detail":"{read_only:false; response_revision:2500; number_of_response:1; }","duration":"162.928254ms","start":"2026-07-27T20:17:07.546587Z","end":"2026-07-27T20:17:07.709515Z","steps":["trace[151155064] 'process raft request' (duration: 108.216261ms)","trace[151155064] 'compare' (duration: 54.5598ms)"],"step_count":2}