{"level":"warn","ts":"2026-02-09T03:18:03.059473Z","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-09T03:18:03.059565Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-09T03:18:03.05963Z","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-09T03:18:03.059639Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-02-09T03:18:03.059655Z","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-09T03:18:03.060753Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-02-09T03:18:03.060822Z","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://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-09T03:18:03.07165Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.855735ms"} {"level":"info","ts":"2026-02-09T03:18:03.116922Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-02-09T03:18:03.117242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-02-09T03:18:03.117263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-02-09T03:18:03.117273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-09T03:18:03.117289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-02-09T03:18:03.117322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-02-09T03:18:03.129439Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-09T03:18:03.151598Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-09T03:18:03.156485Z","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-09T03:18:03.16429Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-09T03:18:03.169241Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-09T03:18:03.169545Z","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-09T03:18:03.169582Z","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-09T03:18:03.169591Z","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-09T03:18:03.184556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-02-09T03:18:03.185246Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-02-09T03:18:03.186309Z","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-09T03:18:03.18647Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-02-09T03:18:03.18731Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-02-09T03:18:03.191893Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-09T03:18:03.191939Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-09T03:18:03.91824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-02-09T03:18:03.918361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-02-09T03:18:03.918394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-02-09T03:18:03.918415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-02-09T03:18:03.918442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-02-09T03:18:03.918463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-02-09T03:18:03.91848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-02-09T03:18:03.92129Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-02-09T03:18:03.921347Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T03:18:03.921678Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:18:03.928112Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T03:18:03.929581Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-09T03:18:03.942077Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-09T03:18:03.942094Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-09T03:18:03.943177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-02-09T03:18:03.968849Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:18:03.970059Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:18:03.97013Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:18:20.621737Z","caller":"traceutil/trace.go:171","msg":"trace[97789215] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"511.693026ms","start":"2026-02-09T03:18:20.110028Z","end":"2026-02-09T03:18:20.621721Z","steps":["trace[97789215] 'read index received' (duration: 486.951167ms)","trace[97789215] 'applied index is now lower than readState.Index' (duration: 24.739599ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:18:20.621908Z","caller":"traceutil/trace.go:171","msg":"trace[1397639817] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"536.042212ms","start":"2026-02-09T03:18:20.085859Z","end":"2026-02-09T03:18:20.621901Z","steps":["trace[1397639817] 'process raft request' (duration: 511.166719ms)","trace[1397639817] 'compare' (duration: 24.641665ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:18:20.622184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:18:20.085841Z","time spent":"536.081363ms","remote":"127.0.0.1:51784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:18:20.62229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.263065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:18:20.622311Z","caller":"traceutil/trace.go:171","msg":"trace[1973222707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:449; }","duration":"512.322286ms","start":"2026-02-09T03:18:20.109983Z","end":"2026-02-09T03:18:20.622306Z","steps":["trace[1973222707] 'agreement among raft nodes before linearized reading' (duration: 512.289735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:18:20.622324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:18:20.109969Z","time spent":"512.352708ms","remote":"127.0.0.1:51324","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-09T03:18:20.622397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.373826ms","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-09T03:18:20.62241Z","caller":"traceutil/trace.go:171","msg":"trace[1152767719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:449; }","duration":"288.386237ms","start":"2026-02-09T03:18:20.334019Z","end":"2026-02-09T03:18:20.622405Z","steps":["trace[1152767719] 'agreement among raft nodes before linearized reading' (duration: 288.360496ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:18:23.809616Z","caller":"traceutil/trace.go:171","msg":"trace[1460537330] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"114.161392ms","start":"2026-02-09T03:18:23.695412Z","end":"2026-02-09T03:18:23.809573Z","steps":["trace[1460537330] 'process raft request' (duration: 113.987846ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:18:26.206444Z","caller":"traceutil/trace.go:171","msg":"trace[150605893] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"389.623495ms","start":"2026-02-09T03:18:25.816803Z","end":"2026-02-09T03:18:26.206426Z","steps":["trace[150605893] 'process raft request' (duration: 389.531592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:18:26.206553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:18:25.816788Z","time spent":"389.705698ms","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:18:26.206513Z","caller":"traceutil/trace.go:171","msg":"trace[384016692] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:474; }","duration":"376.868759ms","start":"2026-02-09T03:18:25.82962Z","end":"2026-02-09T03:18:26.206489Z","steps":["trace[384016692] 'read index received' (duration: 376.861569ms)","trace[384016692] 'applied index is now lower than readState.Index' (duration: 5.94µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:18:26.206723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.078436ms","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-09T03:18:26.206773Z","caller":"traceutil/trace.go:171","msg":"trace[515780614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:460; }","duration":"377.176409ms","start":"2026-02-09T03:18:25.829586Z","end":"2026-02-09T03:18:26.206762Z","steps":["trace[515780614] 'agreement among raft nodes before linearized reading' (duration: 377.034294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:18:26.20681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:18:25.829569Z","time spent":"377.23044ms","remote":"127.0.0.1:51606","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-09T03:18:26.208726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.755682ms","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-09T03:18:26.208773Z","caller":"traceutil/trace.go:171","msg":"trace[658205846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:461; }","duration":"375.832305ms","start":"2026-02-09T03:18:25.83293Z","end":"2026-02-09T03:18:26.208762Z","steps":["trace[658205846] 'agreement among raft nodes before linearized reading' (duration: 375.743252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:18:26.209109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:18:25.83292Z","time spent":"376.175836ms","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-09T03:18:33.584237Z","caller":"traceutil/trace.go:171","msg":"trace[1491717047] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"216.993663ms","start":"2026-02-09T03:18:33.367219Z","end":"2026-02-09T03:18:33.584212Z","steps":["trace[1491717047] 'process raft request' (duration: 216.787656ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:18:34.582812Z","caller":"traceutil/trace.go:171","msg":"trace[1617376743] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"298.617831ms","start":"2026-02-09T03:18:34.284178Z","end":"2026-02-09T03:18:34.582796Z","steps":["trace[1617376743] 'process raft request' (duration: 298.59367ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:18:34.582996Z","caller":"traceutil/trace.go:171","msg":"trace[517542754] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"300.653097ms","start":"2026-02-09T03:18:34.282337Z","end":"2026-02-09T03:18:34.58299Z","steps":["trace[517542754] 'process raft request' (duration: 273.422117ms)","trace[517542754] 'compare' (duration: 26.955101ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:18:34.583049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:18:34.282325Z","time spent":"300.683229ms","remote":"127.0.0.1:51606","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-09T03:18:39.926599Z","caller":"traceutil/trace.go:171","msg":"trace[1973502661] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"112.665483ms","start":"2026-02-09T03:18:39.813916Z","end":"2026-02-09T03:18:39.926582Z","steps":["trace[1973502661] 'process raft request' (duration: 112.544159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:18:40.47244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.934306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:18:40.472501Z","caller":"traceutil/trace.go:171","msg":"trace[1344651526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"364.033489ms","start":"2026-02-09T03:18:40.108453Z","end":"2026-02-09T03:18:40.472486Z","steps":["trace[1344651526] 'range keys from in-memory index tree' (duration: 363.848593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:18:40.47253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:18:40.108427Z","time spent":"364.096272ms","remote":"127.0.0.1:51324","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-09T03:18:40.472783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.571842ms","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-09T03:18:40.472808Z","caller":"traceutil/trace.go:171","msg":"trace[1587044962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:533; }","duration":"165.642474ms","start":"2026-02-09T03:18:40.307158Z","end":"2026-02-09T03:18:40.472801Z","steps":["trace[1587044962] 'range keys from in-memory index tree' (duration: 165.461138ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:18:40.804345Z","caller":"traceutil/trace.go:171","msg":"trace[122988344] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"192.489592ms","start":"2026-02-09T03:18:40.611833Z","end":"2026-02-09T03:18:40.804323Z","steps":["trace[122988344] 'process raft request' (duration: 192.397359ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:18:40.804724Z","caller":"traceutil/trace.go:171","msg":"trace[739292656] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"194.332953ms","start":"2026-02-09T03:18:40.610375Z","end":"2026-02-09T03:18:40.804708Z","steps":["trace[739292656] 'process raft request' (duration: 193.129623ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:18:42.712668Z","caller":"traceutil/trace.go:171","msg":"trace[948180579] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"111.149243ms","start":"2026-02-09T03:18:42.601502Z","end":"2026-02-09T03:18:42.712651Z","steps":["trace[948180579] 'process raft request' (duration: 111.0517ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:18:54.138588Z","caller":"traceutil/trace.go:171","msg":"trace[659061756] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"128.834441ms","start":"2026-02-09T03:18:54.00973Z","end":"2026-02-09T03:18:54.138564Z","steps":["trace[659061756] 'process raft request' (duration: 86.360213ms)","trace[659061756] 'compare' (duration: 42.391536ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:18:54.140423Z","caller":"traceutil/trace.go:171","msg":"trace[1757441371] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"121.450849ms","start":"2026-02-09T03:18:54.01896Z","end":"2026-02-09T03:18:54.140411Z","steps":["trace[1757441371] 'process raft request' (duration: 121.353176ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:18:59.112017Z","caller":"traceutil/trace.go:171","msg":"trace[480041062] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"129.694219ms","start":"2026-02-09T03:18:58.982309Z","end":"2026-02-09T03:18:59.112003Z","steps":["trace[480041062] 'read index received' (duration: 129.596416ms)","trace[480041062] 'applied index is now lower than readState.Index' (duration: 96.973µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:18:59.112134Z","caller":"traceutil/trace.go:171","msg":"trace[551496864] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"274.175473ms","start":"2026-02-09T03:18:58.837951Z","end":"2026-02-09T03:18:59.112127Z","steps":["trace[551496864] 'process raft request' (duration: 273.954636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:18:59.112281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.966878ms","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-09T03:18:59.1123Z","caller":"traceutil/trace.go:171","msg":"trace[2087219732] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:720; }","duration":"130.016519ms","start":"2026-02-09T03:18:58.982277Z","end":"2026-02-09T03:18:59.112294Z","steps":["trace[2087219732] 'agreement among raft nodes before linearized reading' (duration: 129.933177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:18:59.854104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.553669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:18:59.854207Z","caller":"traceutil/trace.go:171","msg":"trace[1414169961] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"735.847564ms","start":"2026-02-09T03:18:59.118337Z","end":"2026-02-09T03:18:59.854184Z","steps":["trace[1414169961] 'process raft request' (duration: 291.925423ms)","trace[1414169961] 'compare' (duration: 443.28336ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:18:59.854268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:18:59.118317Z","time spent":"735.923026ms","remote":"127.0.0.1:51606","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-02-09T03:18:59.921065Z","caller":"traceutil/trace.go:171","msg":"trace[625354021] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:742; }","duration":"794.261494ms","start":"2026-02-09T03:18:59.126789Z","end":"2026-02-09T03:18:59.92105Z","steps":["trace[625354021] 'read index received' (duration: 283.483577ms)","trace[625354021] 'applied index is now lower than readState.Index' (duration: 510.777147ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:18:59.921234Z","caller":"traceutil/trace.go:171","msg":"trace[789653168] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"801.207931ms","start":"2026-02-09T03:18:59.120018Z","end":"2026-02-09T03:18:59.921226Z","steps":["trace[789653168] 'process raft request' (duration: 800.925372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:18:59.921293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:18:59.120004Z","time spent":"801.249142ms","remote":"127.0.0.1:51606","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-02-09T03:18:59.921403Z","caller":"traceutil/trace.go:171","msg":"trace[191687781] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"799.545876ms","start":"2026-02-09T03:18:59.121851Z","end":"2026-02-09T03:18:59.921397Z","steps":["trace[191687781] 'process raft request' (duration: 799.175195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:18:59.921434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:18:59.121842Z","time spent":"799.574046ms","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:18:59.921596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.805061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-09T03:18:59.921617Z","caller":"traceutil/trace.go:171","msg":"trace[1316223491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:723; }","duration":"794.843733ms","start":"2026-02-09T03:18:59.126767Z","end":"2026-02-09T03:18:59.92161Z","steps":["trace[1316223491] 'agreement among raft nodes before linearized reading' (duration: 794.74669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:18:59.921634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:18:59.12676Z","time spent":"794.870093ms","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-09T03:19:00.052127Z","caller":"traceutil/trace.go:171","msg":"trace[871323278] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"126.580898ms","start":"2026-02-09T03:18:59.925531Z","end":"2026-02-09T03:19:00.052112Z","steps":["trace[871323278] 'process raft request' (duration: 126.532137ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:19:00.05229Z","caller":"traceutil/trace.go:171","msg":"trace[1896442541] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"512.73432ms","start":"2026-02-09T03:18:59.539548Z","end":"2026-02-09T03:19:00.052283Z","steps":["trace[1896442541] 'process raft request' (duration: 480.724053ms)","trace[1896442541] 'compare' (duration: 31.686286ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:19:00.052373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:18:59.53953Z","time spent":"512.774312ms","remote":"127.0.0.1:51416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T03:19:00.052521Z","caller":"traceutil/trace.go:171","msg":"trace[545635097] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"324.452156ms","start":"2026-02-09T03:18:59.728063Z","end":"2026-02-09T03:19:00.052515Z","steps":["trace[545635097] 'process raft request' (duration: 323.964189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:19:00.05256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:18:59.72804Z","time spent":"324.495277ms","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:19:00.052632Z","caller":"traceutil/trace.go:171","msg":"trace[166698804] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:745; }","duration":"131.519529ms","start":"2026-02-09T03:18:59.921106Z","end":"2026-02-09T03:19:00.052626Z","steps":["trace[166698804] 'read index received' (duration: 99.173362ms)","trace[166698804] 'applied index is now lower than readState.Index' (duration: 32.345607ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:19:00.052703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.065077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-qxbcm\" ","response":"range_response_count:1 size:5026"} {"level":"info","ts":"2026-02-09T03:19:00.052723Z","caller":"traceutil/trace.go:171","msg":"trace[1597211732] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-qxbcm; range_end:; response_count:1; response_revision:726; }","duration":"500.106788ms","start":"2026-02-09T03:18:59.552611Z","end":"2026-02-09T03:19:00.052718Z","steps":["trace[1597211732] 'agreement among raft nodes before linearized reading' (duration: 500.034046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:19:00.052741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:18:59.552603Z","time spent":"500.132809ms","remote":"127.0.0.1:51520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5048,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-qxbcm\" "} {"level":"info","ts":"2026-02-09T03:19:01.272998Z","caller":"traceutil/trace.go:171","msg":"trace[1064285362] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"152.552147ms","start":"2026-02-09T03:19:01.120431Z","end":"2026-02-09T03:19:01.272983Z","steps":["trace[1064285362] 'process raft request' (duration: 152.440564ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:19:02.192059Z","caller":"traceutil/trace.go:171","msg":"trace[229817207] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"266.563682ms","start":"2026-02-09T03:19:01.925479Z","end":"2026-02-09T03:19:02.192042Z","steps":["trace[229817207] 'read index received' (duration: 266.410937ms)","trace[229817207] 'applied index is now lower than readState.Index' (duration: 151.835µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:19:02.192159Z","caller":"traceutil/trace.go:171","msg":"trace[1580688649] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"331.288309ms","start":"2026-02-09T03:19:01.860862Z","end":"2026-02-09T03:19:02.192151Z","steps":["trace[1580688649] 'process raft request' (duration: 331.02007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:19:02.192224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:19:01.860852Z","time spent":"331.32456ms","remote":"127.0.0.1:51606","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-09T03:19:02.192368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.886953ms","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-09T03:19:02.192389Z","caller":"traceutil/trace.go:171","msg":"trace[1821736124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"266.919164ms","start":"2026-02-09T03:19:01.925463Z","end":"2026-02-09T03:19:02.192383Z","steps":["trace[1821736124] 'agreement among raft nodes before linearized reading' (duration: 266.846592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:19:02.192483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.958136ms","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-09T03:19:02.192499Z","caller":"traceutil/trace.go:171","msg":"trace[2067997001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"266.990577ms","start":"2026-02-09T03:19:01.925503Z","end":"2026-02-09T03:19:02.192494Z","steps":["trace[2067997001] 'agreement among raft nodes before linearized reading' (duration: 266.946226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:19:02.192572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.862619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-09T03:19:02.192587Z","caller":"traceutil/trace.go:171","msg":"trace[1394121978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:733; }","duration":"134.90401ms","start":"2026-02-09T03:19:02.057679Z","end":"2026-02-09T03:19:02.192583Z","steps":["trace[1394121978] 'agreement among raft nodes before linearized reading' (duration: 134.861939ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:19:07.454339Z","caller":"traceutil/trace.go:171","msg":"trace[1941460078] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"160.759715ms","start":"2026-02-09T03:19:07.293541Z","end":"2026-02-09T03:19:07.454301Z","steps":["trace[1941460078] 'process raft request' (duration: 160.317531ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:19:20.631283Z","caller":"traceutil/trace.go:171","msg":"trace[2110548248] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"183.4964ms","start":"2026-02-09T03:19:20.447766Z","end":"2026-02-09T03:19:20.631263Z","steps":["trace[2110548248] 'process raft request' (duration: 174.321708ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:19:21.715468Z","caller":"traceutil/trace.go:171","msg":"trace[906941929] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"143.474262ms","start":"2026-02-09T03:19:21.57197Z","end":"2026-02-09T03:19:21.715444Z","steps":["trace[906941929] 'process raft request' (duration: 143.337239ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:19:34.056393Z","caller":"traceutil/trace.go:171","msg":"trace[458898451] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"288.796873ms","start":"2026-02-09T03:19:33.767576Z","end":"2026-02-09T03:19:34.056373Z","steps":["trace[458898451] 'process raft request' (duration: 288.699792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:19:34.492515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.040413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:19:34.492721Z","caller":"traceutil/trace.go:171","msg":"trace[182565871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:878; }","duration":"388.323288ms","start":"2026-02-09T03:19:34.104381Z","end":"2026-02-09T03:19:34.492704Z","steps":["trace[182565871] 'range keys from in-memory index tree' (duration: 387.965531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:19:34.492782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:19:34.104367Z","time spent":"388.383769ms","remote":"127.0.0.1:51324","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-09T03:19:34.633089Z","caller":"traceutil/trace.go:171","msg":"trace[1271118426] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"135.774374ms","start":"2026-02-09T03:19:34.497298Z","end":"2026-02-09T03:19:34.633073Z","steps":["trace[1271118426] 'process raft request' (duration: 135.668082ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:19:34.63459Z","caller":"traceutil/trace.go:171","msg":"trace[471271770] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"113.287247ms","start":"2026-02-09T03:19:34.521286Z","end":"2026-02-09T03:19:34.634573Z","steps":["trace[471271770] 'read index received' (duration: 111.932292ms)","trace[471271770] 'applied index is now lower than readState.Index' (duration: 1.354235ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:19:34.634682Z","caller":"traceutil/trace.go:171","msg":"trace[1898453334] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"136.825323ms","start":"2026-02-09T03:19:34.497851Z","end":"2026-02-09T03:19:34.634677Z","steps":["trace[1898453334] 'process raft request' (duration: 136.594559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:19:34.63492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.621393ms","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-09T03:19:34.634956Z","caller":"traceutil/trace.go:171","msg":"trace[155521447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:880; }","duration":"113.689244ms","start":"2026-02-09T03:19:34.521259Z","end":"2026-02-09T03:19:34.634949Z","steps":["trace[155521447] 'agreement among raft nodes before linearized reading' (duration: 113.517741ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:19:42.367907Z","caller":"traceutil/trace.go:171","msg":"trace[424613895] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"283.538602ms","start":"2026-02-09T03:19:42.084351Z","end":"2026-02-09T03:19:42.367889Z","steps":["trace[424613895] 'process raft request' (duration: 283.42159ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:19:42.368332Z","caller":"traceutil/trace.go:171","msg":"trace[829248279] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:933; }","duration":"257.47977ms","start":"2026-02-09T03:19:42.110845Z","end":"2026-02-09T03:19:42.368325Z","steps":["trace[829248279] 'read index received' (duration: 257.47726ms)","trace[829248279] 'applied index is now lower than readState.Index' (duration: 2.06µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:19:42.36839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.532401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:19:42.368409Z","caller":"traceutil/trace.go:171","msg":"trace[1217027468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"257.573632ms","start":"2026-02-09T03:19:42.110829Z","end":"2026-02-09T03:19:42.368403Z","steps":["trace[1217027468] 'agreement among raft nodes before linearized reading' (duration: 257.521881ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:19:48.81604Z","caller":"traceutil/trace.go:171","msg":"trace[402977255] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:951; }","duration":"226.215426ms","start":"2026-02-09T03:19:48.589808Z","end":"2026-02-09T03:19:48.816023Z","steps":["trace[402977255] 'read index received' (duration: 226.081913ms)","trace[402977255] 'applied index is now lower than readState.Index' (duration: 132.853µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:19:48.816125Z","caller":"traceutil/trace.go:171","msg":"trace[23566998] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"432.102142ms","start":"2026-02-09T03:19:48.384018Z","end":"2026-02-09T03:19:48.81612Z","steps":["trace[23566998] 'process raft request' (duration: 431.910858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:19:48.8162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:19:48.38401Z","time spent":"432.131413ms","remote":"127.0.0.1:51606","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-02-09T03:19:48.816293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.482331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T03:19:48.816309Z","caller":"traceutil/trace.go:171","msg":"trace[343944232] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:918; }","duration":"226.499461ms","start":"2026-02-09T03:19:48.589805Z","end":"2026-02-09T03:19:48.816304Z","steps":["trace[343944232] 'agreement among raft nodes before linearized reading' (duration: 226.4684ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:19:48.961064Z","caller":"traceutil/trace.go:171","msg":"trace[1817551706] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"115.295771ms","start":"2026-02-09T03:19:48.845749Z","end":"2026-02-09T03:19:48.961045Z","steps":["trace[1817551706] 'process raft request' (duration: 113.983218ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:19:48.961766Z","caller":"traceutil/trace.go:171","msg":"trace[1809290209] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"112.226508ms","start":"2026-02-09T03:19:48.849527Z","end":"2026-02-09T03:19:48.961754Z","steps":["trace[1809290209] 'process raft request' (duration: 110.351715ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:19:51.14103Z","caller":"traceutil/trace.go:171","msg":"trace[517215432] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"205.075382ms","start":"2026-02-09T03:19:50.935939Z","end":"2026-02-09T03:19:51.141014Z","steps":["trace[517215432] 'process raft request' (duration: 205.041691ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:19:51.141271Z","caller":"traceutil/trace.go:171","msg":"trace[1000375803] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"235.378689ms","start":"2026-02-09T03:19:50.905883Z","end":"2026-02-09T03:19:51.141262Z","steps":["trace[1000375803] 'process raft request' (duration: 176.983314ms)","trace[1000375803] 'compare' (duration: 58.019348ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:19:51.141377Z","caller":"traceutil/trace.go:171","msg":"trace[1231798745] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"206.896654ms","start":"2026-02-09T03:19:50.934459Z","end":"2026-02-09T03:19:51.141356Z","steps":["trace[1231798745] 'read index received' (duration: 148.418099ms)","trace[1231798745] 'applied index is now lower than readState.Index' (duration: 58.477475ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:19:51.141557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.087618ms","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-09T03:19:51.141581Z","caller":"traceutil/trace.go:171","msg":"trace[264570397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:933; }","duration":"207.121178ms","start":"2026-02-09T03:19:50.934452Z","end":"2026-02-09T03:19:51.141574Z","steps":["trace[264570397] 'agreement among raft nodes before linearized reading' (duration: 207.027296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:19:51.141725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.347416ms","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-09T03:19:51.141746Z","caller":"traceutil/trace.go:171","msg":"trace[2017666862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:933; }","duration":"175.410997ms","start":"2026-02-09T03:19:50.966328Z","end":"2026-02-09T03:19:51.141739Z","steps":["trace[2017666862] 'agreement among raft nodes before linearized reading' (duration: 175.334796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:19:51.141995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.473978ms","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-09T03:19:51.142016Z","caller":"traceutil/trace.go:171","msg":"trace[1378552867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"175.531188ms","start":"2026-02-09T03:19:50.966479Z","end":"2026-02-09T03:19:51.14201Z","steps":["trace[1378552867] 'agreement among raft nodes before linearized reading' (duration: 175.474577ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:20:06.576053Z","caller":"traceutil/trace.go:171","msg":"trace[4524974] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"130.680594ms","start":"2026-02-09T03:20:06.445345Z","end":"2026-02-09T03:20:06.576025Z","steps":["trace[4524974] 'process raft request' (duration: 77.213037ms)","trace[4524974] 'compare' (duration: 53.350415ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:22:36.401417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100261500582892,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:22:37.187388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100261500582892,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:22:37.223685Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.515078947s","expected-duration":"1s"} {"level":"info","ts":"2026-02-09T03:22:37.225267Z","caller":"traceutil/trace.go:171","msg":"trace[1538159153] linearizableReadLoop","detail":"{readStateIndex:1710; appliedIndex:1709; }","duration":"1.324028498s","start":"2026-02-09T03:22:35.901222Z","end":"2026-02-09T03:22:37.225251Z","steps":["trace[1538159153] 'read index received' (duration: 1.323498336s)","trace[1538159153] 'applied index is now lower than readState.Index' (duration: 529.552µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:22:37.226246Z","caller":"traceutil/trace.go:171","msg":"trace[741717591] transaction","detail":"{read_only:false; response_revision:1643; number_of_response:1; }","duration":"1.517765754s","start":"2026-02-09T03:22:35.708467Z","end":"2026-02-09T03:22:37.226233Z","steps":["trace[741717591] 'process raft request' (duration: 1.516650741s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:22:37.226461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:22:35.708434Z","time spent":"1.517938505s","remote":"127.0.0.1:51606","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-02-09T03:22:37.226751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.325540743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T03:22:37.226917Z","caller":"traceutil/trace.go:171","msg":"trace[2060179432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1643; }","duration":"1.325754853s","start":"2026-02-09T03:22:35.901153Z","end":"2026-02-09T03:22:37.226908Z","steps":["trace[2060179432] 'agreement among raft nodes before linearized reading' (duration: 1.325543823s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:22:37.226949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:22:35.90113Z","time spent":"1.325809923s","remote":"127.0.0.1:51606","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-02-09T03:22:37.227068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11045276s","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-09T03:22:37.227085Z","caller":"traceutil/trace.go:171","msg":"trace[1811333376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1643; }","duration":"1.11052418s","start":"2026-02-09T03:22:36.116555Z","end":"2026-02-09T03:22:37.227079Z","steps":["trace[1811333376] 'agreement among raft nodes before linearized reading' (duration: 1.11047228s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:22:37.228496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:22:36.11653Z","time spent":"1.111956835s","remote":"127.0.0.1:51606","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-09T03:22:37.230207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085423925s","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-09T03:22:37.230244Z","caller":"traceutil/trace.go:171","msg":"trace[2037817641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1643; }","duration":"1.085542425s","start":"2026-02-09T03:22:36.144694Z","end":"2026-02-09T03:22:37.230236Z","steps":["trace[2037817641] 'agreement among raft nodes before linearized reading' (duration: 1.085464665s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:22:37.230335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:22:36.144677Z","time spent":"1.085652106s","remote":"127.0.0.1:51606","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-09T03:22:37.230725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104310322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-09T03:22:37.230752Z","caller":"traceutil/trace.go:171","msg":"trace[2039352120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1643; }","duration":"1.104352262s","start":"2026-02-09T03:22:36.126393Z","end":"2026-02-09T03:22:37.230745Z","steps":["trace[2039352120] 'agreement among raft nodes before linearized reading' (duration: 1.104288632s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:22:37.230845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:22:36.126384Z","time spent":"1.104454372s","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-09T03:23:17.684038Z","caller":"traceutil/trace.go:171","msg":"trace[481925463] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"168.395539ms","start":"2026-02-09T03:23:17.515612Z","end":"2026-02-09T03:23:17.684008Z","steps":["trace[481925463] 'process raft request' (duration: 111.159561ms)","trace[481925463] 'compare' (duration: 57.087515ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:23:19.787813Z","caller":"traceutil/trace.go:171","msg":"trace[1461414232] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"199.78708ms","start":"2026-02-09T03:23:19.587991Z","end":"2026-02-09T03:23:19.787778Z","steps":["trace[1461414232] 'process raft request' (duration: 149.807001ms)","trace[1461414232] 'compare' (duration: 49.696782ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:23:23.77516Z","caller":"traceutil/trace.go:171","msg":"trace[233108551] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"300.387921ms","start":"2026-02-09T03:23:23.474732Z","end":"2026-02-09T03:23:23.77512Z","steps":["trace[233108551] 'read index received' (duration: 300.048333ms)","trace[233108551] 'applied index is now lower than readState.Index' (duration: 338.568µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:23:23.775308Z","caller":"traceutil/trace.go:171","msg":"trace[1892025649] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"327.726875ms","start":"2026-02-09T03:23:23.447548Z","end":"2026-02-09T03:23:23.775275Z","steps":["trace[1892025649] 'process raft request' (duration: 327.323866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:23:23.775364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.164343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:23:23.775396Z","caller":"traceutil/trace.go:171","msg":"trace[527560933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1863; }","duration":"260.249845ms","start":"2026-02-09T03:23:23.515139Z","end":"2026-02-09T03:23:23.775389Z","steps":["trace[527560933] 'agreement among raft nodes before linearized reading' (duration: 260.158493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:23:23.775513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.78125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T03:23:23.775526Z","caller":"traceutil/trace.go:171","msg":"trace[1680776263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1863; }","duration":"300.829971ms","start":"2026-02-09T03:23:23.474692Z","end":"2026-02-09T03:23:23.775522Z","steps":["trace[1680776263] 'agreement among raft nodes before linearized reading' (duration: 300.79237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:23:23.775538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:23:23.474662Z","time spent":"300.872682ms","remote":"127.0.0.1:51606","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-02-09T03:23:23.775505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:23:23.44751Z","time spent":"327.823718ms","remote":"127.0.0.1:51606","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-02-09T03:23:23.775668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.78518ms","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-02-09T03:23:23.77568Z","caller":"traceutil/trace.go:171","msg":"trace[1224312587] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1863; }","duration":"216.840651ms","start":"2026-02-09T03:23:23.558836Z","end":"2026-02-09T03:23:23.775677Z","steps":["trace[1224312587] 'agreement among raft nodes before linearized reading' (duration: 216.80114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:23:23.775837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.763653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-09T03:23:23.775851Z","caller":"traceutil/trace.go:171","msg":"trace[789677216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1863; }","duration":"259.790794ms","start":"2026-02-09T03:23:23.516055Z","end":"2026-02-09T03:23:23.775846Z","steps":["trace[789677216] 'agreement among raft nodes before linearized reading' (duration: 259.753323ms)"],"step_count":1}