{"level":"warn","ts":"2026-01-31T13:23:40.356246Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T13:23:40.356503Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.186:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.186:2380","--initial-cluster=instance=https://162.253.55.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.186: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-01-31T13:23:40.357342Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T13:23:40.357589Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-01-31T13:23:40.357878Z","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-01-31T13:23:40.361832Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"]} {"level":"info","ts":"2026-01-31T13:23:40.362021Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.186: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-01-31T13:23:40.368638Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.483695ms"} {"level":"info","ts":"2026-01-31T13:23:40.375548Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"612d5169b08bb96b","cluster-id":"20ec8eafba602517"} {"level":"info","ts":"2026-01-31T13:23:40.375682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=()"} {"level":"info","ts":"2026-01-31T13:23:40.375754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 0"} {"level":"info","ts":"2026-01-31T13:23:40.375825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 612d5169b08bb96b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-31T13:23:40.375888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 1"} {"level":"info","ts":"2026-01-31T13:23:40.375964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"warn","ts":"2026-01-31T13:23:40.385551Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-31T13:23:40.396202Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-31T13:23:40.401541Z","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-01-31T13:23:40.404047Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"612d5169b08bb96b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-31T13:23:40.406505Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"612d5169b08bb96b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-31T13:23:40.406698Z","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-01-31T13:23:40.406786Z","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-01-31T13:23:40.406866Z","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-01-31T13:23:40.40771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"info","ts":"2026-01-31T13:23:40.407862Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","added-peer-id":"612d5169b08bb96b","added-peer-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-01-31T13:23:40.41282Z","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-01-31T13:23:40.413512Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-01-31T13:23:40.41624Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-01-31T13:23:40.4173Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"612d5169b08bb96b","initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-31T13:23:40.417386Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-31T13:23:41.076511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b is starting a new election at term 1"} {"level":"info","ts":"2026-01-31T13:23:41.076549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became pre-candidate at term 1"} {"level":"info","ts":"2026-01-31T13:23:41.076567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgPreVoteResp from 612d5169b08bb96b at term 1"} {"level":"info","ts":"2026-01-31T13:23:41.076577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became candidate at term 2"} {"level":"info","ts":"2026-01-31T13:23:41.076583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgVoteResp from 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-01-31T13:23:41.076592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became leader at term 2"} {"level":"info","ts":"2026-01-31T13:23:41.076599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 612d5169b08bb96b elected leader 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-01-31T13:23:41.078711Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"612d5169b08bb96b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.186:2379]}","request-path":"/0/members/612d5169b08bb96b/attributes","cluster-id":"20ec8eafba602517","publish-timeout":"7s"} {"level":"info","ts":"2026-01-31T13:23:41.078733Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T13:23:41.078972Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T13:23:41.080764Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T13:23:41.080909Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-31T13:23:41.080919Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-31T13:23:41.087287Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.186:2379"} {"level":"info","ts":"2026-01-31T13:23:41.087513Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T13:23:41.087554Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T13:23:41.087753Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T13:23:41.081475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-31T13:24:07.095298Z","caller":"traceutil/trace.go:171","msg":"trace[134813417] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"169.474081ms","start":"2026-01-31T13:24:06.925803Z","end":"2026-01-31T13:24:07.095277Z","steps":["trace[134813417] 'read index received' (duration: 169.311009ms)","trace[134813417] 'applied index is now lower than readState.Index' (duration: 162.162µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:24:07.095411Z","caller":"traceutil/trace.go:171","msg":"trace[501456616] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"313.597359ms","start":"2026-01-31T13:24:06.781805Z","end":"2026-01-31T13:24:07.095403Z","steps":["trace[501456616] 'process raft request' (duration: 313.322137ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:07.095545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.056735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-31T13:24:07.095663Z","caller":"traceutil/trace.go:171","msg":"trace[1478454974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"108.200946ms","start":"2026-01-31T13:24:06.98744Z","end":"2026-01-31T13:24:07.095641Z","steps":["trace[1478454974] 'agreement among raft nodes before linearized reading' (duration: 108.036295ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:07.095846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:06.781779Z","time spent":"313.661149ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:24:07.095975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.160275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-01-31T13:24:07.09602Z","caller":"traceutil/trace.go:171","msg":"trace[1366451382] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:431; }","duration":"170.234276ms","start":"2026-01-31T13:24:06.925773Z","end":"2026-01-31T13:24:07.096007Z","steps":["trace[1366451382] 'agreement among raft nodes before linearized reading' (duration: 170.128815ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:07.096108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.827172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-31T13:24:07.096137Z","caller":"traceutil/trace.go:171","msg":"trace[64323011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:431; }","duration":"107.864583ms","start":"2026-01-31T13:24:06.988265Z","end":"2026-01-31T13:24:07.09613Z","steps":["trace[64323011] 'agreement among raft nodes before linearized reading' (duration: 107.814462ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:07.428332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.915545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T13:24:07.42846Z","caller":"traceutil/trace.go:171","msg":"trace[1364120300] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:445; }","duration":"276.25402ms","start":"2026-01-31T13:24:07.152195Z","end":"2026-01-31T13:24:07.428449Z","steps":["trace[1364120300] 'read index received' (duration: 275.113511ms)","trace[1364120300] 'applied index is now lower than readState.Index' (duration: 1.139939ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:24:07.428629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.129285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-01-31T13:24:07.428646Z","caller":"traceutil/trace.go:171","msg":"trace[84838058] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:433; }","duration":"225.166825ms","start":"2026-01-31T13:24:07.203472Z","end":"2026-01-31T13:24:07.428639Z","steps":["trace[84838058] 'agreement among raft nodes before linearized reading' (duration: 225.123455ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:24:07.428982Z","caller":"traceutil/trace.go:171","msg":"trace[2001663858] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"324.834458ms","start":"2026-01-31T13:24:07.104141Z","end":"2026-01-31T13:24:07.428975Z","steps":["trace[2001663858] 'process raft request' (duration: 47.136897ms)","trace[2001663858] 'compare' (duration: 276.741064ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:24:07.429013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:07.104123Z","time spent":"324.872898ms","remote":"127.0.0.1:38428","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-01-31T13:24:07.429109Z","caller":"traceutil/trace.go:171","msg":"trace[196218897] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"322.402889ms","start":"2026-01-31T13:24:07.106701Z","end":"2026-01-31T13:24:07.429104Z","steps":["trace[196218897] 'process raft request' (duration: 321.695874ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:07.429133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:07.106693Z","time spent":"322.42719ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:24:07.430248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.387371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:24:07.430277Z","caller":"traceutil/trace.go:171","msg":"trace[2010984427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:433; }","duration":"278.103494ms","start":"2026-01-31T13:24:07.152164Z","end":"2026-01-31T13:24:07.430268Z","steps":["trace[2010984427] 'agreement among raft nodes before linearized reading' (duration: 276.383742ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:24:11.645317Z","caller":"traceutil/trace.go:171","msg":"trace[2111580392] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"493.303672ms","start":"2026-01-31T13:24:11.151994Z","end":"2026-01-31T13:24:11.645298Z","steps":["trace[2111580392] 'read index received' (duration: 493.117981ms)","trace[2111580392] 'applied index is now lower than readState.Index' (duration: 185.151µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:24:11.645417Z","caller":"traceutil/trace.go:171","msg":"trace[2568983] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"528.465403ms","start":"2026-01-31T13:24:11.116935Z","end":"2026-01-31T13:24:11.6454Z","steps":["trace[2568983] 'process raft request' (duration: 528.238911ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:11.645504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:11.116916Z","time spent":"528.517443ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:24:11.645629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.566636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-31T13:24:11.645697Z","caller":"traceutil/trace.go:171","msg":"trace[1892246553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"204.679207ms","start":"2026-01-31T13:24:11.441Z","end":"2026-01-31T13:24:11.645679Z","steps":["trace[1892246553] 'agreement among raft nodes before linearized reading' (duration: 204.570037ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:11.645778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.323372ms","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-01-31T13:24:11.645801Z","caller":"traceutil/trace.go:171","msg":"trace[1752723825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:477; }","duration":"191.389862ms","start":"2026-01-31T13:24:11.454404Z","end":"2026-01-31T13:24:11.645794Z","steps":["trace[1752723825] 'agreement among raft nodes before linearized reading' (duration: 191.336612ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:11.645862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.880056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:24:11.645874Z","caller":"traceutil/trace.go:171","msg":"trace[515347368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"493.927576ms","start":"2026-01-31T13:24:11.151943Z","end":"2026-01-31T13:24:11.64587Z","steps":["trace[515347368] 'agreement among raft nodes before linearized reading' (duration: 493.909346ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:11.645885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:11.151923Z","time spent":"493.959487ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T13:24:11.645934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.127215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-31T13:24:11.645964Z","caller":"traceutil/trace.go:171","msg":"trace[31963802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:477; }","duration":"203.168095ms","start":"2026-01-31T13:24:11.442787Z","end":"2026-01-31T13:24:11.645955Z","steps":["trace[31963802] 'agreement among raft nodes before linearized reading' (duration: 203.114445ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:24:14.224968Z","caller":"traceutil/trace.go:171","msg":"trace[511347407] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"199.115397ms","start":"2026-01-31T13:24:14.025837Z","end":"2026-01-31T13:24:14.224952Z","steps":["trace[511347407] 'process raft request' (duration: 198.960976ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:14.649597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.308834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-31T13:24:14.649684Z","caller":"traceutil/trace.go:171","msg":"trace[597655436] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"173.67927ms","start":"2026-01-31T13:24:14.475994Z","end":"2026-01-31T13:24:14.649673Z","steps":["trace[597655436] 'read index received' (duration: 68.321645ms)","trace[597655436] 'applied index is now lower than readState.Index' (duration: 105.356565ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:24:14.649771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.773001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-01-31T13:24:14.649809Z","caller":"traceutil/trace.go:171","msg":"trace[600446120] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:529; }","duration":"173.817401ms","start":"2026-01-31T13:24:14.47597Z","end":"2026-01-31T13:24:14.649788Z","steps":["trace[600446120] 'agreement among raft nodes before linearized reading' (duration: 173.73384ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:24:14.650046Z","caller":"traceutil/trace.go:171","msg":"trace[1081833675] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"185.27301ms","start":"2026-01-31T13:24:14.464764Z","end":"2026-01-31T13:24:14.650037Z","steps":["trace[1081833675] 'process raft request' (duration: 79.529453ms)","trace[1081833675] 'compare' (duration: 105.072302ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:24:14.651265Z","caller":"traceutil/trace.go:171","msg":"trace[1586614420] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"155.590083ms","start":"2026-01-31T13:24:14.495667Z","end":"2026-01-31T13:24:14.651257Z","steps":["trace[1586614420] 'process raft request' (duration: 155.0665ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:15.651327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360944330198015119,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T13:24:15.831148Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.109237458s","expected-duration":"1s"} {"level":"info","ts":"2026-01-31T13:24:15.831518Z","caller":"traceutil/trace.go:171","msg":"trace[424639690] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.10965262s","start":"2026-01-31T13:24:14.721849Z","end":"2026-01-31T13:24:15.831502Z","steps":["trace[424639690] 'process raft request' (duration: 1.10957246s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:15.831603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:14.721833Z","time spent":"1.109723261s","remote":"127.0.0.1:38248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:24:16.069399Z","caller":"traceutil/trace.go:171","msg":"trace[359128254] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:549; }","duration":"918.453039ms","start":"2026-01-31T13:24:15.15093Z","end":"2026-01-31T13:24:16.069383Z","steps":["trace[359128254] 'read index received' (duration: 680.89402ms)","trace[359128254] 'applied index is now lower than readState.Index' (duration: 237.558379ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:24:16.070325Z","caller":"traceutil/trace.go:171","msg":"trace[1156854385] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.158770307s","start":"2026-01-31T13:24:14.911546Z","end":"2026-01-31T13:24:16.070316Z","steps":["trace[1156854385] 'process raft request' (duration: 1.156408091s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:16.070395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:14.91153Z","time spent":"1.158821128s","remote":"127.0.0.1:38248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-31T13:24:16.070509Z","caller":"traceutil/trace.go:171","msg":"trace[53636237] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.096441914s","start":"2026-01-31T13:24:14.974061Z","end":"2026-01-31T13:24:16.070502Z","steps":["trace[53636237] 'process raft request' (duration: 1.095067984s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:16.070546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:14.974042Z","time spent":"1.096480594s","remote":"127.0.0.1:38248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:24:16.07062Z","caller":"traceutil/trace.go:171","msg":"trace[1503060670] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.082836618s","start":"2026-01-31T13:24:14.987778Z","end":"2026-01-31T13:24:16.070615Z","steps":["trace[1503060670] 'process raft request' (duration: 1.081541409s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:16.070646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:14.987759Z","time spent":"1.08287377s","remote":"127.0.0.1:38248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-31T13:24:16.070718Z","caller":"traceutil/trace.go:171","msg":"trace[809276499] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"884.341235ms","start":"2026-01-31T13:24:15.18637Z","end":"2026-01-31T13:24:16.070711Z","steps":["trace[809276499] 'process raft request' (duration: 882.973675ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:16.070743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:15.186347Z","time spent":"884.383245ms","remote":"127.0.0.1:38248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-31T13:24:16.070811Z","caller":"traceutil/trace.go:171","msg":"trace[182181019] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"683.665677ms","start":"2026-01-31T13:24:15.387141Z","end":"2026-01-31T13:24:16.070806Z","steps":["trace[182181019] 'process raft request' (duration: 682.222046ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:16.070837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:15.387113Z","time spent":"683.710987ms","remote":"127.0.0.1:38248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-31T13:24:16.070915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.990191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:24:16.070935Z","caller":"traceutil/trace.go:171","msg":"trace[111354405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"920.034632ms","start":"2026-01-31T13:24:15.150895Z","end":"2026-01-31T13:24:16.070929Z","steps":["trace[111354405] 'agreement among raft nodes before linearized reading' (duration: 920.000392ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:16.070982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:15.150881Z","time spent":"920.097403ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T13:24:16.071841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.428726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T13:24:16.071894Z","caller":"traceutil/trace.go:171","msg":"trace[47302625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:539; }","duration":"598.530927ms","start":"2026-01-31T13:24:15.473352Z","end":"2026-01-31T13:24:16.071883Z","steps":["trace[47302625] 'agreement among raft nodes before linearized reading' (duration: 598.379106ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:16.07192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:15.473334Z","time spent":"598.581057ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-31T13:24:16.072049Z","caller":"traceutil/trace.go:171","msg":"trace[1901203499] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"236.605703ms","start":"2026-01-31T13:24:15.835438Z","end":"2026-01-31T13:24:16.072043Z","steps":["trace[1901203499] 'process raft request' (duration: 236.20524ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:16.07221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.769107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-01-31T13:24:16.07223Z","caller":"traceutil/trace.go:171","msg":"trace[300666656] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:539; }","duration":"287.817617ms","start":"2026-01-31T13:24:15.784407Z","end":"2026-01-31T13:24:16.072225Z","steps":["trace[300666656] 'agreement among raft nodes before linearized reading' (duration: 287.771296ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:16.072291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.263247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-31T13:24:16.072306Z","caller":"traceutil/trace.go:171","msg":"trace[507173549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"331.292308ms","start":"2026-01-31T13:24:15.74101Z","end":"2026-01-31T13:24:16.072303Z","steps":["trace[507173549] 'agreement among raft nodes before linearized reading' (duration: 331.253038ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:16.072317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:15.741003Z","time spent":"331.310918ms","remote":"127.0.0.1:38428","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":"warn","ts":"2026-01-31T13:24:16.0724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.333225ms","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-01-31T13:24:16.072416Z","caller":"traceutil/trace.go:171","msg":"trace[1591358384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"332.376155ms","start":"2026-01-31T13:24:15.740036Z","end":"2026-01-31T13:24:16.072413Z","steps":["trace[1591358384] 'agreement among raft nodes before linearized reading' (duration: 332.330645ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:16.072428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:15.740007Z","time spent":"332.418055ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T13:24:16.0725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.211173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-31T13:24:16.072516Z","caller":"traceutil/trace.go:171","msg":"trace[1837749077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"339.227093ms","start":"2026-01-31T13:24:15.733285Z","end":"2026-01-31T13:24:16.072512Z","steps":["trace[1837749077] 'agreement among raft nodes before linearized reading' (duration: 339.188002ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:16.072527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:15.733251Z","time spent":"339.273273ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T13:24:16.072595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.889167ms","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-01-31T13:24:16.072612Z","caller":"traceutil/trace.go:171","msg":"trace[600355162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"411.943787ms","start":"2026-01-31T13:24:15.660664Z","end":"2026-01-31T13:24:16.072608Z","steps":["trace[600355162] 'agreement among raft nodes before linearized reading' (duration: 411.904567ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:16.072623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:15.660645Z","time spent":"411.975367ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-31T13:24:16.072687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.491749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:24:16.0727Z","caller":"traceutil/trace.go:171","msg":"trace[86914319] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:539; }","duration":"487.537399ms","start":"2026-01-31T13:24:15.585158Z","end":"2026-01-31T13:24:16.072696Z","steps":["trace[86914319] 'agreement among raft nodes before linearized reading' (duration: 487.516469ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:16.072712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:15.585142Z","time spent":"487.567459ms","remote":"127.0.0.1:38248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-01-31T13:24:23.540662Z","caller":"traceutil/trace.go:171","msg":"trace[545875802] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"233.670298ms","start":"2026-01-31T13:24:23.306971Z","end":"2026-01-31T13:24:23.540641Z","steps":["trace[545875802] 'process raft request' (duration: 233.519017ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:24:24.03182Z","caller":"traceutil/trace.go:171","msg":"trace[356814190] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"637.892408ms","start":"2026-01-31T13:24:23.393911Z","end":"2026-01-31T13:24:24.031804Z","steps":["trace[356814190] 'process raft request' (duration: 563.730921ms)","trace[356814190] 'compare' (duration: 74.100047ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:24:24.031924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:23.393896Z","time spent":"637.971109ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:24:24.974808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.392955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T13:24:24.974861Z","caller":"traceutil/trace.go:171","msg":"trace[903926374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:581; }","duration":"683.483255ms","start":"2026-01-31T13:24:24.291367Z","end":"2026-01-31T13:24:24.97485Z","steps":["trace[903926374] 'range keys from in-memory index tree' (duration: 683.280003ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:24.974888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:24.291353Z","time spent":"683.530185ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-31T13:24:24.974954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.520619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-31T13:24:24.975047Z","caller":"traceutil/trace.go:171","msg":"trace[1275479374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"682.634409ms","start":"2026-01-31T13:24:24.292394Z","end":"2026-01-31T13:24:24.975028Z","steps":["trace[1275479374] 'range keys from in-memory index tree' (duration: 682.394197ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:24.975087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:24.292388Z","time spent":"682.69035ms","remote":"127.0.0.1:38428","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":"warn","ts":"2026-01-31T13:24:24.975123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.718323ms","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-01-31T13:24:24.975141Z","caller":"traceutil/trace.go:171","msg":"trace[201729236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"791.744173ms","start":"2026-01-31T13:24:24.183392Z","end":"2026-01-31T13:24:24.975136Z","steps":["trace[201729236] 'range keys from in-memory index tree' (duration: 791.660953ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:24.975154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:24.183388Z","time spent":"791.762363ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-31T13:24:24.975207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.846684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T13:24:24.975217Z","caller":"traceutil/trace.go:171","msg":"trace[656402563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"791.874875ms","start":"2026-01-31T13:24:24.183339Z","end":"2026-01-31T13:24:24.975214Z","steps":["trace[656402563] 'range keys from in-memory index tree' (duration: 791.749504ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:24.97523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:24.183325Z","time spent":"791.901965ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T13:24:24.975389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.377529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-31T13:24:24.975402Z","caller":"traceutil/trace.go:171","msg":"trace[1087618520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"731.41353ms","start":"2026-01-31T13:24:24.243985Z","end":"2026-01-31T13:24:24.975398Z","steps":["trace[1087618520] 'range keys from in-memory index tree' (duration: 731.319568ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:24.975413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:24.243972Z","time spent":"731.43844ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T13:24:24.975492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.161237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-31T13:24:24.975505Z","caller":"traceutil/trace.go:171","msg":"trace[2047898982] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:1; response_revision:581; }","duration":"708.173317ms","start":"2026-01-31T13:24:24.267326Z","end":"2026-01-31T13:24:24.9755Z","steps":["trace[2047898982] 'range keys from in-memory index tree' (duration: 708.087516ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:24.975518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:24.267284Z","time spent":"708.229067ms","remote":"127.0.0.1:38236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.186\" "} {"level":"warn","ts":"2026-01-31T13:24:25.6489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360944330198015250,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T13:24:25.988258Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.009818576s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-31T13:24:25.988739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:24.97835Z","time spent":"1.010384439s","remote":"127.0.0.1:38236","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-31T13:24:26.347559Z","caller":"traceutil/trace.go:171","msg":"trace[681136083] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.366136324s","start":"2026-01-31T13:24:24.981399Z","end":"2026-01-31T13:24:26.347535Z","steps":["trace[681136083] 'process raft request' (duration: 1.342450011s)","trace[681136083] 'compare' (duration: 23.329821ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:24:26.347686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:24.981387Z","time spent":"1.366227635s","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:24:26.347998Z","caller":"traceutil/trace.go:171","msg":"trace[1945152430] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.366209086s","start":"2026-01-31T13:24:24.981782Z","end":"2026-01-31T13:24:26.347991Z","steps":["trace[1945152430] 'process raft request' (duration: 1.365493601s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:26.348037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:24.981773Z","time spent":"1.366243386s","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:24:26.350739Z","caller":"traceutil/trace.go:171","msg":"trace[1301716856] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.367089392s","start":"2026-01-31T13:24:24.983637Z","end":"2026-01-31T13:24:26.350727Z","steps":["trace[1301716856] 'process raft request' (duration: 1.3638624s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:26.35104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:24.983629Z","time spent":"1.367354863s","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:24:26.353929Z","caller":"traceutil/trace.go:171","msg":"trace[52762280] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.368476841s","start":"2026-01-31T13:24:24.985441Z","end":"2026-01-31T13:24:26.353917Z","steps":["trace[52762280] 'process raft request' (duration: 1.362512093s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:26.354153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2056586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T13:24:26.354204Z","caller":"traceutil/trace.go:171","msg":"trace[2042004334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"1.205770161s","start":"2026-01-31T13:24:25.148426Z","end":"2026-01-31T13:24:26.354196Z","steps":["trace[2042004334] 'agreement among raft nodes before linearized reading' (duration: 1.20560675s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:26.354236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:25.148414Z","time spent":"1.205815371s","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T13:24:26.354177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:24.985432Z","time spent":"1.368695803s","remote":"127.0.0.1:38428","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-01-31T13:24:26.353905Z","caller":"traceutil/trace.go:171","msg":"trace[1997837029] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"336.842243ms","start":"2026-01-31T13:24:26.017027Z","end":"2026-01-31T13:24:26.353869Z","steps":["trace[1997837029] 'process raft request' (duration: 336.670912ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:26.354684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:26.017003Z","time spent":"337.623398ms","remote":"127.0.0.1:38236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:24:26.353974Z","caller":"traceutil/trace.go:171","msg":"trace[732899297] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.367463184s","start":"2026-01-31T13:24:24.986503Z","end":"2026-01-31T13:24:26.353966Z","steps":["trace[732899297] 'process raft request' (duration: 1.366058104s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:26.354975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:24.986496Z","time spent":"1.368277069s","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T13:24:26.354023Z","caller":"traceutil/trace.go:171","msg":"trace[1172303510] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:600; }","duration":"1.205563479s","start":"2026-01-31T13:24:25.148452Z","end":"2026-01-31T13:24:26.354016Z","steps":["trace[1172303510] 'read index received' (duration: 868.060062ms)","trace[1172303510] 'applied index is now lower than readState.Index' (duration: 337.502567ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:24:48.880915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.19178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T13:24:48.880986Z","caller":"traceutil/trace.go:171","msg":"trace[909435846] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"313.903178ms","start":"2026-01-31T13:24:48.567073Z","end":"2026-01-31T13:24:48.880977Z","steps":["trace[909435846] 'read index received' (duration: 30.721389ms)","trace[909435846] 'applied index is now lower than readState.Index' (duration: 283.180979ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:24:48.88122Z","caller":"traceutil/trace.go:171","msg":"trace[1855912657] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"364.105051ms","start":"2026-01-31T13:24:48.5171Z","end":"2026-01-31T13:24:48.881205Z","steps":["trace[1855912657] 'process raft request' (duration: 209.566506ms)","trace[1855912657] 'compare' (duration: 154.116827ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T13:24:48.881256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:48.517093Z","time spent":"364.147133ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T13:24:48.881383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.756907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-01-31T13:24:48.881425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.302193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T13:24:48.88143Z","caller":"traceutil/trace.go:171","msg":"trace[1986349440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:749; }","duration":"306.850711ms","start":"2026-01-31T13:24:48.574568Z","end":"2026-01-31T13:24:48.881418Z","steps":["trace[1986349440] 'agreement among raft nodes before linearized reading' (duration: 306.686744ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:24:48.881443Z","caller":"traceutil/trace.go:171","msg":"trace[1155548850] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:749; }","duration":"215.320444ms","start":"2026-01-31T13:24:48.666116Z","end":"2026-01-31T13:24:48.881437Z","steps":["trace[1155548850] 'agreement among raft nodes before linearized reading' (duration: 215.286222ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:48.881464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:48.57453Z","time spent":"306.929615ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T13:24:48.881576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.507625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-31T13:24:48.881592Z","caller":"traceutil/trace.go:171","msg":"trace[1822266898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:749; }","duration":"314.556327ms","start":"2026-01-31T13:24:48.567032Z","end":"2026-01-31T13:24:48.881588Z","steps":["trace[1822266898] 'agreement among raft nodes before linearized reading' (duration: 314.499055ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:48.881604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:48.567017Z","time spent":"314.584128ms","remote":"127.0.0.1:38428","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":"warn","ts":"2026-01-31T13:24:48.881631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.769047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T13:24:48.881649Z","caller":"traceutil/trace.go:171","msg":"trace[621023330] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:749; }","duration":"304.81022ms","start":"2026-01-31T13:24:48.576834Z","end":"2026-01-31T13:24:48.881644Z","steps":["trace[621023330] 'agreement among raft nodes before linearized reading' (duration: 304.762388ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:24:48.881675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T13:24:48.576821Z","time spent":"304.851081ms","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-31T13:25:47.248456Z","caller":"traceutil/trace.go:171","msg":"trace[616679302] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"113.313246ms","start":"2026-01-31T13:25:47.135126Z","end":"2026-01-31T13:25:47.24844Z","steps":["trace[616679302] 'read index received' (duration: 113.171343ms)","trace[616679302] 'applied index is now lower than readState.Index' (duration: 141.253µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T13:25:47.24856Z","caller":"traceutil/trace.go:171","msg":"trace[413128873] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"120.909978ms","start":"2026-01-31T13:25:47.127644Z","end":"2026-01-31T13:25:47.248554Z","steps":["trace[413128873] 'process raft request' (duration: 120.692903ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:25:47.248729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.589243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-31T13:25:47.248752Z","caller":"traceutil/trace.go:171","msg":"trace[45329650] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"113.644153ms","start":"2026-01-31T13:25:47.1351Z","end":"2026-01-31T13:25:47.248744Z","steps":["trace[45329650] 'agreement among raft nodes before linearized reading' (duration: 113.546741ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:25:47.248885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.613912ms","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-01-31T13:25:47.248941Z","caller":"traceutil/trace.go:171","msg":"trace[2099502091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"100.699084ms","start":"2026-01-31T13:25:47.14823Z","end":"2026-01-31T13:25:47.248929Z","steps":["trace[2099502091] 'agreement among raft nodes before linearized reading' (duration: 100.560861ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T13:25:47.48818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.825632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T13:25:47.488361Z","caller":"traceutil/trace.go:171","msg":"trace[229484286] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"232.142904ms","start":"2026-01-31T13:25:47.256203Z","end":"2026-01-31T13:25:47.488346Z","steps":["trace[229484286] 'process raft request' (duration: 232.094113ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T13:25:47.488368Z","caller":"traceutil/trace.go:171","msg":"trace[676390776] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"232.695747ms","start":"2026-01-31T13:25:47.255654Z","end":"2026-01-31T13:25:47.48835Z","steps":["trace[676390776] 'process raft request' (duration: 77.64304ms)","trace[676390776] 'compare' (duration: 154.499604ms)"],"step_count":2}