{"level":"warn","ts":"2026-03-13T09:50:36.97662Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T09:50:36.976733Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.62:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.62:2380","--initial-cluster=instance=https://199.19.213.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.62: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-03-13T09:50:36.976818Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T09:50:36.976837Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.62:2380"]} {"level":"info","ts":"2026-03-13T09:50:36.976866Z","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-03-13T09:50:36.977708Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"]} {"level":"info","ts":"2026-03-13T09:50:36.977809Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.62:2380"],"listen-peer-urls":["https://199.19.213.62:2380"],"advertise-client-urls":["https://199.19.213.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.62: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-03-13T09:50:36.981724Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.66486ms"} {"level":"info","ts":"2026-03-13T09:50:36.98841Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2451868e402db19e","cluster-id":"1953d1daf63e3757"} {"level":"info","ts":"2026-03-13T09:50:36.988529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T09:50:36.988555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became follower at term 0"} {"level":"info","ts":"2026-03-13T09:50:36.988611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2451868e402db19e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T09:50:36.988662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became follower at term 1"} {"level":"info","ts":"2026-03-13T09:50:36.988723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=(2617020803999183262)"} {"level":"warn","ts":"2026-03-13T09:50:36.99749Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T09:50:36.998798Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T09:50:36.999935Z","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-03-13T09:50:37.002182Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2451868e402db19e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T09:50:37.00237Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2451868e402db19e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T09:50:37.00251Z","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-03-13T09:50:37.00259Z","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-03-13T09:50:37.002605Z","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-03-13T09:50:37.003216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=(2617020803999183262)"} {"level":"info","ts":"2026-03-13T09:50:37.003348Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1953d1daf63e3757","local-member-id":"2451868e402db19e","added-peer-id":"2451868e402db19e","added-peer-peer-urls":["https://199.19.213.62:2380"]} {"level":"info","ts":"2026-03-13T09:50:37.006469Z","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-03-13T09:50:37.006681Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2451868e402db19e","initial-advertise-peer-urls":["https://199.19.213.62:2380"],"listen-peer-urls":["https://199.19.213.62:2380"],"advertise-client-urls":["https://199.19.213.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T09:50:37.006708Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T09:50:37.006791Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.62:2380"} {"level":"info","ts":"2026-03-13T09:50:37.006802Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.62:2380"} {"level":"info","ts":"2026-03-13T09:50:37.789456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T09:50:37.78951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T09:50:37.789531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e received MsgPreVoteResp from 2451868e402db19e at term 1"} {"level":"info","ts":"2026-03-13T09:50:37.789543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became candidate at term 2"} {"level":"info","ts":"2026-03-13T09:50:37.78955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e received MsgVoteResp from 2451868e402db19e at term 2"} {"level":"info","ts":"2026-03-13T09:50:37.789559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became leader at term 2"} {"level":"info","ts":"2026-03-13T09:50:37.789566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2451868e402db19e elected leader 2451868e402db19e at term 2"} {"level":"info","ts":"2026-03-13T09:50:37.793978Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:50:37.799919Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T09:50:37.799928Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2451868e402db19e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.62:2379]}","request-path":"/0/members/2451868e402db19e/attributes","cluster-id":"1953d1daf63e3757","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T09:50:37.800085Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T09:50:37.800416Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T09:50:37.800475Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T09:50:37.800573Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1953d1daf63e3757","local-member-id":"2451868e402db19e","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:50:37.800681Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:50:37.800712Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:50:37.802632Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.62:2379"} {"level":"info","ts":"2026-03-13T09:50:37.803237Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-13T09:50:39.295924Z","caller":"traceutil/trace.go:171","msg":"trace[863554996] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"203.0056ms","start":"2026-03-13T09:50:39.092894Z","end":"2026-03-13T09:50:39.2959Z","steps":["trace[863554996] 'process raft request' (duration: 202.808625ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:50:39.296108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.997399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:50:39.29617Z","caller":"traceutil/trace.go:171","msg":"trace[291388552] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:2; }","duration":"203.101272ms","start":"2026-03-13T09:50:39.09306Z","end":"2026-03-13T09:50:39.296161Z","steps":["trace[291388552] 'agreement among raft nodes before linearized reading' (duration: 202.871147ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:50:39.295927Z","caller":"traceutil/trace.go:171","msg":"trace[1057469948] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:5; }","duration":"202.837166ms","start":"2026-03-13T09:50:39.093075Z","end":"2026-03-13T09:50:39.295913Z","steps":["trace[1057469948] 'read index received' (duration: 202.830546ms)","trace[1057469948] 'applied index is now lower than readState.Index' (duration: 5.4µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:50:39.52764Z","caller":"traceutil/trace.go:171","msg":"trace[433144210] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"231.255845ms","start":"2026-03-13T09:50:39.296354Z","end":"2026-03-13T09:50:39.52761Z","steps":["trace[433144210] 'read index received' (duration: 230.57449ms)","trace[433144210] 'applied index is now lower than readState.Index' (duration: 680.135µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:50:39.527745Z","caller":"traceutil/trace.go:171","msg":"trace[105357609] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"359.712956ms","start":"2026-03-13T09:50:39.16801Z","end":"2026-03-13T09:50:39.527723Z","steps":["trace[105357609] 'process raft request' (duration: 359.010511ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:50:39.527783Z","caller":"traceutil/trace.go:171","msg":"trace[526705788] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"359.370618ms","start":"2026-03-13T09:50:39.168398Z","end":"2026-03-13T09:50:39.527768Z","steps":["trace[526705788] 'process raft request' (duration: 358.942629ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:50:39.527872Z","caller":"traceutil/trace.go:171","msg":"trace[2013026528] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"359.079682ms","start":"2026-03-13T09:50:39.168777Z","end":"2026-03-13T09:50:39.527857Z","steps":["trace[2013026528] 'process raft request' (duration: 358.596341ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:50:39.527927Z","caller":"traceutil/trace.go:171","msg":"trace[1678600166] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"359.059971ms","start":"2026-03-13T09:50:39.168857Z","end":"2026-03-13T09:50:39.527917Z","steps":["trace[1678600166] 'process raft request' (duration: 358.54972ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:50:39.528288Z","caller":"traceutil/trace.go:171","msg":"trace[1341732142] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"359.359638ms","start":"2026-03-13T09:50:39.16828Z","end":"2026-03-13T09:50:39.527639Z","steps":["trace[1341732142] 'process raft request' (duration: 358.99722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:50:39.528497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.098328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:50:39.528557Z","caller":"traceutil/trace.go:171","msg":"trace[1707884917] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"362.194421ms","start":"2026-03-13T09:50:39.166349Z","end":"2026-03-13T09:50:39.528544Z","steps":["trace[1707884917] 'agreement among raft nodes before linearized reading' (duration: 361.582298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:50:39.528616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.166339Z","time spent":"362.261342ms","remote":"127.0.0.1:38768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-13T09:50:39.528758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.016246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-13T09:50:39.528905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.167993Z","time spent":"359.806548ms","remote":"127.0.0.1:39212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T09:50:39.529213Z","caller":"traceutil/trace.go:171","msg":"trace[1702682236] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"360.31408ms","start":"2026-03-13T09:50:39.168886Z","end":"2026-03-13T09:50:39.5292Z","steps":["trace[1702682236] 'process raft request' (duration: 358.550641ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:50:39.529289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.168859Z","time spent":"360.394591ms","remote":"127.0.0.1:39212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:50:39.529237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.769626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:50:39.529378Z","caller":"traceutil/trace.go:171","msg":"trace[1063474585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:10; }","duration":"435.267788ms","start":"2026-03-13T09:50:39.09407Z","end":"2026-03-13T09:50:39.529338Z","steps":["trace[1063474585] 'agreement among raft nodes before linearized reading' (duration: 434.678494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:50:39.52943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.094064Z","time spent":"435.35498ms","remote":"127.0.0.1:38914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T09:50:39.529408Z","caller":"traceutil/trace.go:171","msg":"trace[1238742391] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"351.833812ms","start":"2026-03-13T09:50:39.177556Z","end":"2026-03-13T09:50:39.52939Z","steps":["trace[1238742391] 'process raft request' (duration: 349.9214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:50:39.529541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.17754Z","time spent":"351.935294ms","remote":"127.0.0.1:38912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:50:39.529674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.038333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:50:39.529686Z","caller":"traceutil/trace.go:171","msg":"trace[1492510340] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"346.79111ms","start":"2026-03-13T09:50:39.182879Z","end":"2026-03-13T09:50:39.52967Z","steps":["trace[1492510340] 'process raft request' (duration: 344.641153ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:50:39.529706Z","caller":"traceutil/trace.go:171","msg":"trace[1656170365] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:10; }","duration":"431.079455ms","start":"2026-03-13T09:50:39.098618Z","end":"2026-03-13T09:50:39.529698Z","steps":["trace[1656170365] 'agreement among raft nodes before linearized reading' (duration: 431.031594ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:50:39.529731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.098611Z","time spent":"431.115176ms","remote":"127.0.0.1:38728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":26,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-03-13T09:50:39.529755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.18285Z","time spent":"346.870873ms","remote":"127.0.0.1:38830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:50:39.529904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.871608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-03-13T09:50:39.529935Z","caller":"traceutil/trace.go:171","msg":"trace[727102580] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"434.914169ms","start":"2026-03-13T09:50:39.095014Z","end":"2026-03-13T09:50:39.529928Z","steps":["trace[727102580] 'agreement among raft nodes before linearized reading' (duration: 434.848288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:50:39.529955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.095008Z","time spent":"434.94214ms","remote":"127.0.0.1:38830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3192,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-13T09:50:39.529993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.138576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:50:39.530026Z","caller":"traceutil/trace.go:171","msg":"trace[1203510886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"436.182618ms","start":"2026-03-13T09:50:39.093833Z","end":"2026-03-13T09:50:39.530016Z","steps":["trace[1203510886] 'agreement among raft nodes before linearized reading' (duration: 436.132967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:50:39.530002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.617192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-13T09:50:39.530066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.093827Z","time spent":"436.233159ms","remote":"127.0.0.1:38914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-13T09:50:39.530073Z","caller":"traceutil/trace.go:171","msg":"trace[119921182] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"179.718295ms","start":"2026-03-13T09:50:39.350343Z","end":"2026-03-13T09:50:39.530062Z","steps":["trace[119921182] 'agreement among raft nodes before linearized reading' (duration: 179.627343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:50:39.530464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.168767Z","time spent":"359.140943ms","remote":"127.0.0.1:39212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:50:39.530504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.168832Z","time spent":"359.130383ms","remote":"127.0.0.1:39212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:50:39.530679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.16827Z","time spent":"360.068203ms","remote":"127.0.0.1:39126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:50:39.53138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.004825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.62\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:50:39.53143Z","caller":"traceutil/trace.go:171","msg":"trace[2073370487] range","detail":"{range_begin:/registry/masterleases/199.19.213.62; range_end:; response_count:0; response_revision:10; }","duration":"366.085947ms","start":"2026-03-13T09:50:39.165335Z","end":"2026-03-13T09:50:39.531421Z","steps":["trace[2073370487] 'agreement among raft nodes before linearized reading' (duration: 362.63326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:50:39.531462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.165309Z","time spent":"366.145657ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.19.213.62\" "} {"level":"warn","ts":"2026-03-13T09:50:39.531878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.168391Z","time spent":"359.426709ms","remote":"127.0.0.1:39212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:50:39.528887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.829629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-13T09:50:39.532401Z","caller":"traceutil/trace.go:171","msg":"trace[675946293] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"363.363418ms","start":"2026-03-13T09:50:39.169025Z","end":"2026-03-13T09:50:39.532389Z","steps":["trace[675946293] 'agreement among raft nodes before linearized reading' (duration: 359.813708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:50:39.53244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.169018Z","time spent":"363.408819ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-03-13T09:50:39.528983Z","caller":"traceutil/trace.go:171","msg":"trace[2035474266] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"310.459537ms","start":"2026-03-13T09:50:39.218457Z","end":"2026-03-13T09:50:39.528917Z","steps":["trace[2035474266] 'agreement among raft nodes before linearized reading' (duration: 310.008986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:50:39.533944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:50:39.218437Z","time spent":"315.419286ms","remote":"127.0.0.1:38768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-13T09:51:13.624387Z","caller":"traceutil/trace.go:171","msg":"trace[1503629734] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"168.357135ms","start":"2026-03-13T09:51:13.455999Z","end":"2026-03-13T09:51:13.624356Z","steps":["trace[1503629734] 'read index received' (duration: 168.15881ms)","trace[1503629734] 'applied index is now lower than readState.Index' (duration: 197.245µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:51:13.624422Z","caller":"traceutil/trace.go:171","msg":"trace[1647495764] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"190.70609ms","start":"2026-03-13T09:51:13.433689Z","end":"2026-03-13T09:51:13.624395Z","steps":["trace[1647495764] 'process raft request' (duration: 190.524735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:51:13.624853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.601701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-13T09:51:13.624958Z","caller":"traceutil/trace.go:171","msg":"trace[208862580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"168.97935ms","start":"2026-03-13T09:51:13.455962Z","end":"2026-03-13T09:51:13.624942Z","steps":["trace[208862580] 'agreement among raft nodes before linearized reading' (duration: 168.52016ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:51:13.890404Z","caller":"traceutil/trace.go:171","msg":"trace[523769154] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"191.770424ms","start":"2026-03-13T09:51:13.698609Z","end":"2026-03-13T09:51:13.89038Z","steps":["trace[523769154] 'read index received' (duration: 152.566021ms)","trace[523769154] 'applied index is now lower than readState.Index' (duration: 39.203173ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:51:13.890524Z","caller":"traceutil/trace.go:171","msg":"trace[947130569] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"260.709632ms","start":"2026-03-13T09:51:13.629802Z","end":"2026-03-13T09:51:13.890512Z","steps":["trace[947130569] 'process raft request' (duration: 221.365655ms)","trace[947130569] 'compare' (duration: 39.069049ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:51:13.890829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.199837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:51:13.890895Z","caller":"traceutil/trace.go:171","msg":"trace[1724699110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"140.271759ms","start":"2026-03-13T09:51:13.750606Z","end":"2026-03-13T09:51:13.890877Z","steps":["trace[1724699110] 'agreement among raft nodes before linearized reading' (duration: 140.171947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:51:13.89109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.47022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mlfgt\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-13T09:51:13.891149Z","caller":"traceutil/trace.go:171","msg":"trace[679249635] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mlfgt; range_end:; response_count:1; response_revision:533; }","duration":"192.572923ms","start":"2026-03-13T09:51:13.698558Z","end":"2026-03-13T09:51:13.891131Z","steps":["trace[679249635] 'agreement among raft nodes before linearized reading' (duration: 192.381828ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:51:35.063363Z","caller":"traceutil/trace.go:171","msg":"trace[1219907806] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"244.945888ms","start":"2026-03-13T09:51:34.818393Z","end":"2026-03-13T09:51:35.063339Z","steps":["trace[1219907806] 'process raft request' (duration: 244.830694ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:51:40.78198Z","caller":"traceutil/trace.go:171","msg":"trace[2027669733] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"219.343628ms","start":"2026-03-13T09:51:40.562587Z","end":"2026-03-13T09:51:40.78193Z","steps":["trace[2027669733] 'process raft request' (duration: 219.100812ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:51:57.547205Z","caller":"traceutil/trace.go:171","msg":"trace[1844633584] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"111.897973ms","start":"2026-03-13T09:51:57.435223Z","end":"2026-03-13T09:51:57.547121Z","steps":["trace[1844633584] 'process raft request' (duration: 111.730578ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:51:58.367197Z","caller":"traceutil/trace.go:171","msg":"trace[354191598] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"156.79378ms","start":"2026-03-13T09:51:58.210296Z","end":"2026-03-13T09:51:58.36709Z","steps":["trace[354191598] 'process raft request' (duration: 104.497037ms)","trace[354191598] 'compare' (duration: 52.038408ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:52:09.858456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.713455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:52:09.858554Z","caller":"traceutil/trace.go:171","msg":"trace[726261066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:881; }","duration":"108.865938ms","start":"2026-03-13T09:52:09.749667Z","end":"2026-03-13T09:52:09.858533Z","steps":["trace[726261066] 'range keys from in-memory index tree' (duration: 108.592043ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:52:11.503719Z","caller":"traceutil/trace.go:171","msg":"trace[195616020] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"164.021753ms","start":"2026-03-13T09:52:11.339671Z","end":"2026-03-13T09:52:11.503693Z","steps":["trace[195616020] 'process raft request' (duration: 163.843168ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:52:28.471132Z","caller":"traceutil/trace.go:171","msg":"trace[894701497] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"116.762704ms","start":"2026-03-13T09:52:28.354347Z","end":"2026-03-13T09:52:28.47111Z","steps":["trace[894701497] 'process raft request' (duration: 53.06853ms)","trace[894701497] 'compare' (duration: 63.602792ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:52:28.940295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.091019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:52:28.94039Z","caller":"traceutil/trace.go:171","msg":"trace[548608951] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"423.839167ms","start":"2026-03-13T09:52:28.516537Z","end":"2026-03-13T09:52:28.940376Z","steps":["trace[548608951] 'read index received' (duration: 84.33391ms)","trace[548608951] 'applied index is now lower than readState.Index' (duration: 339.503887ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:52:28.940622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.075402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-13T09:52:28.940684Z","caller":"traceutil/trace.go:171","msg":"trace[1830336489] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:959; }","duration":"424.173724ms","start":"2026-03-13T09:52:28.516495Z","end":"2026-03-13T09:52:28.940669Z","steps":["trace[1830336489] 'agreement among raft nodes before linearized reading' (duration: 423.948469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:28.94074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:52:28.516474Z","time spent":"424.249246ms","remote":"127.0.0.1:38914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-13T09:52:28.940684Z","caller":"traceutil/trace.go:171","msg":"trace[1637600281] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"424.926981ms","start":"2026-03-13T09:52:28.515465Z","end":"2026-03-13T09:52:28.940392Z","steps":["trace[1637600281] 'process raft request' (duration: 85.429814ms)","trace[1637600281] 'compare' (duration: 338.871934ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:52:28.941066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:52:28.51544Z","time spent":"425.454672ms","remote":"127.0.0.1:38914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:52:28.964215Z","caller":"traceutil/trace.go:171","msg":"trace[2110581262] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"432.684674ms","start":"2026-03-13T09:52:28.531513Z","end":"2026-03-13T09:52:28.964198Z","steps":["trace[2110581262] 'process raft request' (duration: 432.660804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:28.964321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:52:28.531497Z","time spent":"432.776976ms","remote":"127.0.0.1:38738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:52:28.964364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.270079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:52:28.964458Z","caller":"traceutil/trace.go:171","msg":"trace[104508896] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:961; }","duration":"426.411902ms","start":"2026-03-13T09:52:28.538023Z","end":"2026-03-13T09:52:28.964435Z","steps":["trace[104508896] 'agreement among raft nodes before linearized reading' (duration: 426.215908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:28.964399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.730633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T09:52:28.96452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:52:28.538008Z","time spent":"426.496205ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-03-13T09:52:28.964623Z","caller":"traceutil/trace.go:171","msg":"trace[360196946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"214.936978ms","start":"2026-03-13T09:52:28.749617Z","end":"2026-03-13T09:52:28.964554Z","steps":["trace[360196946] 'agreement among raft nodes before linearized reading' (duration: 214.63381ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:52:28.964957Z","caller":"traceutil/trace.go:171","msg":"trace[629869486] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"443.501033ms","start":"2026-03-13T09:52:28.52142Z","end":"2026-03-13T09:52:28.964921Z","steps":["trace[629869486] 'process raft request' (duration: 442.677865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:28.965156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:52:28.521398Z","time spent":"443.706057ms","remote":"127.0.0.1:38738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"}