{"level":"warn","ts":"2026-02-25T19:29:11.6025Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T19:29:11.602611Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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-02-25T19:29:11.602694Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T19:29:11.602703Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-02-25T19:29:11.602734Z","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-02-25T19:29:11.60346Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-02-25T19:29:11.603532Z","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.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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-02-25T19:29:11.607952Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.22685ms"} {"level":"info","ts":"2026-02-25T19:29:11.622473Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-02-25T19:29:11.622582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T19:29:11.622611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-02-25T19:29:11.622628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T19:29:11.622644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-02-25T19:29:11.622705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-02-25T19:29:11.62886Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T19:29:11.630284Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T19:29:11.631486Z","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-02-25T19:29:11.635238Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T19:29:11.636141Z","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-02-25T19:29:11.636675Z","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-02-25T19:29:11.636692Z","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-02-25T19:29:11.637172Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T19:29:11.63745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-02-25T19:29:11.641781Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-02-25T19:29:11.643894Z","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-02-25T19:29:11.644037Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-02-25T19:29:11.644047Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-02-25T19:29:11.644894Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T19:29:11.644929Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T19:29:12.022983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T19:29:12.023044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T19:29:12.023094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-02-25T19:29:12.023285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-02-25T19:29:12.023312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-02-25T19:29:12.023478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-02-25T19:29:12.023536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-02-25T19:29:12.024909Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T19:29:12.024957Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:29:12.025495Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:29:12.025965Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:29:12.026231Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T19:29:12.026311Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T19:29:12.026517Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:29:12.02669Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:29:12.02678Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:29:12.029785Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T19:29:12.030518Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"warn","ts":"2026-02-25T19:29:15.149785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.332203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T19:29:15.149981Z","caller":"traceutil/trace.go:171","msg":"trace[102849619] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"547.501892ms","start":"2026-02-25T19:29:14.602465Z","end":"2026-02-25T19:29:15.149967Z","steps":["trace[102849619] 'process raft request' (duration: 271.444031ms)","trace[102849619] 'compare' (duration: 275.160529ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:29:15.15005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:14.602441Z","time spent":"547.588054ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:29:15.150493Z","caller":"traceutil/trace.go:171","msg":"trace[1055248391] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"547.158884ms","start":"2026-02-25T19:29:14.60274Z","end":"2026-02-25T19:29:15.149899Z","steps":["trace[1055248391] 'read index received' (duration: 271.075653ms)","trace[1055248391] 'applied index is now lower than readState.Index' (duration: 276.082161ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:29:15.15052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.674576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:29:15.150754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.625674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:29:15.150803Z","caller":"traceutil/trace.go:171","msg":"trace[188970996] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:76; }","duration":"547.704956ms","start":"2026-02-25T19:29:14.603082Z","end":"2026-02-25T19:29:15.150786Z","steps":["trace[188970996] 'agreement among raft nodes before linearized reading' (duration: 547.579804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:15.150896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:14.602997Z","time spent":"547.88003ms","remote":"127.0.0.1:33240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"info","ts":"2026-02-25T19:29:15.150767Z","caller":"traceutil/trace.go:171","msg":"trace[976769487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:76; }","duration":"548.019434ms","start":"2026-02-25T19:29:14.602713Z","end":"2026-02-25T19:29:15.150733Z","steps":["trace[976769487] 'agreement among raft nodes before linearized reading' (duration: 547.467151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:15.151034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.123357ms","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-02-25T19:29:15.151073Z","caller":"traceutil/trace.go:171","msg":"trace[705428628] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:76; }","duration":"292.208329ms","start":"2026-02-25T19:29:14.85885Z","end":"2026-02-25T19:29:15.151058Z","steps":["trace[705428628] 'agreement among raft nodes before linearized reading' (duration: 292.139517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:15.151077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:14.602705Z","time spent":"548.357542ms","remote":"127.0.0.1:32916","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-02-25T19:29:48.464033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.063562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:29:48.464162Z","caller":"traceutil/trace.go:171","msg":"trace[168911182] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"648.117968ms","start":"2026-02-25T19:29:47.816021Z","end":"2026-02-25T19:29:48.464139Z","steps":["trace[168911182] 'read index received' (duration: 382.520554ms)","trace[168911182] 'applied index is now lower than readState.Index' (duration: 265.596044ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:29:48.464396Z","caller":"traceutil/trace.go:171","msg":"trace[1866130018] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"670.379901ms","start":"2026-02-25T19:29:47.794003Z","end":"2026-02-25T19:29:48.464383Z","steps":["trace[1866130018] 'process raft request' (duration: 404.687315ms)","trace[1866130018] 'compare' (duration: 264.927248ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:29:48.464458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:47.793971Z","time spent":"670.453593ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:29:48.46461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.823407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:29:48.464664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.64934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T19:29:48.465171Z","caller":"traceutil/trace.go:171","msg":"trace[767303690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"649.186783ms","start":"2026-02-25T19:29:47.815967Z","end":"2026-02-25T19:29:48.465154Z","steps":["trace[767303690] 'agreement among raft nodes before linearized reading' (duration: 648.569969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:48.465398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:47.815943Z","time spent":"649.413738ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T19:29:48.465116Z","caller":"traceutil/trace.go:171","msg":"trace[641253678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"174.326238ms","start":"2026-02-25T19:29:48.290754Z","end":"2026-02-25T19:29:48.46508Z","steps":["trace[641253678] 'agreement among raft nodes before linearized reading' (duration: 173.755725ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:29:49.047598Z","caller":"traceutil/trace.go:171","msg":"trace[2053589477] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"192.706202ms","start":"2026-02-25T19:29:48.854867Z","end":"2026-02-25T19:29:49.047573Z","steps":["trace[2053589477] 'process raft request' (duration: 192.550388ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:29:49.420656Z","caller":"traceutil/trace.go:171","msg":"trace[994727353] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"319.271796ms","start":"2026-02-25T19:29:49.101362Z","end":"2026-02-25T19:29:49.420634Z","steps":["trace[994727353] 'process raft request' (duration: 319.143083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:49.420826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:49.101339Z","time spent":"319.384369ms","remote":"127.0.0.1:33076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:29:50.355672Z","caller":"traceutil/trace.go:171","msg":"trace[1626596122] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"181.936597ms","start":"2026-02-25T19:29:50.173713Z","end":"2026-02-25T19:29:50.35565Z","steps":["trace[1626596122] 'read index received' (duration: 181.818664ms)","trace[1626596122] 'applied index is now lower than readState.Index' (duration: 116.273µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:29:50.355875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.134042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:29:50.356022Z","caller":"traceutil/trace.go:171","msg":"trace[917979760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"182.323437ms","start":"2026-02-25T19:29:50.17366Z","end":"2026-02-25T19:29:50.355983Z","steps":["trace[917979760] 'agreement among raft nodes before linearized reading' (duration: 182.113182ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:29:50.355821Z","caller":"traceutil/trace.go:171","msg":"trace[1872167406] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"591.27975ms","start":"2026-02-25T19:29:49.76448Z","end":"2026-02-25T19:29:50.355759Z","steps":["trace[1872167406] 'process raft request' (duration: 591.013964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:50.356431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:49.764463Z","time spent":"591.773143ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:29:50.658329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.31383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T19:29:50.658445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.744579ms","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-02-25T19:29:50.658549Z","caller":"traceutil/trace.go:171","msg":"trace[1494102807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"180.906013ms","start":"2026-02-25T19:29:50.47761Z","end":"2026-02-25T19:29:50.658516Z","steps":["trace[1494102807] 'range keys from in-memory index tree' (duration: 180.522134ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:29:50.658497Z","caller":"traceutil/trace.go:171","msg":"trace[1242329005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"189.537056ms","start":"2026-02-25T19:29:50.468922Z","end":"2026-02-25T19:29:50.658459Z","steps":["trace[1242329005] 'range keys from in-memory index tree' (duration: 189.086265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:50.66004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.766543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T19:29:50.660171Z","caller":"traceutil/trace.go:171","msg":"trace[1223213506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"183.434433ms","start":"2026-02-25T19:29:50.476702Z","end":"2026-02-25T19:29:50.660136Z","steps":["trace[1223213506] 'range keys from in-memory index tree' (duration: 181.463166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:52.477063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.208393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:29:52.477168Z","caller":"traceutil/trace.go:171","msg":"trace[2077266190] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"116.364625ms","start":"2026-02-25T19:29:52.36078Z","end":"2026-02-25T19:29:52.477144Z","steps":["trace[2077266190] 'range keys from in-memory index tree' (duration: 116.043298ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:10.888209Z","caller":"traceutil/trace.go:171","msg":"trace[1940805165] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"302.157888ms","start":"2026-02-25T19:30:10.58602Z","end":"2026-02-25T19:30:10.888178Z","steps":["trace[1940805165] 'process raft request' (duration: 301.929992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:10.88841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:10.585988Z","time spent":"302.310992ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:30:11.291726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.891565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:30:11.292173Z","caller":"traceutil/trace.go:171","msg":"trace[2038353608] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"399.821378ms","start":"2026-02-25T19:30:10.89233Z","end":"2026-02-25T19:30:11.292151Z","steps":["trace[2038353608] 'process raft request' (duration: 158.386758ms)","trace[2038353608] 'compare' (duration: 240.647659ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:11.292242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:10.892311Z","time spent":"399.90499ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:30:11.353992Z","caller":"traceutil/trace.go:171","msg":"trace[2100195023] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"454.033849ms","start":"2026-02-25T19:30:10.89993Z","end":"2026-02-25T19:30:11.353964Z","steps":["trace[2100195023] 'process raft request' (duration: 453.826233ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:11.354082Z","caller":"traceutil/trace.go:171","msg":"trace[1419826910] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"453.887055ms","start":"2026-02-25T19:30:10.900167Z","end":"2026-02-25T19:30:11.354054Z","steps":["trace[1419826910] 'process raft request' (duration: 453.722871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:11.354112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:10.899913Z","time spent":"454.139211ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:30:11.354237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:10.90016Z","time spent":"453.987577ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:30:13.158275Z","caller":"traceutil/trace.go:171","msg":"trace[2125452500] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"260.038102ms","start":"2026-02-25T19:30:12.898206Z","end":"2026-02-25T19:30:13.158244Z","steps":["trace[2125452500] 'process raft request' (duration: 259.868107ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:37.487465Z","caller":"traceutil/trace.go:171","msg":"trace[610222969] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"222.873987ms","start":"2026-02-25T19:30:37.264565Z","end":"2026-02-25T19:30:37.487439Z","steps":["trace[610222969] 'process raft request' (duration: 222.446416ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:37.855823Z","caller":"traceutil/trace.go:171","msg":"trace[1715668045] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"355.915837ms","start":"2026-02-25T19:30:37.499879Z","end":"2026-02-25T19:30:37.855795Z","steps":["trace[1715668045] 'read index received' (duration: 329.776038ms)","trace[1715668045] 'applied index is now lower than readState.Index' (duration: 26.138809ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:37.855901Z","caller":"traceutil/trace.go:171","msg":"trace[80508430] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"363.943595ms","start":"2026-02-25T19:30:37.491894Z","end":"2026-02-25T19:30:37.855838Z","steps":["trace[80508430] 'process raft request' (duration: 337.746185ms)","trace[80508430] 'compare' (duration: 26.056077ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:37.856593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.553301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T19:30:37.856682Z","caller":"traceutil/trace.go:171","msg":"trace[9339207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"344.680475ms","start":"2026-02-25T19:30:37.511981Z","end":"2026-02-25T19:30:37.856661Z","steps":["trace[9339207] 'agreement among raft nodes before linearized reading' (duration: 344.428408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:37.856353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.444051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T19:30:37.856731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:37.511968Z","time spent":"344.754197ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T19:30:37.856772Z","caller":"traceutil/trace.go:171","msg":"trace[1987004815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"356.888123ms","start":"2026-02-25T19:30:37.49984Z","end":"2026-02-25T19:30:37.856729Z","steps":["trace[1987004815] 'agreement among raft nodes before linearized reading' (duration: 356.186614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:37.856964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:37.499827Z","time spent":"357.113188ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T19:30:37.856975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:37.491873Z","time spent":"364.732126ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:30:49.743716Z","caller":"traceutil/trace.go:171","msg":"trace[1560453610] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"179.525589ms","start":"2026-02-25T19:30:49.564158Z","end":"2026-02-25T19:30:49.743684Z","steps":["trace[1560453610] 'process raft request' (duration: 179.360385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:51.78628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.523264ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T19:30:51.786431Z","caller":"traceutil/trace.go:171","msg":"trace[448563183] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"515.760814ms","start":"2026-02-25T19:30:51.270647Z","end":"2026-02-25T19:30:51.786407Z","steps":["trace[448563183] 'read index received' (duration: 9.028695ms)","trace[448563183] 'applied index is now lower than readState.Index' (duration: 506.730229ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:51.786601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.690366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:30:51.786645Z","caller":"traceutil/trace.go:171","msg":"trace[1605858076] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:893; }","duration":"186.799588ms","start":"2026-02-25T19:30:51.599831Z","end":"2026-02-25T19:30:51.78663Z","steps":["trace[1605858076] 'agreement among raft nodes before linearized reading' (duration: 186.694935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:51.786621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.950289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:30:51.787071Z","caller":"traceutil/trace.go:171","msg":"trace[2061687865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:893; }","duration":"516.477323ms","start":"2026-02-25T19:30:51.270581Z","end":"2026-02-25T19:30:51.787059Z","steps":["trace[2061687865] 'agreement among raft nodes before linearized reading' (duration: 515.927029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:51.78711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:51.270554Z","time spent":"516.544243ms","remote":"127.0.0.1:32916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:08.604679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.966202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:31:08.604794Z","caller":"traceutil/trace.go:171","msg":"trace[1447515265] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"331.184095ms","start":"2026-02-25T19:31:08.273593Z","end":"2026-02-25T19:31:08.604777Z","steps":["trace[1447515265] 'read index received' (duration: 48.342µs)","trace[1447515265] 'applied index is now lower than readState.Index' (duration: 331.134293ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:08.604884Z","caller":"traceutil/trace.go:171","msg":"trace[1844606355] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"501.946175ms","start":"2026-02-25T19:31:08.102915Z","end":"2026-02-25T19:31:08.604861Z","steps":["trace[1844606355] 'process raft request' (duration: 43.681095ms)","trace[1844606355] 'compare' (duration: 457.789337ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:08.605003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:08.102893Z","time spent":"502.038968ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:08.604901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.309048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:08.605119Z","caller":"traceutil/trace.go:171","msg":"trace[872319413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"331.564014ms","start":"2026-02-25T19:31:08.273542Z","end":"2026-02-25T19:31:08.605106Z","steps":["trace[872319413] 'agreement among raft nodes before linearized reading' (duration: 331.319438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:08.605153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:08.273518Z","time spent":"331.627206ms","remote":"127.0.0.1:32916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:31:09.064056Z","caller":"traceutil/trace.go:171","msg":"trace[311039530] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"454.465349ms","start":"2026-02-25T19:31:08.609558Z","end":"2026-02-25T19:31:09.064024Z","steps":["trace[311039530] 'process raft request' (duration: 450.401852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:09.064809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:08.609546Z","time spent":"455.173947ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:10.050034Z","caller":"traceutil/trace.go:171","msg":"trace[1997506183] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"143.692407ms","start":"2026-02-25T19:31:09.906319Z","end":"2026-02-25T19:31:10.050012Z","steps":["trace[1997506183] 'read index received' (duration: 94.96412ms)","trace[1997506183] 'applied index is now lower than readState.Index' (duration: 48.726777ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:10.050345Z","caller":"traceutil/trace.go:171","msg":"trace[76596931] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"270.769081ms","start":"2026-02-25T19:31:09.779556Z","end":"2026-02-25T19:31:10.050325Z","steps":["trace[76596931] 'process raft request' (duration: 221.715406ms)","trace[76596931] 'compare' (duration: 48.383828ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:10.050404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.051906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:31:10.050524Z","caller":"traceutil/trace.go:171","msg":"trace[1942791881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"144.2269ms","start":"2026-02-25T19:31:09.906265Z","end":"2026-02-25T19:31:10.050492Z","steps":["trace[1942791881] 'agreement among raft nodes before linearized reading' (duration: 143.879721ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:10.521577Z","caller":"traceutil/trace.go:171","msg":"trace[302245448] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"465.882337ms","start":"2026-02-25T19:31:10.055663Z","end":"2026-02-25T19:31:10.521545Z","steps":["trace[302245448] 'process raft request' (duration: 465.63294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:10.521765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:10.055631Z","time spent":"466.040571ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:10.556849Z","caller":"traceutil/trace.go:171","msg":"trace[482407448] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"499.85163ms","start":"2026-02-25T19:31:10.056964Z","end":"2026-02-25T19:31:10.556815Z","steps":["trace[482407448] 'process raft request' (duration: 499.626615ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:10.557102Z","caller":"traceutil/trace.go:171","msg":"trace[1708825203] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"498.492684ms","start":"2026-02-25T19:31:10.058566Z","end":"2026-02-25T19:31:10.557059Z","steps":["trace[1708825203] 'process raft request' (duration: 498.198867ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:10.557144Z","caller":"traceutil/trace.go:171","msg":"trace[1373020129] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1005; }","duration":"484.549412ms","start":"2026-02-25T19:31:10.07258Z","end":"2026-02-25T19:31:10.55713Z","steps":["trace[1373020129] 'read index received' (duration: 448.861484ms)","trace[1373020129] 'applied index is now lower than readState.Index' (duration: 35.687198ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:10.557231Z","caller":"traceutil/trace.go:171","msg":"trace[457709901] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"389.737016ms","start":"2026-02-25T19:31:10.167485Z","end":"2026-02-25T19:31:10.557222Z","steps":["trace[457709901] 'process raft request' (duration: 389.552481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:10.557248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:10.058545Z","time spent":"498.611498ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:10.557248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:10.056951Z","time spent":"499.990763ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:10.557341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:10.167461Z","time spent":"389.795447ms","remote":"127.0.0.1:32972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:10.557461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.847429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:10.557536Z","caller":"traceutil/trace.go:171","msg":"trace[1512428353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"384.976762ms","start":"2026-02-25T19:31:10.172542Z","end":"2026-02-25T19:31:10.557519Z","steps":["trace[1512428353] 'agreement among raft nodes before linearized reading' (duration: 384.779717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:10.557522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.913101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-25T19:31:10.557573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:10.17253Z","time spent":"385.037044ms","remote":"127.0.0.1:32916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:10.557509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.878858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:10.557662Z","caller":"traceutil/trace.go:171","msg":"trace[657420854] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"195.482004ms","start":"2026-02-25T19:31:10.362165Z","end":"2026-02-25T19:31:10.557647Z","steps":["trace[657420854] 'process raft request' (duration: 194.904549ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:10.557693Z","caller":"traceutil/trace.go:171","msg":"trace[1811161653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"285.175126ms","start":"2026-02-25T19:31:10.272496Z","end":"2026-02-25T19:31:10.557671Z","steps":["trace[1811161653] 'agreement among raft nodes before linearized reading' (duration: 284.823987ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:10.557604Z","caller":"traceutil/trace.go:171","msg":"trace[294347445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"485.040255ms","start":"2026-02-25T19:31:10.072546Z","end":"2026-02-25T19:31:10.557586Z","steps":["trace[294347445] 'agreement among raft nodes before linearized reading' (duration: 484.736717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:10.557852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:10.072535Z","time spent":"485.303282ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T19:31:10.55723Z","caller":"traceutil/trace.go:171","msg":"trace[1686027552] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"498.461114ms","start":"2026-02-25T19:31:10.058668Z","end":"2026-02-25T19:31:10.557129Z","steps":["trace[1686027552] 'process raft request' (duration: 498.29409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:10.558043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:10.058644Z","time spent":"499.351597ms","remote":"127.0.0.1:32972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:31:10.722609Z","caller":"traceutil/trace.go:171","msg":"trace[649470881] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1011; }","duration":"113.635915ms","start":"2026-02-25T19:31:10.608946Z","end":"2026-02-25T19:31:10.722582Z","steps":["trace[649470881] 'read index received' (duration: 4.646991ms)","trace[649470881] 'applied index is now lower than readState.Index' (duration: 108.987444ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:10.722874Z","caller":"traceutil/trace.go:171","msg":"trace[1778779825] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"156.462489ms","start":"2026-02-25T19:31:10.566392Z","end":"2026-02-25T19:31:10.722855Z","steps":["trace[1778779825] 'process raft request' (duration: 156.020788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:10.722874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.880781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-25T19:31:10.722946Z","caller":"traceutil/trace.go:171","msg":"trace[1013337476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:974; }","duration":"114.035005ms","start":"2026-02-25T19:31:10.608893Z","end":"2026-02-25T19:31:10.722928Z","steps":["trace[1013337476] 'agreement among raft nodes before linearized reading' (duration: 113.797649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:11.444182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.578233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:11.44431Z","caller":"traceutil/trace.go:171","msg":"trace[192773959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"171.787477ms","start":"2026-02-25T19:31:11.272493Z","end":"2026-02-25T19:31:11.444281Z","steps":["trace[192773959] 'range keys from in-memory index tree' (duration: 171.460749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:11.84817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.085807ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T19:31:11.848278Z","caller":"traceutil/trace.go:171","msg":"trace[658126582] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"402.828266ms","start":"2026-02-25T19:31:11.445434Z","end":"2026-02-25T19:31:11.848262Z","steps":["trace[658126582] 'read index received' (duration: 32.731µs)","trace[658126582] 'applied index is now lower than readState.Index' (duration: 402.794155ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:11.848342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.901918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:31:11.84836Z","caller":"traceutil/trace.go:171","msg":"trace[2121196936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"402.96401ms","start":"2026-02-25T19:31:11.445389Z","end":"2026-02-25T19:31:11.848353Z","steps":["trace[2121196936] 'agreement among raft nodes before linearized reading' (duration: 402.909838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:11.848383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:11.445371Z","time spent":"403.006921ms","remote":"127.0.0.1:32924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:31:23.450038Z","caller":"traceutil/trace.go:171","msg":"trace[15366126] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"250.552426ms","start":"2026-02-25T19:31:23.199457Z","end":"2026-02-25T19:31:23.450009Z","steps":["trace[15366126] 'process raft request' (duration: 250.375231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:23.769235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.350543ms","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:509"} {"level":"info","ts":"2026-02-25T19:31:23.769589Z","caller":"traceutil/trace.go:171","msg":"trace[83882972] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1051; }","duration":"119.647322ms","start":"2026-02-25T19:31:23.649767Z","end":"2026-02-25T19:31:23.769414Z","steps":["trace[83882972] 'range keys from in-memory index tree' (duration: 118.957414ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:34:46.328234Z","caller":"traceutil/trace.go:171","msg":"trace[1586377099] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"235.413244ms","start":"2026-02-25T19:34:46.092723Z","end":"2026-02-25T19:34:46.328137Z","steps":["trace[1586377099] 'process raft request' (duration: 212.659567ms)","trace[1586377099] 'compare' (duration: 22.455669ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:34:46.328407Z","caller":"traceutil/trace.go:171","msg":"trace[962902137] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"231.230364ms","start":"2026-02-25T19:34:46.097135Z","end":"2026-02-25T19:34:46.328365Z","steps":["trace[962902137] 'process raft request' (duration: 231.03418ms)"],"step_count":1}