{"level":"warn","ts":"2026-05-05T12:48:45.46034Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T12:48:45.460433Z","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-05-05T12:48:45.46051Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T12:48:45.460524Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-05-05T12:48:45.460544Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T12:48:45.461173Z","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-05-05T12:48:45.46125Z","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-05-05T12:48:45.466407Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.943551ms"} {"level":"info","ts":"2026-05-05T12:48:45.477815Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-05-05T12:48:45.477904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T12:48:45.477924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-05-05T12:48:45.477937Z","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-05-05T12:48:45.477944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-05-05T12:48:45.478022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-05-05T12:48:45.491707Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T12:48:45.494883Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T12:48:45.4965Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T12:48:45.500375Z","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-05-05T12:48:45.500623Z","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-05-05T12:48:45.500941Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T12:48:45.501187Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T12:48:45.501212Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T12:48:45.50219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-05-05T12:48:45.502391Z","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-05-05T12:48:45.505787Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T12:48:45.505949Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-05-05T12:48:45.506017Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-05-05T12:48:45.506242Z","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-05-05T12:48:45.506293Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T12:48:45.678977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T12:48:45.679048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T12:48:45.67907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-05-05T12:48:45.679086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-05-05T12:48:45.679091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-05-05T12:48:45.6791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-05-05T12:48:45.679107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-05-05T12:48:45.681919Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:48:45.683367Z","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-05-05T12:48:45.683425Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:48:45.683462Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:48:45.683484Z","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-05-05T12:48:45.683659Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:48:45.684107Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:48:45.686489Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T12:48:45.68659Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T12:48:45.689272Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T12:48:45.693364Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"warn","ts":"2026-05-05T12:48:49.865195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.279977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:48:49.865361Z","caller":"traceutil/trace.go:171","msg":"trace[755141314] linearizableReadLoop","detail":"{readStateIndex:210; appliedIndex:209; }","duration":"268.849917ms","start":"2026-05-05T12:48:49.596491Z","end":"2026-05-05T12:48:49.865341Z","steps":["trace[755141314] 'read index received' (duration: 114.365235ms)","trace[755141314] 'applied index is now lower than readState.Index' (duration: 154.482011ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:48:49.865389Z","caller":"traceutil/trace.go:171","msg":"trace[962482391] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"435.309051ms","start":"2026-05-05T12:48:49.430041Z","end":"2026-05-05T12:48:49.86535Z","steps":["trace[962482391] 'process raft request' (duration: 280.872401ms)","trace[962482391] 'compare' (duration: 153.081641ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:48:49.865533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:48:49.430026Z","time spent":"435.431884ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:48:49.865572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.069383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:48:49.865604Z","caller":"traceutil/trace.go:171","msg":"trace[1843890729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:205; }","duration":"269.151066ms","start":"2026-05-05T12:48:49.596443Z","end":"2026-05-05T12:48:49.865594Z","steps":["trace[1843890729] 'agreement among raft nodes before linearized reading' (duration: 269.045883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:49.865665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.876777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:48:49.865777Z","caller":"traceutil/trace.go:171","msg":"trace[345321116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:205; }","duration":"152.057131ms","start":"2026-05-05T12:48:49.7137Z","end":"2026-05-05T12:48:49.865757Z","steps":["trace[345321116] 'agreement among raft nodes before linearized reading' (duration: 151.814774ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:15.224143Z","caller":"traceutil/trace.go:171","msg":"trace[198843281] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"106.32133ms","start":"2026-05-05T12:49:15.117766Z","end":"2026-05-05T12:49:15.224087Z","steps":["trace[198843281] 'process raft request' (duration: 95.359872ms)","trace[198843281] 'compare' (duration: 10.736452ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:49:21.374717Z","caller":"traceutil/trace.go:171","msg":"trace[1270078377] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"111.485046ms","start":"2026-05-05T12:49:21.263175Z","end":"2026-05-05T12:49:21.374661Z","steps":["trace[1270078377] 'process raft request' (duration: 95.530387ms)","trace[1270078377] 'compare' (duration: 15.757043ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:49:21.392001Z","caller":"traceutil/trace.go:171","msg":"trace[1930154358] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"128.768372ms","start":"2026-05-05T12:49:21.263199Z","end":"2026-05-05T12:49:21.391968Z","steps":["trace[1930154358] 'process raft request' (duration: 128.445513ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:21.392086Z","caller":"traceutil/trace.go:171","msg":"trace[1851780140] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"128.569286ms","start":"2026-05-05T12:49:21.263467Z","end":"2026-05-05T12:49:21.392036Z","steps":["trace[1851780140] 'process raft request' (duration: 128.361551ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:21.392334Z","caller":"traceutil/trace.go:171","msg":"trace[505842621] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"128.476934ms","start":"2026-05-05T12:49:21.263807Z","end":"2026-05-05T12:49:21.392284Z","steps":["trace[505842621] 'process raft request' (duration: 128.084683ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:21.867848Z","caller":"traceutil/trace.go:171","msg":"trace[1989802124] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"196.451586ms","start":"2026-05-05T12:49:21.67136Z","end":"2026-05-05T12:49:21.867812Z","steps":["trace[1989802124] 'process raft request' (duration: 196.216319ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:21.889405Z","caller":"traceutil/trace.go:171","msg":"trace[547776393] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"217.478307ms","start":"2026-05-05T12:49:21.671887Z","end":"2026-05-05T12:49:21.889365Z","steps":["trace[547776393] 'process raft request' (duration: 217.208519ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:23.198509Z","caller":"traceutil/trace.go:171","msg":"trace[1582016946] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"100.081446ms","start":"2026-05-05T12:49:23.098397Z","end":"2026-05-05T12:49:23.198478Z","steps":["trace[1582016946] 'process raft request' (duration: 93.325726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:37.476614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.472574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T12:49:37.477082Z","caller":"traceutil/trace.go:171","msg":"trace[1281577198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"203.983829ms","start":"2026-05-05T12:49:37.273052Z","end":"2026-05-05T12:49:37.477035Z","steps":["trace[1281577198] 'range keys from in-memory index tree' (duration: 203.229006ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:37.613056Z","caller":"traceutil/trace.go:171","msg":"trace[1868896607] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"131.528499ms","start":"2026-05-05T12:49:37.48148Z","end":"2026-05-05T12:49:37.613009Z","steps":["trace[1868896607] 'process raft request' (duration: 115.55655ms)","trace[1868896607] 'compare' (duration: 15.839146ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:11.391927Z","caller":"traceutil/trace.go:171","msg":"trace[1661377001] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"104.50462ms","start":"2026-05-05T12:50:11.287403Z","end":"2026-05-05T12:50:11.391907Z","steps":["trace[1661377001] 'process raft request' (duration: 104.196432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:34.433286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.525505ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T12:50:34.433424Z","caller":"traceutil/trace.go:171","msg":"trace[215421599] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"225.307166ms","start":"2026-05-05T12:50:34.208104Z","end":"2026-05-05T12:50:34.433411Z","steps":["trace[215421599] 'read index received' (duration: 122.587245ms)","trace[215421599] 'applied index is now lower than readState.Index' (duration: 102.718931ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:50:34.43353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.655505ms","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":"info","ts":"2026-05-05T12:50:34.433569Z","caller":"traceutil/trace.go:171","msg":"trace[407076088] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1073; }","duration":"174.738527ms","start":"2026-05-05T12:50:34.258821Z","end":"2026-05-05T12:50:34.43356Z","steps":["trace[407076088] 'agreement among raft nodes before linearized reading' (duration: 174.672366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:34.433603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.497421ms","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-05-05T12:50:34.433672Z","caller":"traceutil/trace.go:171","msg":"trace[1882359183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1073; }","duration":"225.599614ms","start":"2026-05-05T12:50:34.208054Z","end":"2026-05-05T12:50:34.433653Z","steps":["trace[1882359183] 'agreement among raft nodes before linearized reading' (duration: 225.416069ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:36.572995Z","caller":"traceutil/trace.go:171","msg":"trace[878152117] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"101.225689ms","start":"2026-05-05T12:50:36.471738Z","end":"2026-05-05T12:50:36.572963Z","steps":["trace[878152117] 'process raft request' (duration: 78.430764ms)","trace[878152117] 'compare' (duration: 22.60932ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:36.573175Z","caller":"traceutil/trace.go:171","msg":"trace[602090737] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"101.177278ms","start":"2026-05-05T12:50:36.471971Z","end":"2026-05-05T12:50:36.573148Z","steps":["trace[602090737] 'process raft request' (duration: 100.950262ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:46.929162Z","caller":"traceutil/trace.go:171","msg":"trace[650528887] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"230.087399ms","start":"2026-05-05T12:50:46.699055Z","end":"2026-05-05T12:50:46.929142Z","steps":["trace[650528887] 'process raft request' (duration: 229.980526ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:48.123909Z","caller":"traceutil/trace.go:171","msg":"trace[1376201032] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"166.791686ms","start":"2026-05-05T12:50:47.957091Z","end":"2026-05-05T12:50:48.123883Z","steps":["trace[1376201032] 'process raft request' (duration: 164.472042ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:07.713617Z","caller":"traceutil/trace.go:171","msg":"trace[994568078] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"101.007934ms","start":"2026-05-05T12:51:07.612594Z","end":"2026-05-05T12:51:07.713602Z","steps":["trace[994568078] 'read index received' (duration: 100.86939ms)","trace[994568078] 'applied index is now lower than readState.Index' (duration: 138.024µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:07.713789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.178438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:07.713815Z","caller":"traceutil/trace.go:171","msg":"trace[641743289] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1268; }","duration":"101.24471ms","start":"2026-05-05T12:51:07.612562Z","end":"2026-05-05T12:51:07.713807Z","steps":["trace[641743289] 'agreement among raft nodes before linearized reading' (duration: 101.133887ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:07.713728Z","caller":"traceutil/trace.go:171","msg":"trace[1925277750] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"197.491741ms","start":"2026-05-05T12:51:07.516196Z","end":"2026-05-05T12:51:07.713688Z","steps":["trace[1925277750] 'process raft request' (duration: 197.312296ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:07.833725Z","caller":"traceutil/trace.go:171","msg":"trace[1333795618] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"101.664682ms","start":"2026-05-05T12:51:07.73204Z","end":"2026-05-05T12:51:07.833705Z","steps":["trace[1333795618] 'read index received' (duration: 21.485458ms)","trace[1333795618] 'applied index is now lower than readState.Index' (duration: 80.173384ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:07.833817Z","caller":"traceutil/trace.go:171","msg":"trace[1184283452] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"110.777526ms","start":"2026-05-05T12:51:07.723006Z","end":"2026-05-05T12:51:07.833784Z","steps":["trace[1184283452] 'process raft request' (duration: 30.51829ms)","trace[1184283452] 'compare' (duration: 80.04888ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:07.833863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.808606ms","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-05-05T12:51:07.833891Z","caller":"traceutil/trace.go:171","msg":"trace[1692929375] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1270; }","duration":"101.880828ms","start":"2026-05-05T12:51:07.732Z","end":"2026-05-05T12:51:07.833881Z","steps":["trace[1692929375] 'agreement among raft nodes before linearized reading' (duration: 101.776285ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:07.867641Z","caller":"traceutil/trace.go:171","msg":"trace[816305844] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"122.315967ms","start":"2026-05-05T12:51:07.745279Z","end":"2026-05-05T12:51:07.867595Z","steps":["trace[816305844] 'process raft request' (duration: 122.276646ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:07.867718Z","caller":"traceutil/trace.go:171","msg":"trace[1041448440] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"132.352446ms","start":"2026-05-05T12:51:07.735332Z","end":"2026-05-05T12:51:07.867684Z","steps":["trace[1041448440] 'process raft request' (duration: 132.12568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:08.490776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.500713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:08.49086Z","caller":"traceutil/trace.go:171","msg":"trace[282105086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"112.630337ms","start":"2026-05-05T12:51:08.378211Z","end":"2026-05-05T12:51:08.490842Z","steps":["trace[282105086] 'range keys from in-memory index tree' (duration: 112.408441ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:08.869848Z","caller":"traceutil/trace.go:171","msg":"trace[1360986159] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"252.560145ms","start":"2026-05-05T12:51:08.617242Z","end":"2026-05-05T12:51:08.869802Z","steps":["trace[1360986159] 'process raft request' (duration: 252.273047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:18.659258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.397116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:18.659421Z","caller":"traceutil/trace.go:171","msg":"trace[2079630096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1339; }","duration":"280.595611ms","start":"2026-05-05T12:51:18.378797Z","end":"2026-05-05T12:51:18.659393Z","steps":["trace[2079630096] 'range keys from in-memory index tree' (duration: 280.302223ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:24.632501Z","caller":"traceutil/trace.go:171","msg":"trace[1046583881] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"157.728264ms","start":"2026-05-05T12:54:24.474728Z","end":"2026-05-05T12:54:24.632456Z","steps":["trace[1046583881] 'process raft request' (duration: 157.447776ms)"],"step_count":1}