{"level":"warn","ts":"2026-09-18T15:23:40.021068Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T15:23:40.021219Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.27: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://162.253.55.27:2380","--initial-cluster=instance=https://162.253.55.27:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.27:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.27: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-09-18T15:23:40.021308Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T15:23:40.021323Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-09-18T15:23:40.02135Z","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-09-18T15:23:40.02208Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"]} {"level":"info","ts":"2026-09-18T15:23:40.022204Z","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://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.27: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-09-18T15:23:40.029645Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.214986ms"} {"level":"info","ts":"2026-09-18T15:23:40.038181Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d9b565cb482924cf","cluster-id":"5bc3f8d4cf836b6c"} {"level":"info","ts":"2026-09-18T15:23:40.038262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T15:23:40.038292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 0"} {"level":"info","ts":"2026-09-18T15:23:40.038308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d9b565cb482924cf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T15:23:40.038325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 1"} {"level":"info","ts":"2026-09-18T15:23:40.038382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"warn","ts":"2026-09-18T15:23:40.044627Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T15:23:40.046122Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T15:23:40.047766Z","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-09-18T15:23:40.050193Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d9b565cb482924cf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T15:23:40.050956Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d9b565cb482924cf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T15:23:40.051106Z","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-09-18T15:23:40.051197Z","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-09-18T15:23:40.05122Z","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-09-18T15:23:40.056797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"info","ts":"2026-09-18T15:23:40.059373Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","added-peer-id":"d9b565cb482924cf","added-peer-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-09-18T15:23:40.06083Z","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-09-18T15:23:40.061036Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-09-18T15:23:40.061053Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-09-18T15:23:40.062048Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d9b565cb482924cf","initial-advertise-peer-urls":["https://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T15:23:40.062097Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T15:23:40.538783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T15:23:40.538863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T15:23:40.538908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgPreVoteResp from d9b565cb482924cf at term 1"} {"level":"info","ts":"2026-09-18T15:23:40.538931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became candidate at term 2"} {"level":"info","ts":"2026-09-18T15:23:40.53894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgVoteResp from d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-09-18T15:23:40.538956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became leader at term 2"} {"level":"info","ts":"2026-09-18T15:23:40.538971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d9b565cb482924cf elected leader d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-09-18T15:23:40.546904Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:23:40.555044Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d9b565cb482924cf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.27:2379]}","request-path":"/0/members/d9b565cb482924cf/attributes","cluster-id":"5bc3f8d4cf836b6c","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T15:23:40.555111Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:23:40.55549Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:23:40.555734Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T15:23:40.55576Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T15:23:40.558059Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.27:2379"} {"level":"info","ts":"2026-09-18T15:23:40.559347Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T15:23:40.566761Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:23:40.566867Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:23:40.566898Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:24:30.881208Z","caller":"traceutil/trace.go:171","msg":"trace[1845215775] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"211.587795ms","start":"2026-09-18T15:24:30.669564Z","end":"2026-09-18T15:24:30.881152Z","steps":["trace[1845215775] 'read index received' (duration: 210.606749ms)","trace[1845215775] 'applied index is now lower than readState.Index' (duration: 979.376µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:24:30.881294Z","caller":"traceutil/trace.go:171","msg":"trace[343406063] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"449.0322ms","start":"2026-09-18T15:24:30.432212Z","end":"2026-09-18T15:24:30.881244Z","steps":["trace[343406063] 'process raft request' (duration: 448.075856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:24:30.881702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.985486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:24:30.882064Z","caller":"traceutil/trace.go:171","msg":"trace[1818772378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"212.425407ms","start":"2026-09-18T15:24:30.669497Z","end":"2026-09-18T15:24:30.881922Z","steps":["trace[1818772378] 'agreement among raft nodes before linearized reading' (duration: 211.846972ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:24:30.882594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:24:30.432145Z","time spent":"449.595957ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:24:48.17827Z","caller":"traceutil/trace.go:171","msg":"trace[235679259] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"118.117681ms","start":"2026-09-18T15:24:48.060112Z","end":"2026-09-18T15:24:48.17823Z","steps":["trace[235679259] 'process raft request' (duration: 117.872525ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:25:28.449739Z","caller":"traceutil/trace.go:171","msg":"trace[686190109] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"244.632122ms","start":"2026-09-18T15:25:28.205072Z","end":"2026-09-18T15:25:28.449704Z","steps":["trace[686190109] 'read index received' (duration: 244.510759ms)","trace[686190109] 'applied index is now lower than readState.Index' (duration: 119.763µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:25:28.449932Z","caller":"traceutil/trace.go:171","msg":"trace[872778725] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"267.931757ms","start":"2026-09-18T15:25:28.181955Z","end":"2026-09-18T15:25:28.449887Z","steps":["trace[872778725] 'process raft request' (duration: 267.541896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:28.450003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.879199ms","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":"info","ts":"2026-09-18T15:25:28.450058Z","caller":"traceutil/trace.go:171","msg":"trace[1225058997] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1062; }","duration":"245.018693ms","start":"2026-09-18T15:25:28.205023Z","end":"2026-09-18T15:25:28.450041Z","steps":["trace[1225058997] 'agreement among raft nodes before linearized reading' (duration: 244.854888ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:25:28.679371Z","caller":"traceutil/trace.go:171","msg":"trace[1008893719] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"229.48969ms","start":"2026-09-18T15:25:28.449844Z","end":"2026-09-18T15:25:28.679334Z","steps":["trace[1008893719] 'read index received' (duration: 142.536375ms)","trace[1008893719] 'applied index is now lower than readState.Index' (duration: 86.951985ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:25:28.679581Z","caller":"traceutil/trace.go:171","msg":"trace[819947526] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"435.147972ms","start":"2026-09-18T15:25:28.244413Z","end":"2026-09-18T15:25:28.679561Z","steps":["trace[819947526] 'process raft request' (duration: 347.993012ms)","trace[819947526] 'compare' (duration: 86.727019ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:25:28.679673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.369316ms","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-09-18T15:25:28.679707Z","caller":"traceutil/trace.go:171","msg":"trace[1447907018] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1063; }","duration":"185.476449ms","start":"2026-09-18T15:25:28.494221Z","end":"2026-09-18T15:25:28.679697Z","steps":["trace[1447907018] 'agreement among raft nodes before linearized reading' (duration: 185.390897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:28.67973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:25:28.244382Z","time spent":"435.236964ms","remote":"127.0.0.1:40012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:25:28.67985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.224267ms","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-09-18T15:25:28.680034Z","caller":"traceutil/trace.go:171","msg":"trace[1762649625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"260.474524ms","start":"2026-09-18T15:25:28.419532Z","end":"2026-09-18T15:25:28.680007Z","steps":["trace[1762649625] 'agreement among raft nodes before linearized reading' (duration: 260.109774ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:25:48.928161Z","caller":"traceutil/trace.go:171","msg":"trace[1207588078] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"395.312708ms","start":"2026-09-18T15:25:48.532818Z","end":"2026-09-18T15:25:48.928131Z","steps":["trace[1207588078] 'process raft request' (duration: 394.912278ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:48.928365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:25:48.532774Z","time spent":"395.487993ms","remote":"127.0.0.1:40012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:25:49.009377Z","caller":"traceutil/trace.go:171","msg":"trace[625213654] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"341.447872ms","start":"2026-09-18T15:25:48.667901Z","end":"2026-09-18T15:25:49.009349Z","steps":["trace[625213654] 'read index received' (duration: 260.269257ms)","trace[625213654] 'applied index is now lower than readState.Index' (duration: 81.177345ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:25:49.009448Z","caller":"traceutil/trace.go:171","msg":"trace[899688571] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"451.279939ms","start":"2026-09-18T15:25:48.558069Z","end":"2026-09-18T15:25:49.009349Z","steps":["trace[899688571] 'process raft request' (duration: 408.029268ms)","trace[899688571] 'compare' (duration: 42.949943ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:25:49.009622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.692628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:25:49.009585Z","caller":"traceutil/trace.go:171","msg":"trace[45167642] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"222.664482ms","start":"2026-09-18T15:25:48.786881Z","end":"2026-09-18T15:25:49.009545Z","steps":["trace[45167642] 'process raft request' (duration: 222.403935ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:25:49.00966Z","caller":"traceutil/trace.go:171","msg":"trace[1708063370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1152; }","duration":"341.802873ms","start":"2026-09-18T15:25:48.667846Z","end":"2026-09-18T15:25:49.009649Z","steps":["trace[1708063370] 'agreement among raft nodes before linearized reading' (duration: 341.647108ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:49.00965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.912598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T15:25:49.009698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:25:48.667821Z","time spent":"341.870734ms","remote":"127.0.0.1:39728","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-09-18T15:25:49.009709Z","caller":"traceutil/trace.go:171","msg":"trace[1577690488] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1152; }","duration":"324.040032ms","start":"2026-09-18T15:25:48.685656Z","end":"2026-09-18T15:25:49.009696Z","steps":["trace[1577690488] 'agreement among raft nodes before linearized reading' (duration: 323.897367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:49.009733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:25:48.685617Z","time spent":"324.111414ms","remote":"127.0.0.1:40012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-09-18T15:25:49.009797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.934513ms","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-09-18T15:25:49.009817Z","caller":"traceutil/trace.go:171","msg":"trace[1267394215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1152; }","duration":"168.021826ms","start":"2026-09-18T15:25:48.841788Z","end":"2026-09-18T15:25:49.00981Z","steps":["trace[1267394215] 'agreement among raft nodes before linearized reading' (duration: 167.896722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:49.009813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.574181ms","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-09-18T15:25:49.009658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:25:48.558035Z","time spent":"451.519775ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T15:25:49.009867Z","caller":"traceutil/trace.go:171","msg":"trace[1764769904] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1152; }","duration":"131.690214ms","start":"2026-09-18T15:25:48.878161Z","end":"2026-09-18T15:25:49.009851Z","steps":["trace[1764769904] 'agreement among raft nodes before linearized reading' (duration: 131.468628ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:25:49.477772Z","caller":"traceutil/trace.go:171","msg":"trace[1685533239] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"133.348797ms","start":"2026-09-18T15:25:49.344381Z","end":"2026-09-18T15:25:49.47773Z","steps":["trace[1685533239] 'process raft request' (duration: 133.07668ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:25:49.657408Z","caller":"traceutil/trace.go:171","msg":"trace[379258200] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"173.97539ms","start":"2026-09-18T15:25:49.483364Z","end":"2026-09-18T15:25:49.657339Z","steps":["trace[379258200] 'process raft request' (duration: 132.956437ms)","trace[379258200] 'compare' (duration: 40.847278ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:26:03.139144Z","caller":"traceutil/trace.go:171","msg":"trace[1655846017] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1292; }","duration":"114.567881ms","start":"2026-09-18T15:26:03.024558Z","end":"2026-09-18T15:26:03.139126Z","steps":["trace[1655846017] 'read index received' (duration: 114.385646ms)","trace[1655846017] 'applied index is now lower than readState.Index' (duration: 181.635µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:26:03.139468Z","caller":"traceutil/trace.go:171","msg":"trace[335596403] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"161.723302ms","start":"2026-09-18T15:26:02.977736Z","end":"2026-09-18T15:26:03.139459Z","steps":["trace[335596403] 'process raft request' (duration: 161.274891ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:03.139715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.94091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:26:03.139893Z","caller":"traceutil/trace.go:171","msg":"trace[494575124] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1244; }","duration":"115.350512ms","start":"2026-09-18T15:26:03.024515Z","end":"2026-09-18T15:26:03.139865Z","steps":["trace[494575124] 'agreement among raft nodes before linearized reading' (duration: 114.849088ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:03.23925Z","caller":"traceutil/trace.go:171","msg":"trace[1716130570] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"181.116696ms","start":"2026-09-18T15:26:03.058071Z","end":"2026-09-18T15:26:03.239188Z","steps":["trace[1716130570] 'process raft request' (duration: 132.718572ms)","trace[1716130570] 'compare' (duration: 48.148858ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:26:03.239395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.709535ms","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-09-18T15:26:03.239487Z","caller":"traceutil/trace.go:171","msg":"trace[686795829] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1245; }","duration":"131.87795ms","start":"2026-09-18T15:26:03.107588Z","end":"2026-09-18T15:26:03.239466Z","steps":["trace[686795829] 'agreement among raft nodes before linearized reading' (duration: 131.598742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:03.5614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.176172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:26:03.561521Z","caller":"traceutil/trace.go:171","msg":"trace[129945870] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1294; }","duration":"306.034085ms","start":"2026-09-18T15:26:03.255469Z","end":"2026-09-18T15:26:03.561503Z","steps":["trace[129945870] 'read index received' (duration: 69.084381ms)","trace[129945870] 'applied index is now lower than readState.Index' (duration: 236.948274ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:26:03.561628Z","caller":"traceutil/trace.go:171","msg":"trace[605160933] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"316.71017ms","start":"2026-09-18T15:26:03.244856Z","end":"2026-09-18T15:26:03.561566Z","steps":["trace[605160933] 'process raft request' (duration: 161.031434ms)","trace[605160933] 'compare' (duration: 154.930576ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:26:03.561733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.26072ms","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:533"} {"level":"info","ts":"2026-09-18T15:26:03.561861Z","caller":"traceutil/trace.go:171","msg":"trace[1280510776] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1247; }","duration":"306.417544ms","start":"2026-09-18T15:26:03.255426Z","end":"2026-09-18T15:26:03.561843Z","steps":["trace[1280510776] 'agreement among raft nodes before linearized reading' (duration: 306.180428ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:03.561862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:26:03.244828Z","time spent":"316.913417ms","remote":"127.0.0.1:40012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:26:03.56193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:26:03.255412Z","time spent":"306.507726ms","remote":"127.0.0.1:40012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "}