{"level":"warn","ts":"2026-02-07T03:52:55.108888Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-07T03:52:55.109051Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43: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.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-07T03:52:55.111334Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-07T03:52:55.111442Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-07T03:52:55.111556Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-07T03:52:55.112513Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-02-07T03:52:55.11268Z","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.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-07T03:52:55.119942Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.973131ms"} {"level":"info","ts":"2026-02-07T03:52:55.143417Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-02-07T03:52:55.143522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T03:52:55.143596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-02-07T03:52:55.143662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-07T03:52:55.143714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-02-07T03:52:55.143772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-02-07T03:52:55.152982Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T03:52:55.170579Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T03:52:55.174542Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-07T03:52:55.180235Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-07T03:52:55.200452Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-07T03:52:55.200657Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-07T03:52:55.20074Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-07T03:52:55.200775Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-07T03:52:55.20315Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-07T03:52:55.203405Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-07T03:52:55.203474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-02-07T03:52:55.203486Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T03:52:55.203662Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-07T03:52:55.203872Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-07T03:52:55.20393Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-07T03:52:56.144301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T03:52:56.144373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T03:52:56.144544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-02-07T03:52:56.144578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-02-07T03:52:56.144676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-07T03:52:56.144767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-02-07T03:52:56.144859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-07T03:52:56.172164Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T03:52:56.175686Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T03:52:56.175694Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-02-07T03:52:56.176083Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T03:52:56.176573Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T03:52:56.176604Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T03:52:56.179761Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"info","ts":"2026-02-07T03:52:56.180085Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T03:52:56.180182Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T03:52:56.180233Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T03:52:56.180952Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-07T03:53:06.392248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.133572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4115"} {"level":"info","ts":"2026-02-07T03:53:06.392347Z","caller":"traceutil/trace.go:171","msg":"trace[921231262] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:391; }","duration":"464.236863ms","start":"2026-02-07T03:53:05.928091Z","end":"2026-02-07T03:53:06.392328Z","steps":["trace[921231262] 'range keys from in-memory index tree' (duration: 464.080961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:06.392393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:05.928056Z","time spent":"464.328793ms","remote":"127.0.0.1:43912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4137,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-07T03:53:06.392547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.937427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-4pqrw\" ","response":"range_response_count:1 size:4417"} {"level":"info","ts":"2026-02-07T03:53:06.392575Z","caller":"traceutil/trace.go:171","msg":"trace[919175686] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-4pqrw; range_end:; response_count:1; response_revision:391; }","duration":"124.989287ms","start":"2026-02-07T03:53:06.267576Z","end":"2026-02-07T03:53:06.392566Z","steps":["trace[919175686] 'range keys from in-memory index tree' (duration: 124.882777ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:08.529754Z","caller":"traceutil/trace.go:171","msg":"trace[291923280] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"116.393984ms","start":"2026-02-07T03:53:08.413342Z","end":"2026-02-07T03:53:08.529736Z","steps":["trace[291923280] 'process raft request' (duration: 116.250783ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:11.993517Z","caller":"traceutil/trace.go:171","msg":"trace[321691851] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"231.549895ms","start":"2026-02-07T03:53:11.761954Z","end":"2026-02-07T03:53:11.993504Z","steps":["trace[321691851] 'read index received' (duration: 231.426394ms)","trace[321691851] 'applied index is now lower than readState.Index' (duration: 123.001µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:53:11.993676Z","caller":"traceutil/trace.go:171","msg":"trace[824914240] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"281.602691ms","start":"2026-02-07T03:53:11.712068Z","end":"2026-02-07T03:53:11.993671Z","steps":["trace[824914240] 'process raft request' (duration: 281.347689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:11.993786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.820257ms","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-02-07T03:53:11.993803Z","caller":"traceutil/trace.go:171","msg":"trace[296282484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"231.873619ms","start":"2026-02-07T03:53:11.761924Z","end":"2026-02-07T03:53:11.993797Z","steps":["trace[296282484] 'agreement among raft nodes before linearized reading' (duration: 231.834679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:11.993873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.68019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T03:53:11.993885Z","caller":"traceutil/trace.go:171","msg":"trace[1422018940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"122.716051ms","start":"2026-02-07T03:53:11.871165Z","end":"2026-02-07T03:53:11.993881Z","steps":["trace[1422018940] 'agreement among raft nodes before linearized reading' (duration: 122.697041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:11.993973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.772082ms","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-02-07T03:53:11.993985Z","caller":"traceutil/trace.go:171","msg":"trace[2060003146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:413; }","duration":"101.807042ms","start":"2026-02-07T03:53:11.892174Z","end":"2026-02-07T03:53:11.993981Z","steps":["trace[2060003146] 'agreement among raft nodes before linearized reading' (duration: 101.785272ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:12.180806Z","caller":"traceutil/trace.go:171","msg":"trace[626749353] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"181.945226ms","start":"2026-02-07T03:53:11.998849Z","end":"2026-02-07T03:53:12.180794Z","steps":["trace[626749353] 'process raft request' (duration: 181.918425ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:12.18108Z","caller":"traceutil/trace.go:171","msg":"trace[1622949304] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"183.065554ms","start":"2026-02-07T03:53:11.998007Z","end":"2026-02-07T03:53:12.181073Z","steps":["trace[1622949304] 'process raft request' (duration: 180.655626ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:24.856568Z","caller":"traceutil/trace.go:171","msg":"trace[668121703] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"254.963653ms","start":"2026-02-07T03:53:24.601544Z","end":"2026-02-07T03:53:24.856508Z","steps":["trace[668121703] 'process raft request' (duration: 254.630631ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:24.947723Z","caller":"traceutil/trace.go:171","msg":"trace[65402158] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"209.51205ms","start":"2026-02-07T03:53:24.738188Z","end":"2026-02-07T03:53:24.947701Z","steps":["trace[65402158] 'read index received' (duration: 119.157459ms)","trace[65402158] 'applied index is now lower than readState.Index' (duration: 90.353491ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:53:24.948105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.845623ms","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-02-07T03:53:24.948258Z","caller":"traceutil/trace.go:171","msg":"trace[160776136] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:476; }","duration":"210.075955ms","start":"2026-02-07T03:53:24.738114Z","end":"2026-02-07T03:53:24.94819Z","steps":["trace[160776136] 'agreement among raft nodes before linearized reading' (duration: 209.694432ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:25.995229Z","caller":"traceutil/trace.go:171","msg":"trace[2074811289] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"145.990008ms","start":"2026-02-07T03:53:25.849217Z","end":"2026-02-07T03:53:25.995207Z","steps":["trace[2074811289] 'process raft request' (duration: 145.947598ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:25.995905Z","caller":"traceutil/trace.go:171","msg":"trace[748157451] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"148.716837ms","start":"2026-02-07T03:53:25.847161Z","end":"2026-02-07T03:53:25.995878Z","steps":["trace[748157451] 'process raft request' (duration: 57.07075ms)","trace[748157451] 'compare' (duration: 90.7315ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:53:26.495236Z","caller":"traceutil/trace.go:171","msg":"trace[575666624] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:493; }","duration":"243.853799ms","start":"2026-02-07T03:53:26.25136Z","end":"2026-02-07T03:53:26.495214Z","steps":["trace[575666624] 'read index received' (duration: 228.014151ms)","trace[575666624] 'applied index is now lower than readState.Index' (duration: 15.838798ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:53:26.495581Z","caller":"traceutil/trace.go:171","msg":"trace[371170795] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"433.530229ms","start":"2026-02-07T03:53:26.062031Z","end":"2026-02-07T03:53:26.495561Z","steps":["trace[371170795] 'process raft request' (duration: 417.330729ms)","trace[371170795] 'compare' (duration: 15.604776ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:53:26.49609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:26.062014Z","time spent":"433.619271ms","remote":"127.0.0.1:44084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2364,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T03:53:26.496384Z","caller":"traceutil/trace.go:171","msg":"trace[2032588622] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"431.557506ms","start":"2026-02-07T03:53:26.064815Z","end":"2026-02-07T03:53:26.496372Z","steps":["trace[2032588622] 'process raft request' (duration: 430.281428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:26.496635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:26.064799Z","time spent":"431.611297ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:53:26.496831Z","caller":"traceutil/trace.go:171","msg":"trace[1816558041] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"431.661656ms","start":"2026-02-07T03:53:26.065146Z","end":"2026-02-07T03:53:26.496808Z","steps":["trace[1816558041] 'process raft request' (duration: 430.037866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:26.496915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:26.065142Z","time spent":"431.734257ms","remote":"127.0.0.1:44084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1639,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T03:53:26.497117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.760702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-07T03:53:26.497152Z","caller":"traceutil/trace.go:171","msg":"trace[1972029720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:483; }","duration":"245.833503ms","start":"2026-02-07T03:53:26.251308Z","end":"2026-02-07T03:53:26.497142Z","steps":["trace[1972029720] 'agreement among raft nodes before linearized reading' (duration: 245.766063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:26.49729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.418589ms","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-02-07T03:53:26.497315Z","caller":"traceutil/trace.go:171","msg":"trace[1744009669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:483; }","duration":"239.492609ms","start":"2026-02-07T03:53:26.257815Z","end":"2026-02-07T03:53:26.497308Z","steps":["trace[1744009669] 'agreement among raft nodes before linearized reading' (duration: 239.447989ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:29.236444Z","caller":"traceutil/trace.go:171","msg":"trace[118060354] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"356.853544ms","start":"2026-02-07T03:53:28.879573Z","end":"2026-02-07T03:53:29.236427Z","steps":["trace[118060354] 'process raft request' (duration: 356.739924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:29.236557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:28.879563Z","time spent":"356.935895ms","remote":"127.0.0.1:44004","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-02-07T03:53:29.707845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.927152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T03:53:29.707897Z","caller":"traceutil/trace.go:171","msg":"trace[1556553511] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:520; }","duration":"351.010252ms","start":"2026-02-07T03:53:29.356872Z","end":"2026-02-07T03:53:29.707883Z","steps":["trace[1556553511] 'range keys from in-memory index tree' (duration: 350.859202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:29.70792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:29.356859Z","time spent":"351.056493ms","remote":"127.0.0.1:43792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.43\" "} {"level":"warn","ts":"2026-02-07T03:53:30.183397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:29.709166Z","time spent":"474.227461ms","remote":"127.0.0.1:43792","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-07T03:53:30.376106Z","caller":"traceutil/trace.go:171","msg":"trace[1303525794] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"174.139341ms","start":"2026-02-07T03:53:30.20194Z","end":"2026-02-07T03:53:30.37608Z","steps":["trace[1303525794] 'process raft request' (duration: 173.90316ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:38.009803Z","caller":"traceutil/trace.go:171","msg":"trace[742075145] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"397.198975ms","start":"2026-02-07T03:53:37.612577Z","end":"2026-02-07T03:53:38.009776Z","steps":["trace[742075145] 'read index received' (duration: 397.003783ms)","trace[742075145] 'applied index is now lower than readState.Index' (duration: 193.832µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:53:38.010068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.463726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fshdb\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-07T03:53:38.010113Z","caller":"traceutil/trace.go:171","msg":"trace[1222474288] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fshdb; range_end:; response_count:1; response_revision:563; }","duration":"397.547927ms","start":"2026-02-07T03:53:37.61255Z","end":"2026-02-07T03:53:38.010098Z","steps":["trace[1222474288] 'agreement among raft nodes before linearized reading' (duration: 397.345145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:38.010175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:37.612536Z","time spent":"397.627347ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16674,"request content":"key:\"/registry/pods/kube-system/cilium-fshdb\" "} {"level":"info","ts":"2026-02-07T03:53:38.010579Z","caller":"traceutil/trace.go:171","msg":"trace[1215800267] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"743.824561ms","start":"2026-02-07T03:53:37.266738Z","end":"2026-02-07T03:53:38.010562Z","steps":["trace[1215800267] 'process raft request' (duration: 742.876115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:38.010686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:37.266703Z","time spent":"743.913892ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T03:53:39.882832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.08399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T03:53:39.882891Z","caller":"traceutil/trace.go:171","msg":"trace[286677261] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"1.291406969s","start":"2026-02-07T03:53:38.591475Z","end":"2026-02-07T03:53:39.882882Z","steps":["trace[286677261] 'read index received' (duration: 414.194918ms)","trace[286677261] 'applied index is now lower than readState.Index' (duration: 877.211431ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:53:39.88299Z","caller":"traceutil/trace.go:171","msg":"trace[834277770] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.742323805s","start":"2026-02-07T03:53:38.140661Z","end":"2026-02-07T03:53:39.882985Z","steps":["trace[834277770] 'process raft request' (duration: 864.966773ms)","trace[834277770] 'compare' (duration: 876.863289ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:53:39.883019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:38.140643Z","time spent":"1.742360805s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T03:53:39.883146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2916743s","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-02-07T03:53:39.883172Z","caller":"traceutil/trace.go:171","msg":"trace[1195984583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:565; }","duration":"1.29172782s","start":"2026-02-07T03:53:38.591439Z","end":"2026-02-07T03:53:39.883167Z","steps":["trace[1195984583] 'agreement among raft nodes before linearized reading' (duration: 1.29165689s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:39.883185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:38.591425Z","time spent":"1.29175676s","remote":"127.0.0.1:44004","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-02-07T03:53:39.88326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27297009s","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-02-07T03:53:39.883272Z","caller":"traceutil/trace.go:171","msg":"trace[1922932151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"1.27301378s","start":"2026-02-07T03:53:38.610255Z","end":"2026-02-07T03:53:39.883268Z","steps":["trace[1922932151] 'agreement among raft nodes before linearized reading' (duration: 1.27297949s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:39.883286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:38.610238Z","time spent":"1.27304458s","remote":"127.0.0.1:44004","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-02-07T03:53:39.883383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.574071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T03:53:39.883406Z","caller":"traceutil/trace.go:171","msg":"trace[1877841070] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:565; }","duration":"526.610121ms","start":"2026-02-07T03:53:39.356782Z","end":"2026-02-07T03:53:39.883392Z","steps":["trace[1877841070] 'agreement among raft nodes before linearized reading' (duration: 526.574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:39.883418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:39.356758Z","time spent":"526.657441ms","remote":"127.0.0.1:43792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.43\" "} {"level":"warn","ts":"2026-02-07T03:53:39.883476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011673047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T03:53:39.883487Z","caller":"traceutil/trace.go:171","msg":"trace[1545040182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"1.011716517s","start":"2026-02-07T03:53:38.871767Z","end":"2026-02-07T03:53:39.883483Z","steps":["trace[1545040182] 'agreement among raft nodes before linearized reading' (duration: 1.011699997s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:39.883498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:38.871748Z","time spent":"1.011747067s","remote":"127.0.0.1:43720","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-02-07T03:53:39.88357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239223843s","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-02-07T03:53:39.883582Z","caller":"traceutil/trace.go:171","msg":"trace[915752679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"1.239268423s","start":"2026-02-07T03:53:38.64431Z","end":"2026-02-07T03:53:39.883578Z","steps":["trace[915752679] 'agreement among raft nodes before linearized reading' (duration: 1.239234423s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:39.883592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:38.644295Z","time spent":"1.239294553s","remote":"127.0.0.1:44004","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-02-07T03:53:42.371683Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349375548025814,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T03:53:42.871886Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349375548025814,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T03:53:43.119557Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.294121247s","expected-duration":"1s"} {"level":"info","ts":"2026-02-07T03:53:43.119907Z","caller":"traceutil/trace.go:171","msg":"trace[476034104] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.29456942s","start":"2026-02-07T03:53:41.825324Z","end":"2026-02-07T03:53:43.119893Z","steps":["trace[476034104] 'process raft request' (duration: 1.294498148s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:43.119989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:41.825308Z","time spent":"1.294643912s","remote":"127.0.0.1:43850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T03:53:43.124366Z","caller":"traceutil/trace.go:171","msg":"trace[735890251] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"1.253590325s","start":"2026-02-07T03:53:41.870763Z","end":"2026-02-07T03:53:43.124353Z","steps":["trace[735890251] 'read index received' (duration: 1.249175527s)","trace[735890251] 'applied index is now lower than readState.Index' (duration: 4.414198ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:53:43.124613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253843822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T03:53:43.124637Z","caller":"traceutil/trace.go:171","msg":"trace[2121953523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"1.253898304s","start":"2026-02-07T03:53:41.870732Z","end":"2026-02-07T03:53:43.124631Z","steps":["trace[2121953523] 'agreement among raft nodes before linearized reading' (duration: 1.253854043s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:43.124658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:41.870719Z","time spent":"1.253935025s","remote":"127.0.0.1:43734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T03:53:43.124709Z","caller":"traceutil/trace.go:171","msg":"trace[1944250716] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.286313403s","start":"2026-02-07T03:53:41.838387Z","end":"2026-02-07T03:53:43.1247Z","steps":["trace[1944250716] 'process raft request' (duration: 1.285893501s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:43.124844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:41.83837Z","time spent":"1.286400335s","remote":"127.0.0.1:44004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T03:53:43.126702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180970298s","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-02-07T03:53:43.126726Z","caller":"traceutil/trace.go:171","msg":"trace[924664324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"1.18101713s","start":"2026-02-07T03:53:41.945702Z","end":"2026-02-07T03:53:43.126719Z","steps":["trace[924664324] 'agreement among raft nodes before linearized reading' (duration: 1.180935687s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:43.126746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:41.945688Z","time spent":"1.18105528s","remote":"127.0.0.1:44004","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-02-07T03:53:43.12684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103131304s","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-02-07T03:53:43.126857Z","caller":"traceutil/trace.go:171","msg":"trace[624907039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"1.103183685s","start":"2026-02-07T03:53:42.02367Z","end":"2026-02-07T03:53:43.126853Z","steps":["trace[624907039] 'agreement among raft nodes before linearized reading' (duration: 1.103140354s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:43.12687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:42.023652Z","time spent":"1.103214886s","remote":"127.0.0.1:44004","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-02-07T03:53:43.126818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.329507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-fj4lp\" ","response":"range_response_count:1 size:4635"} {"level":"warn","ts":"2026-02-07T03:53:43.126947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178971488s","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-02-07T03:53:43.126964Z","caller":"traceutil/trace.go:171","msg":"trace[528119872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"1.178998459s","start":"2026-02-07T03:53:41.947962Z","end":"2026-02-07T03:53:43.12696Z","steps":["trace[528119872] 'agreement among raft nodes before linearized reading' (duration: 1.178957238s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:43.12698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:41.947956Z","time spent":"1.17902161s","remote":"127.0.0.1:44004","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":"info","ts":"2026-02-07T03:53:43.127028Z","caller":"traceutil/trace.go:171","msg":"trace[2113500617] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-fj4lp; range_end:; response_count:1; response_revision:583; }","duration":"496.507582ms","start":"2026-02-07T03:53:42.630446Z","end":"2026-02-07T03:53:43.126954Z","steps":["trace[2113500617] 'agreement among raft nodes before linearized reading' (duration: 496.244415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:43.127063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181244925s","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-02-07T03:53:43.127078Z","caller":"traceutil/trace.go:171","msg":"trace[38658002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"1.181311828s","start":"2026-02-07T03:53:41.945762Z","end":"2026-02-07T03:53:43.127074Z","steps":["trace[38658002] 'agreement among raft nodes before linearized reading' (duration: 1.181273067s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:43.12709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:41.945745Z","time spent":"1.181342918s","remote":"127.0.0.1:44004","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-02-07T03:53:43.127121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:42.630429Z","time spent":"496.637956ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4657,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-fj4lp\" "} {"level":"info","ts":"2026-02-07T03:53:43.779208Z","caller":"traceutil/trace.go:171","msg":"trace[1697087876] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"129.524903ms","start":"2026-02-07T03:53:43.649665Z","end":"2026-02-07T03:53:43.77919Z","steps":["trace[1697087876] 'process raft request' (duration: 120.655497ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:43.819197Z","caller":"traceutil/trace.go:171","msg":"trace[1448018947] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"160.494299ms","start":"2026-02-07T03:53:43.658689Z","end":"2026-02-07T03:53:43.819183Z","steps":["trace[1448018947] 'process raft request' (duration: 160.444547ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:43.819383Z","caller":"traceutil/trace.go:171","msg":"trace[1779046583] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"169.54755ms","start":"2026-02-07T03:53:43.649831Z","end":"2026-02-07T03:53:43.819379Z","steps":["trace[1779046583] 'process raft request' (duration: 169.235011ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:43.899204Z","caller":"traceutil/trace.go:171","msg":"trace[1062188406] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"116.344583ms","start":"2026-02-07T03:53:43.782839Z","end":"2026-02-07T03:53:43.899184Z","steps":["trace[1062188406] 'process raft request' (duration: 97.752696ms)","trace[1062188406] 'compare' (duration: 18.146413ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:53:44.087097Z","caller":"traceutil/trace.go:171","msg":"trace[1765602167] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"172.294012ms","start":"2026-02-07T03:53:43.914785Z","end":"2026-02-07T03:53:44.087079Z","steps":["trace[1765602167] 'process raft request' (duration: 85.975495ms)","trace[1765602167] 'compare' (duration: 86.234045ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:53:44.087564Z","caller":"traceutil/trace.go:171","msg":"trace[1647808478] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"172.581681ms","start":"2026-02-07T03:53:43.914969Z","end":"2026-02-07T03:53:44.087551Z","steps":["trace[1647808478] 'process raft request' (duration: 172.517999ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:44.157016Z","caller":"traceutil/trace.go:171","msg":"trace[1696097326] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"140.62355ms","start":"2026-02-07T03:53:44.016344Z","end":"2026-02-07T03:53:44.156967Z","steps":["trace[1696097326] 'process raft request' (duration: 140.534787ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:44.226775Z","caller":"traceutil/trace.go:171","msg":"trace[1066525138] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"103.291312ms","start":"2026-02-07T03:53:44.12347Z","end":"2026-02-07T03:53:44.226761Z","steps":["trace[1066525138] 'process raft request' (duration: 103.257961ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:44.22696Z","caller":"traceutil/trace.go:171","msg":"trace[1719415806] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"130.628594ms","start":"2026-02-07T03:53:44.096322Z","end":"2026-02-07T03:53:44.226951Z","steps":["trace[1719415806] 'process raft request' (duration: 114.715769ms)","trace[1719415806] 'compare' (duration: 15.590615ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:53:44.227461Z","caller":"traceutil/trace.go:171","msg":"trace[1971475444] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"131.065466ms","start":"2026-02-07T03:53:44.096386Z","end":"2026-02-07T03:53:44.227452Z","steps":["trace[1971475444] 'process raft request' (duration: 130.310485ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:44.653338Z","caller":"traceutil/trace.go:171","msg":"trace[1585587424] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"170.560255ms","start":"2026-02-07T03:53:44.482752Z","end":"2026-02-07T03:53:44.653312Z","steps":["trace[1585587424] 'process raft request' (duration: 84.338911ms)","trace[1585587424] 'compare' (duration: 85.962577ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:53:49.598956Z","caller":"traceutil/trace.go:171","msg":"trace[957997727] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:692; }","duration":"264.475764ms","start":"2026-02-07T03:53:49.334458Z","end":"2026-02-07T03:53:49.598934Z","steps":["trace[957997727] 'read index received' (duration: 86.405418ms)","trace[957997727] 'applied index is now lower than readState.Index' (duration: 178.069406ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:53:49.599242Z","caller":"traceutil/trace.go:171","msg":"trace[1431983685] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"265.45122ms","start":"2026-02-07T03:53:49.33378Z","end":"2026-02-07T03:53:49.599231Z","steps":["trace[1431983685] 'process raft request' (duration: 265.034739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:49.599494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.023498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T03:53:49.599525Z","caller":"traceutil/trace.go:171","msg":"trace[631717682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"265.080849ms","start":"2026-02-07T03:53:49.334435Z","end":"2026-02-07T03:53:49.599516Z","steps":["trace[631717682] 'agreement among raft nodes before linearized reading' (duration: 264.970216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:49.599707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.327103ms","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-02-07T03:53:49.59973Z","caller":"traceutil/trace.go:171","msg":"trace[198743602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:676; }","duration":"246.389375ms","start":"2026-02-07T03:53:49.353333Z","end":"2026-02-07T03:53:49.599722Z","steps":["trace[198743602] 'agreement among raft nodes before linearized reading' (duration: 246.317982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:49.599892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.217342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T03:53:49.59991Z","caller":"traceutil/trace.go:171","msg":"trace[1818810071] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:676; }","duration":"242.245413ms","start":"2026-02-07T03:53:49.357659Z","end":"2026-02-07T03:53:49.599904Z","steps":["trace[1818810071] 'agreement among raft nodes before linearized reading' (duration: 242.192521ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:49.717766Z","caller":"traceutil/trace.go:171","msg":"trace[1220247631] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"112.945076ms","start":"2026-02-07T03:53:49.604804Z","end":"2026-02-07T03:53:49.717749Z","steps":["trace[1220247631] 'process raft request' (duration: 112.920295ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:49.718075Z","caller":"traceutil/trace.go:171","msg":"trace[900689076] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"114.798585ms","start":"2026-02-07T03:53:49.60327Z","end":"2026-02-07T03:53:49.718069Z","steps":["trace[900689076] 'process raft request' (duration: 114.356373ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:50.081635Z","caller":"traceutil/trace.go:171","msg":"trace[1067758991] transaction","detail":"{read_only:false; number_of_response:0; response_revision:680; }","duration":"177.116099ms","start":"2026-02-07T03:53:49.904501Z","end":"2026-02-07T03:53:50.081617Z","steps":["trace[1067758991] 'process raft request' (duration: 176.787479ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:50.085031Z","caller":"traceutil/trace.go:171","msg":"trace[688783493] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"129.119829ms","start":"2026-02-07T03:53:49.955902Z","end":"2026-02-07T03:53:50.085022Z","steps":["trace[688783493] 'process raft request' (duration: 128.919393ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:50.261965Z","caller":"traceutil/trace.go:171","msg":"trace[188136686] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"167.79135ms","start":"2026-02-07T03:53:50.094139Z","end":"2026-02-07T03:53:50.26193Z","steps":["trace[188136686] 'process raft request' (duration: 167.251596ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:50.303005Z","caller":"traceutil/trace.go:171","msg":"trace[883121243] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"136.802151ms","start":"2026-02-07T03:53:50.166182Z","end":"2026-02-07T03:53:50.302984Z","steps":["trace[883121243] 'process raft request' (duration: 136.663128ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:50.790342Z","caller":"traceutil/trace.go:171","msg":"trace[1061590856] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"151.580396ms","start":"2026-02-07T03:53:50.638746Z","end":"2026-02-07T03:53:50.790327Z","steps":["trace[1061590856] 'process raft request' (duration: 151.495353ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:52.631129Z","caller":"traceutil/trace.go:171","msg":"trace[1306814642] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"131.3569ms","start":"2026-02-07T03:53:52.499598Z","end":"2026-02-07T03:53:52.630955Z","steps":["trace[1306814642] 'process raft request' (duration: 131.246417ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:53:55.391829Z","caller":"traceutil/trace.go:171","msg":"trace[1746644565] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"486.947117ms","start":"2026-02-07T03:53:54.90486Z","end":"2026-02-07T03:53:55.391807Z","steps":["trace[1746644565] 'process raft request' (duration: 486.830964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:55.392205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:54.904854Z","time spent":"487.229395ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4974,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:53:55.392669Z","caller":"traceutil/trace.go:171","msg":"trace[295357773] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"492.267493ms","start":"2026-02-07T03:53:54.900386Z","end":"2026-02-07T03:53:55.392654Z","steps":["trace[295357773] 'process raft request' (duration: 433.644326ms)","trace[295357773] 'compare' (duration: 57.254213ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:53:55.39381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:54.90038Z","time spent":"493.366482ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:53:55.392757Z","caller":"traceutil/trace.go:171","msg":"trace[294423429] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"491.646568ms","start":"2026-02-07T03:53:54.901098Z","end":"2026-02-07T03:53:55.392744Z","steps":["trace[294423429] 'process raft request' (duration: 490.501239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:53:55.395087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:53:54.90109Z","time spent":"493.927635ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:54:01.853413Z","caller":"traceutil/trace.go:171","msg":"trace[1301461948] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"120.592212ms","start":"2026-02-07T03:54:01.732798Z","end":"2026-02-07T03:54:01.85339Z","steps":["trace[1301461948] 'process raft request' (duration: 120.4742ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:54:03.586072Z","caller":"traceutil/trace.go:171","msg":"trace[1098865231] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"136.616711ms","start":"2026-02-07T03:54:03.449441Z","end":"2026-02-07T03:54:03.586058Z","steps":["trace[1098865231] 'process raft request' (duration: 136.523769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:54:05.07599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.219744ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-07T03:54:05.076103Z","caller":"traceutil/trace.go:171","msg":"trace[593278498] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"204.972062ms","start":"2026-02-07T03:54:04.871104Z","end":"2026-02-07T03:54:05.076076Z","steps":["trace[593278498] 'read index received' (duration: 87.581424ms)","trace[593278498] 'applied index is now lower than readState.Index' (duration: 117.389218ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:54:05.076173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.081975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T03:54:05.076204Z","caller":"traceutil/trace.go:171","msg":"trace[1124329639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"205.143567ms","start":"2026-02-07T03:54:04.87105Z","end":"2026-02-07T03:54:05.076193Z","steps":["trace[1124329639] 'agreement among raft nodes before linearized reading' (duration: 205.084805ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:54:05.750086Z","caller":"traceutil/trace.go:171","msg":"trace[1322852005] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"112.667009ms","start":"2026-02-07T03:54:05.637406Z","end":"2026-02-07T03:54:05.750073Z","steps":["trace[1322852005] 'process raft request' (duration: 110.785706ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:54:24.125637Z","caller":"traceutil/trace.go:171","msg":"trace[1211245387] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"106.882154ms","start":"2026-02-07T03:54:24.018733Z","end":"2026-02-07T03:54:24.125615Z","steps":["trace[1211245387] 'process raft request' (duration: 96.169419ms)","trace[1211245387] 'compare' (duration: 10.322228ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:55:10.231058Z","caller":"traceutil/trace.go:171","msg":"trace[276932493] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"266.265259ms","start":"2026-02-07T03:55:09.96467Z","end":"2026-02-07T03:55:10.230935Z","steps":["trace[276932493] 'process raft request' (duration: 266.007475ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:55:24.589043Z","caller":"traceutil/trace.go:171","msg":"trace[448648443] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"102.115232ms","start":"2026-02-07T03:55:24.486904Z","end":"2026-02-07T03:55:24.589019Z","steps":["trace[448648443] 'process raft request' (duration: 98.699649ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:55:38.543603Z","caller":"traceutil/trace.go:171","msg":"trace[227822525] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"119.386128ms","start":"2026-02-07T03:55:38.424197Z","end":"2026-02-07T03:55:38.543583Z","steps":["trace[227822525] 'read index received' (duration: 119.224196ms)","trace[227822525] 'applied index is now lower than readState.Index' (duration: 161.151µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:55:38.544238Z","caller":"traceutil/trace.go:171","msg":"trace[2075034400] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"135.446796ms","start":"2026-02-07T03:55:38.408777Z","end":"2026-02-07T03:55:38.544224Z","steps":["trace[2075034400] 'process raft request' (duration: 134.694807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:55:38.544769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.555122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:1 size:625"} {"level":"info","ts":"2026-02-07T03:55:38.544988Z","caller":"traceutil/trace.go:171","msg":"trace[1484500545] range","detail":"{range_begin:/registry/roles/kube-system/system:controller:cloud-provider; range_end:; response_count:1; response_revision:1090; }","duration":"120.762515ms","start":"2026-02-07T03:55:38.424167Z","end":"2026-02-07T03:55:38.54493Z","steps":["trace[1484500545] 'agreement among raft nodes before linearized reading' (duration: 120.540042ms)"],"step_count":1}