{"level":"warn","ts":"2026-03-04T01:21:24.592124Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T01:21:24.592314Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.195: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.195:2380","--initial-cluster=instance=https://162.253.55.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.195:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-04T01:21:24.592424Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T01:21:24.592479Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-03-04T01:21:24.592531Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-04T01:21:24.593192Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"]} {"level":"info","ts":"2026-03-04T01:21:24.593329Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.195:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-04T01:21:24.59686Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.300328ms"} {"level":"info","ts":"2026-03-04T01:21:24.602595Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b66d12fd59101739","cluster-id":"a64a525934ce8379"} {"level":"info","ts":"2026-03-04T01:21:24.60267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=()"} {"level":"info","ts":"2026-03-04T01:21:24.602698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 0"} {"level":"info","ts":"2026-03-04T01:21:24.602736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b66d12fd59101739 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-04T01:21:24.602781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 1"} {"level":"info","ts":"2026-03-04T01:21:24.602871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"warn","ts":"2026-03-04T01:21:24.607818Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-04T01:21:24.610077Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-04T01:21:24.612936Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-04T01:21:24.615996Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b66d12fd59101739","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-04T01:21:24.616582Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-04T01:21:24.617119Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-04T01:21:24.617188Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-04T01:21:24.617864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"info","ts":"2026-03-04T01:21:24.618307Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","added-peer-id":"b66d12fd59101739","added-peer-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-03-04T01:21:24.616583Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b66d12fd59101739","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-04T01:21:24.623563Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-04T01:21:24.623757Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b66d12fd59101739","initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-04T01:21:24.623781Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-04T01:21:24.623862Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-03-04T01:21:24.623893Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-03-04T01:21:25.103483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 is starting a new election at term 1"} {"level":"info","ts":"2026-03-04T01:21:25.103528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-04T01:21:25.103556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgPreVoteResp from b66d12fd59101739 at term 1"} {"level":"info","ts":"2026-03-04T01:21:25.103571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became candidate at term 2"} {"level":"info","ts":"2026-03-04T01:21:25.103582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgVoteResp from b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-03-04T01:21:25.103593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became leader at term 2"} {"level":"info","ts":"2026-03-04T01:21:25.103603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b66d12fd59101739 elected leader b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-03-04T01:21:25.104509Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T01:21:25.105096Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b66d12fd59101739","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.195:2379]}","request-path":"/0/members/b66d12fd59101739/attributes","cluster-id":"a64a525934ce8379","publish-timeout":"7s"} {"level":"info","ts":"2026-03-04T01:21:25.105182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T01:21:25.105236Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T01:21:25.105672Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T01:21:25.105792Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T01:21:25.105862Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T01:21:25.107188Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.195:2379"} {"level":"info","ts":"2026-03-04T01:21:25.107805Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-04T01:21:25.107868Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-04T01:21:25.108495Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-04T01:21:32.502006Z","caller":"traceutil/trace.go:171","msg":"trace[1266556835] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"210.105214ms","start":"2026-03-04T01:21:32.291869Z","end":"2026-03-04T01:21:32.501974Z","steps":["trace[1266556835] 'process raft request' (duration: 209.873199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:21:32.937333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.018895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:21:32.937453Z","caller":"traceutil/trace.go:171","msg":"trace[1922602440] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:338; }","duration":"146.800847ms","start":"2026-03-04T01:21:32.790635Z","end":"2026-03-04T01:21:32.937436Z","steps":["trace[1922602440] 'read index received' (duration: 60.022µs)","trace[1922602440] 'applied index is now lower than readState.Index' (duration: 146.739475ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:21:32.937485Z","caller":"traceutil/trace.go:171","msg":"trace[79321652] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"640.066364ms","start":"2026-03-04T01:21:32.297382Z","end":"2026-03-04T01:21:32.937448Z","steps":["trace[79321652] 'process raft request' (duration: 287.493396ms)","trace[79321652] 'compare' (duration: 351.882422ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:21:32.937561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.931619ms","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-03-04T01:21:32.93761Z","caller":"traceutil/trace.go:171","msg":"trace[68646494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:330; }","duration":"146.98156ms","start":"2026-03-04T01:21:32.790617Z","end":"2026-03-04T01:21:32.937598Z","steps":["trace[68646494] 'agreement among raft nodes before linearized reading' (duration: 146.881698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:21:32.937613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:21:32.297359Z","time spent":"640.193527ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T01:21:33.552356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.513695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:21:33.552465Z","caller":"traceutil/trace.go:171","msg":"trace[1591829321] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:339; }","duration":"609.240141ms","start":"2026-03-04T01:21:32.943206Z","end":"2026-03-04T01:21:33.552446Z","steps":["trace[1591829321] 'read index received' (duration: 311.371657ms)","trace[1591829321] 'applied index is now lower than readState.Index' (duration: 297.867494ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:21:33.55254Z","caller":"traceutil/trace.go:171","msg":"trace[1375304433] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"610.89938ms","start":"2026-03-04T01:21:32.941605Z","end":"2026-03-04T01:21:33.552505Z","steps":["trace[1375304433] 'process raft request' (duration: 313.020066ms)","trace[1375304433] 'compare' (duration: 297.333391ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:21:33.552576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.372834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-03-04T01:21:33.552707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:21:32.941588Z","time spent":"611.032583ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:21:33.552724Z","caller":"traceutil/trace.go:171","msg":"trace[1814506864] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:331; }","duration":"609.542508ms","start":"2026-03-04T01:21:32.943153Z","end":"2026-03-04T01:21:33.552696Z","steps":["trace[1814506864] 'agreement among raft nodes before linearized reading' (duration: 609.381095ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:21:33.552741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.786924ms","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-03-04T01:21:33.552785Z","caller":"traceutil/trace.go:171","msg":"trace[733824006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:331; }","duration":"169.866946ms","start":"2026-03-04T01:21:33.382906Z","end":"2026-03-04T01:21:33.552773Z","steps":["trace[733824006] 'agreement among raft nodes before linearized reading' (duration: 169.785994ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:21:33.552797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:21:32.943132Z","time spent":"609.64207ms","remote":"127.0.0.1:45632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-04T01:21:33.552931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.113566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-04T01:21:33.553023Z","caller":"traceutil/trace.go:171","msg":"trace[59063441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:331; }","duration":"441.28591ms","start":"2026-03-04T01:21:33.111723Z","end":"2026-03-04T01:21:33.553009Z","steps":["trace[59063441] 'agreement among raft nodes before linearized reading' (duration: 440.951932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:21:33.553112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:21:33.111703Z","time spent":"441.396222ms","remote":"127.0.0.1:49100","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":"info","ts":"2026-03-04T01:21:33.978754Z","caller":"traceutil/trace.go:171","msg":"trace[912057362] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"422.546614ms","start":"2026-03-04T01:21:33.556183Z","end":"2026-03-04T01:21:33.97873Z","steps":["trace[912057362] 'process raft request' (duration: 422.348919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:21:33.978897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:21:33.556156Z","time spent":"422.664996ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:21:34.124116Z","caller":"traceutil/trace.go:171","msg":"trace[1958301059] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"567.101326ms","start":"2026-03-04T01:21:33.556988Z","end":"2026-03-04T01:21:34.12409Z","steps":["trace[1958301059] 'process raft request' (duration: 567.041035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:21:34.12426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:21:33.556974Z","time spent":"567.213979ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:21:34.124164Z","caller":"traceutil/trace.go:171","msg":"trace[1345564623] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"567.372243ms","start":"2026-03-04T01:21:33.556767Z","end":"2026-03-04T01:21:34.124139Z","steps":["trace[1345564623] 'process raft request' (duration: 567.008115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:21:34.124421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:21:33.556746Z","time spent":"567.617889ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T01:21:54.490953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.953604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:21:54.491092Z","caller":"traceutil/trace.go:171","msg":"trace[816977828] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:521; }","duration":"224.772882ms","start":"2026-03-04T01:21:54.266303Z","end":"2026-03-04T01:21:54.491076Z","steps":["trace[816977828] 'read index received' (duration: 89.564412ms)","trace[816977828] 'applied index is now lower than readState.Index' (duration: 135.2073ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:21:54.491162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.855634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4420"} {"level":"info","ts":"2026-03-04T01:21:54.491194Z","caller":"traceutil/trace.go:171","msg":"trace[231980054] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:508; }","duration":"224.922256ms","start":"2026-03-04T01:21:54.266263Z","end":"2026-03-04T01:21:54.491185Z","steps":["trace[231980054] 'agreement among raft nodes before linearized reading' (duration: 224.856384ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:21:54.491236Z","caller":"traceutil/trace.go:171","msg":"trace[219009731] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"264.13448ms","start":"2026-03-04T01:21:54.227002Z","end":"2026-03-04T01:21:54.491136Z","steps":["trace[219009731] 'process raft request' (duration: 128.906909ms)","trace[219009731] 'compare' (duration: 134.838322ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:21:54.710904Z","caller":"traceutil/trace.go:171","msg":"trace[2032191918] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"116.608967ms","start":"2026-03-04T01:21:54.594267Z","end":"2026-03-04T01:21:54.710876Z","steps":["trace[2032191918] 'read index received' (duration: 107.975952ms)","trace[2032191918] 'applied index is now lower than readState.Index' (duration: 8.632125ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:21:54.710963Z","caller":"traceutil/trace.go:171","msg":"trace[1810584571] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"210.210706ms","start":"2026-03-04T01:21:54.500715Z","end":"2026-03-04T01:21:54.710926Z","steps":["trace[1810584571] 'process raft request' (duration: 201.506099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:21:54.711091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.815693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-04T01:21:54.711121Z","caller":"traceutil/trace.go:171","msg":"trace[1378495326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"116.896205ms","start":"2026-03-04T01:21:54.594216Z","end":"2026-03-04T01:21:54.711112Z","steps":["trace[1378495326] 'agreement among raft nodes before linearized reading' (duration: 116.748781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:21:55.72795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.604767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:21:55.728084Z","caller":"traceutil/trace.go:171","msg":"trace[1551954714] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"797.550055ms","start":"2026-03-04T01:21:54.930509Z","end":"2026-03-04T01:21:55.728059Z","steps":["trace[1551954714] 'read index received' (duration: 397.771923ms)","trace[1551954714] 'applied index is now lower than readState.Index' (duration: 399.777022ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:21:55.728118Z","caller":"traceutil/trace.go:171","msg":"trace[222642652] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.012619517s","start":"2026-03-04T01:21:54.715462Z","end":"2026-03-04T01:21:55.728081Z","steps":["trace[222642652] 'process raft request' (duration: 612.809175ms)","trace[222642652] 'compare' (duration: 399.471834ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:21:55.728168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.661688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:21:55.728195Z","caller":"traceutil/trace.go:171","msg":"trace[1575618123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"797.711299ms","start":"2026-03-04T01:21:54.930474Z","end":"2026-03-04T01:21:55.728185Z","steps":["trace[1575618123] 'agreement among raft nodes before linearized reading' (duration: 797.665958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:21:55.728223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:21:54.930453Z","time spent":"797.762661ms","remote":"127.0.0.1:45544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-04T01:21:55.728247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:21:54.715438Z","time spent":"1.012742071s","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:22:01.997875Z","caller":"traceutil/trace.go:171","msg":"trace[989135585] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"203.348984ms","start":"2026-03-04T01:22:01.794504Z","end":"2026-03-04T01:22:01.997853Z","steps":["trace[989135585] 'read index received' (duration: 203.156889ms)","trace[989135585] 'applied index is now lower than readState.Index' (duration: 191.155µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:22:01.997993Z","caller":"traceutil/trace.go:171","msg":"trace[731478635] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"350.082899ms","start":"2026-03-04T01:22:01.6479Z","end":"2026-03-04T01:22:01.997983Z","steps":["trace[731478635] 'process raft request' (duration: 349.778001ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:01.998084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:01.647876Z","time spent":"350.141619ms","remote":"127.0.0.1:45698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:22:01.998288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.776293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-04T01:22:01.998346Z","caller":"traceutil/trace.go:171","msg":"trace[1159705417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"203.871636ms","start":"2026-03-04T01:22:01.794453Z","end":"2026-03-04T01:22:01.998325Z","steps":["trace[1159705417] 'agreement among raft nodes before linearized reading' (duration: 203.724692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:01.998409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.752671ms","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-03-04T01:22:01.998491Z","caller":"traceutil/trace.go:171","msg":"trace[1384397451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"200.941786ms","start":"2026-03-04T01:22:01.797524Z","end":"2026-03-04T01:22:01.998466Z","steps":["trace[1384397451] 'agreement among raft nodes before linearized reading' (duration: 200.634899ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:02.334184Z","caller":"traceutil/trace.go:171","msg":"trace[1794619368] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"331.066305ms","start":"2026-03-04T01:22:02.003083Z","end":"2026-03-04T01:22:02.33415Z","steps":["trace[1794619368] 'process raft request' (duration: 249.83965ms)","trace[1794619368] 'compare' (duration: 81.074931ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:22:02.33433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:02.003066Z","time spent":"331.193507ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:22:02.342604Z","caller":"traceutil/trace.go:171","msg":"trace[2019223460] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"338.600544ms","start":"2026-03-04T01:22:02.003971Z","end":"2026-03-04T01:22:02.342571Z","steps":["trace[2019223460] 'process raft request' (duration: 338.399069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:02.342754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:02.003948Z","time spent":"338.725707ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:22:02.342794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.792927ms","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-03-04T01:22:02.34284Z","caller":"traceutil/trace.go:171","msg":"trace[501430775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"125.892729ms","start":"2026-03-04T01:22:02.216936Z","end":"2026-03-04T01:22:02.342828Z","steps":["trace[501430775] 'agreement among raft nodes before linearized reading' (duration: 125.751866ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:02.342643Z","caller":"traceutil/trace.go:171","msg":"trace[1421353877] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:543; }","duration":"125.50204ms","start":"2026-03-04T01:22:02.216985Z","end":"2026-03-04T01:22:02.342487Z","steps":["trace[1421353877] 'read index received' (duration: 35.945007ms)","trace[1421353877] 'applied index is now lower than readState.Index' (duration: 89.555733ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:22:03.448734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673541043912998787,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T01:22:03.537098Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.013879026s","expected-duration":"1s"} {"level":"info","ts":"2026-03-04T01:22:03.537467Z","caller":"traceutil/trace.go:171","msg":"trace[1454418089] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.014348199s","start":"2026-03-04T01:22:02.523094Z","end":"2026-03-04T01:22:03.537442Z","steps":["trace[1454418089] 'process raft request' (duration: 1.014204965s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:03.537805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:02.523069Z","time spent":"1.014471153s","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:22:03.615443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.287532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:22:03.615496Z","caller":"traceutil/trace.go:171","msg":"trace[1796009524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"667.391654ms","start":"2026-03-04T01:22:02.94809Z","end":"2026-03-04T01:22:03.615482Z","steps":["trace[1796009524] 'agreement among raft nodes before linearized reading' (duration: 667.287152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:03.615533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:02.948069Z","time spent":"667.456516ms","remote":"127.0.0.1:45544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-04T01:22:03.615321Z","caller":"traceutil/trace.go:171","msg":"trace[1027098920] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:546; }","duration":"667.108628ms","start":"2026-03-04T01:22:02.948141Z","end":"2026-03-04T01:22:03.61525Z","steps":["trace[1027098920] 'read index received' (duration: 589.274805ms)","trace[1027098920] 'applied index is now lower than readState.Index' (duration: 77.832283ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:22:05.162345Z","caller":"traceutil/trace.go:171","msg":"trace[488446543] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"123.833741ms","start":"2026-03-04T01:22:05.038488Z","end":"2026-03-04T01:22:05.162321Z","steps":["trace[488446543] 'process raft request' (duration: 123.725318ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:05.90874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.258625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:22:05.90892Z","caller":"traceutil/trace.go:171","msg":"trace[498406665] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"302.582577ms","start":"2026-03-04T01:22:05.606268Z","end":"2026-03-04T01:22:05.90885Z","steps":["trace[498406665] 'process raft request' (duration: 35.105817ms)","trace[498406665] 'compare' (duration: 267.110821ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:22:05.90903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:05.606251Z","time spent":"302.74253ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:22:05.945418Z","caller":"traceutil/trace.go:171","msg":"trace[1322879494] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:564; }","duration":"335.48598ms","start":"2026-03-04T01:22:05.609881Z","end":"2026-03-04T01:22:05.945366Z","steps":["trace[1322879494] 'read index received' (duration: 31.597483ms)","trace[1322879494] 'applied index is now lower than readState.Index' (duration: 303.887237ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:22:05.945514Z","caller":"traceutil/trace.go:171","msg":"trace[668157517] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"335.672415ms","start":"2026-03-04T01:22:05.609831Z","end":"2026-03-04T01:22:05.945503Z","steps":["trace[668157517] 'process raft request' (duration: 335.499111ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:05.945517Z","caller":"traceutil/trace.go:171","msg":"trace[1625700664] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"335.694105ms","start":"2026-03-04T01:22:05.609776Z","end":"2026-03-04T01:22:05.94547Z","steps":["trace[1625700664] 'process raft request' (duration: 335.467319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:05.94562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:05.609826Z","time spent":"335.751826ms","remote":"127.0.0.1:45588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T01:22:05.945689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:05.609765Z","time spent":"335.838079ms","remote":"127.0.0.1:45588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T01:22:05.946125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.158046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:22:05.946375Z","caller":"traceutil/trace.go:171","msg":"trace[1605260294] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:551; }","duration":"336.476953ms","start":"2026-03-04T01:22:05.609867Z","end":"2026-03-04T01:22:05.946344Z","steps":["trace[1605260294] 'agreement among raft nodes before linearized reading' (duration: 335.90975ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:05.946585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:05.609863Z","time spent":"336.70622ms","remote":"127.0.0.1:45588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-03-04T01:22:05.947361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.440807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:22:05.947427Z","caller":"traceutil/trace.go:171","msg":"trace[56264159] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"337.516959ms","start":"2026-03-04T01:22:05.609894Z","end":"2026-03-04T01:22:05.947411Z","steps":["trace[56264159] 'agreement among raft nodes before linearized reading' (duration: 337.401577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:05.947463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:05.609891Z","time spent":"337.56414ms","remote":"127.0.0.1:45588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-03-04T01:22:05.947576Z","caller":"traceutil/trace.go:171","msg":"trace[978073106] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"294.245788ms","start":"2026-03-04T01:22:05.653319Z","end":"2026-03-04T01:22:05.947565Z","steps":["trace[978073106] 'process raft request' (duration: 293.88534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:05.947844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.298079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:22:05.947885Z","caller":"traceutil/trace.go:171","msg":"trace[1282762201] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"142.403432ms","start":"2026-03-04T01:22:05.80547Z","end":"2026-03-04T01:22:05.947873Z","steps":["trace[1282762201] 'agreement among raft nodes before linearized reading' (duration: 142.351981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:05.947984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.648927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T01:22:05.948066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.78474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:22:05.948078Z","caller":"traceutil/trace.go:171","msg":"trace[2056894287] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"336.76176ms","start":"2026-03-04T01:22:05.611293Z","end":"2026-03-04T01:22:05.948054Z","steps":["trace[2056894287] 'agreement among raft nodes before linearized reading' (duration: 336.616427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:05.948138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:05.611283Z","time spent":"336.841832ms","remote":"127.0.0.1:45588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-03-04T01:22:05.948154Z","caller":"traceutil/trace.go:171","msg":"trace[410663061] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"336.834062ms","start":"2026-03-04T01:22:05.611257Z","end":"2026-03-04T01:22:05.948091Z","steps":["trace[410663061] 'agreement among raft nodes before linearized reading' (duration: 336.772291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:05.948201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.114325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:22:05.948246Z","caller":"traceutil/trace.go:171","msg":"trace[1446828213] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"336.169087ms","start":"2026-03-04T01:22:05.612067Z","end":"2026-03-04T01:22:05.948236Z","steps":["trace[1446828213] 'agreement among raft nodes before linearized reading' (duration: 336.107335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:05.948283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:05.612063Z","time spent":"336.213368ms","remote":"127.0.0.1:45588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-03-04T01:22:05.948214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:05.611252Z","time spent":"336.954355ms","remote":"127.0.0.1:45588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-03-04T01:22:05.948408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.230779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-zd6fj\" ","response":"range_response_count:1 size:4683"} {"level":"warn","ts":"2026-03-04T01:22:05.948414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.647398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:22:05.948438Z","caller":"traceutil/trace.go:171","msg":"trace[1307276141] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-zd6fj; range_end:; response_count:1; response_revision:552; }","duration":"297.29314ms","start":"2026-03-04T01:22:05.651138Z","end":"2026-03-04T01:22:05.948431Z","steps":["trace[1307276141] 'agreement among raft nodes before linearized reading' (duration: 297.098226ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:05.948447Z","caller":"traceutil/trace.go:171","msg":"trace[1049105240] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"336.685428ms","start":"2026-03-04T01:22:05.611751Z","end":"2026-03-04T01:22:05.948437Z","steps":["trace[1049105240] 'agreement among raft nodes before linearized reading' (duration: 336.642577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:05.94848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:05.611747Z","time spent":"336.727139ms","remote":"127.0.0.1:45588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-03-04T01:22:06.342326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.399668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:22:06.342502Z","caller":"traceutil/trace.go:171","msg":"trace[339333641] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"387.473888ms","start":"2026-03-04T01:22:05.955001Z","end":"2026-03-04T01:22:06.342475Z","steps":["trace[339333641] 'process raft request' (duration: 127.795593ms)","trace[339333641] 'compare' (duration: 259.180683ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:22:06.342597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:05.954939Z","time spent":"387.605441ms","remote":"127.0.0.1:45588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:22:06.404442Z","caller":"traceutil/trace.go:171","msg":"trace[381926701] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"449.434254ms","start":"2026-03-04T01:22:05.954971Z","end":"2026-03-04T01:22:06.404405Z","steps":["trace[381926701] 'process raft request' (duration: 449.172248ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:06.40445Z","caller":"traceutil/trace.go:171","msg":"trace[1491852837] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:568; }","duration":"398.402399ms","start":"2026-03-04T01:22:06.006017Z","end":"2026-03-04T01:22:06.40442Z","steps":["trace[1491852837] 'read index received' (duration: 76.737417ms)","trace[1491852837] 'applied index is now lower than readState.Index' (duration: 321.663902ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:22:06.404584Z","caller":"traceutil/trace.go:171","msg":"trace[1664423163] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"448.201105ms","start":"2026-03-04T01:22:05.956373Z","end":"2026-03-04T01:22:06.404574Z","steps":["trace[1664423163] 'process raft request' (duration: 448.009311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:06.404594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:05.954938Z","time spent":"449.581917ms","remote":"127.0.0.1:49338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T01:22:06.404703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:05.956355Z","time spent":"448.268966ms","remote":"127.0.0.1:49338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T01:22:06.404715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.676095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:22:06.404792Z","caller":"traceutil/trace.go:171","msg":"trace[1298531604] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"398.801038ms","start":"2026-03-04T01:22:06.005973Z","end":"2026-03-04T01:22:06.404774Z","steps":["trace[1298531604] 'agreement among raft nodes before linearized reading' (duration: 398.645224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:06.404835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:06.005955Z","time spent":"398.870669ms","remote":"127.0.0.1:45588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-03-04T01:22:06.404474Z","caller":"traceutil/trace.go:171","msg":"trace[98987788] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"449.005014ms","start":"2026-03-04T01:22:05.955436Z","end":"2026-03-04T01:22:06.404441Z","steps":["trace[98987788] 'process raft request' (duration: 448.868101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:06.405008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:05.955423Z","time spent":"449.520616ms","remote":"127.0.0.1:45698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4873,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:22:06.511865Z","caller":"traceutil/trace.go:171","msg":"trace[1089847433] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"107.314286ms","start":"2026-03-04T01:22:06.404534Z","end":"2026-03-04T01:22:06.511848Z","steps":["trace[1089847433] 'read index received' (duration: 107.024569ms)","trace[1089847433] 'applied index is now lower than readState.Index' (duration: 288.317µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:22:06.512138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.015878ms","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-03-04T01:22:06.512143Z","caller":"traceutil/trace.go:171","msg":"trace[1405057363] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"159.756185ms","start":"2026-03-04T01:22:06.35235Z","end":"2026-03-04T01:22:06.512106Z","steps":["trace[1405057363] 'process raft request' (duration: 159.302434ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:06.512179Z","caller":"traceutil/trace.go:171","msg":"trace[226592488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"162.10891ms","start":"2026-03-04T01:22:06.35006Z","end":"2026-03-04T01:22:06.512169Z","steps":["trace[226592488] 'agreement among raft nodes before linearized reading' (duration: 161.950586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:06.512222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.522891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-04T01:22:06.512312Z","caller":"traceutil/trace.go:171","msg":"trace[1430984883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"157.715936ms","start":"2026-03-04T01:22:06.354578Z","end":"2026-03-04T01:22:06.512294Z","steps":["trace[1430984883] 'agreement among raft nodes before linearized reading' (duration: 157.44074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:06.512298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.002231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:22:06.512418Z","caller":"traceutil/trace.go:171","msg":"trace[1544130751] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:558; }","duration":"307.193117ms","start":"2026-03-04T01:22:06.205194Z","end":"2026-03-04T01:22:06.512388Z","steps":["trace[1544130751] 'agreement among raft nodes before linearized reading' (duration: 306.957561ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:06.511776Z","caller":"traceutil/trace.go:171","msg":"trace[438141610] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"159.761605ms","start":"2026-03-04T01:22:06.351965Z","end":"2026-03-04T01:22:06.511727Z","steps":["trace[438141610] 'process raft request' (duration: 159.507069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:06.512485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:06.205176Z","time spent":"307.298509ms","remote":"127.0.0.1:45588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-03-04T01:22:06.524044Z","caller":"traceutil/trace.go:171","msg":"trace[1409173987] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"115.284986ms","start":"2026-03-04T01:22:06.408721Z","end":"2026-03-04T01:22:06.524006Z","steps":["trace[1409173987] 'process raft request' (duration: 115.143403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:06.524386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.901151ms","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-03-04T01:22:06.524599Z","caller":"traceutil/trace.go:171","msg":"trace[1001679379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"111.133148ms","start":"2026-03-04T01:22:06.413452Z","end":"2026-03-04T01:22:06.524585Z","steps":["trace[1001679379] 'agreement among raft nodes before linearized reading' (duration: 110.646626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:06.524472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.215512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5rxbz\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-03-04T01:22:06.524845Z","caller":"traceutil/trace.go:171","msg":"trace[1253438252] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5rxbz; range_end:; response_count:1; response_revision:559; }","duration":"117.55453ms","start":"2026-03-04T01:22:06.407195Z","end":"2026-03-04T01:22:06.524749Z","steps":["trace[1253438252] 'agreement among raft nodes before linearized reading' (duration: 116.897494ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:06.629626Z","caller":"traceutil/trace.go:171","msg":"trace[2112547557] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"112.335275ms","start":"2026-03-04T01:22:06.517268Z","end":"2026-03-04T01:22:06.629603Z","steps":["trace[2112547557] 'process raft request' (duration: 99.584502ms)","trace[2112547557] 'compare' (duration: 12.518918ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:22:06.629649Z","caller":"traceutil/trace.go:171","msg":"trace[1768278013] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"112.243753ms","start":"2026-03-04T01:22:06.517378Z","end":"2026-03-04T01:22:06.629622Z","steps":["trace[1768278013] 'process raft request' (duration: 112.155781ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:06.629828Z","caller":"traceutil/trace.go:171","msg":"trace[243418917] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"111.350491ms","start":"2026-03-04T01:22:06.518449Z","end":"2026-03-04T01:22:06.6298Z","steps":["trace[243418917] 'process raft request' (duration: 111.120995ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:06.669129Z","caller":"traceutil/trace.go:171","msg":"trace[469012831] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"139.456201ms","start":"2026-03-04T01:22:06.529652Z","end":"2026-03-04T01:22:06.669108Z","steps":["trace[469012831] 'process raft request' (duration: 139.309847ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:06.669121Z","caller":"traceutil/trace.go:171","msg":"trace[1955486324] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"140.562957ms","start":"2026-03-04T01:22:06.528529Z","end":"2026-03-04T01:22:06.669092Z","steps":["trace[1955486324] 'process raft request' (duration: 140.25626ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:06.669536Z","caller":"traceutil/trace.go:171","msg":"trace[1587577127] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"130.986069ms","start":"2026-03-04T01:22:06.53853Z","end":"2026-03-04T01:22:06.669516Z","steps":["trace[1587577127] 'process raft request' (duration: 130.506397ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:22.339584Z","caller":"traceutil/trace.go:171","msg":"trace[598438228] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"350.490008ms","start":"2026-03-04T01:22:21.989075Z","end":"2026-03-04T01:22:22.339565Z","steps":["trace[598438228] 'process raft request' (duration: 350.363415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:22.339709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:21.989056Z","time spent":"350.59047ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:22:22.924298Z","caller":"traceutil/trace.go:171","msg":"trace[1070985803] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"155.311038ms","start":"2026-03-04T01:22:22.768965Z","end":"2026-03-04T01:22:22.924276Z","steps":["trace[1070985803] 'process raft request' (duration: 155.203936ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:22.980586Z","caller":"traceutil/trace.go:171","msg":"trace[105352744] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"210.207186ms","start":"2026-03-04T01:22:22.770353Z","end":"2026-03-04T01:22:22.98056Z","steps":["trace[105352744] 'process raft request' (duration: 209.916999ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:22.980699Z","caller":"traceutil/trace.go:171","msg":"trace[683904146] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"210.718718ms","start":"2026-03-04T01:22:22.769957Z","end":"2026-03-04T01:22:22.980676Z","steps":["trace[683904146] 'process raft request' (duration: 180.695813ms)","trace[683904146] 'compare' (duration: 29.498653ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:22:23.750215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.475151ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-04T01:22:24.006846Z","caller":"traceutil/trace.go:171","msg":"trace[2059568554] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"252.956014ms","start":"2026-03-04T01:22:23.753872Z","end":"2026-03-04T01:22:24.006828Z","steps":["trace[2059568554] 'process raft request' (duration: 252.835191ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:24.475645Z","caller":"traceutil/trace.go:171","msg":"trace[1866794095] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"129.016533ms","start":"2026-03-04T01:22:24.346604Z","end":"2026-03-04T01:22:24.47562Z","steps":["trace[1866794095] 'process raft request' (duration: 128.846929ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:25.422711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.585646ms","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-03-04T01:22:25.422795Z","caller":"traceutil/trace.go:171","msg":"trace[274805853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:720; }","duration":"438.698989ms","start":"2026-03-04T01:22:24.984074Z","end":"2026-03-04T01:22:25.422773Z","steps":["trace[274805853] 'range keys from in-memory index tree' (duration: 438.413102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:25.42284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:24.984069Z","time spent":"438.76195ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-04T01:22:25.423183Z","caller":"traceutil/trace.go:171","msg":"trace[168033109] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"420.374282ms","start":"2026-03-04T01:22:25.002779Z","end":"2026-03-04T01:22:25.423153Z","steps":["trace[168033109] 'process raft request' (duration: 342.918237ms)","trace[168033109] 'compare' (duration: 77.082926ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:22:25.423221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.493793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T01:22:25.42332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:25.00275Z","time spent":"420.503545ms","remote":"127.0.0.1:45598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T01:22:25.423298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.329633ms","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-03-04T01:22:25.423393Z","caller":"traceutil/trace.go:171","msg":"trace[968432388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"493.735629ms","start":"2026-03-04T01:22:24.929637Z","end":"2026-03-04T01:22:25.423373Z","steps":["trace[968432388] 'range keys from in-memory index tree' (duration: 493.416562ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:25.423409Z","caller":"traceutil/trace.go:171","msg":"trace[1747462423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:720; }","duration":"439.480687ms","start":"2026-03-04T01:22:24.983898Z","end":"2026-03-04T01:22:25.423379Z","steps":["trace[1747462423] 'range keys from in-memory index tree' (duration: 439.074808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:25.423447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:24.929613Z","time spent":"493.82323ms","remote":"127.0.0.1:45544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-04T01:22:25.423463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:24.983878Z","time spent":"439.57448ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T01:22:25.423688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.754933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-04T01:22:25.423721Z","caller":"traceutil/trace.go:171","msg":"trace[1817390607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:720; }","duration":"494.828025ms","start":"2026-03-04T01:22:24.928883Z","end":"2026-03-04T01:22:25.423711Z","steps":["trace[1817390607] 'range keys from in-memory index tree' (duration: 494.607459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:25.423836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:24.928861Z","time spent":"494.967158ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-04T01:22:25.776956Z","caller":"traceutil/trace.go:171","msg":"trace[1746396874] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"349.172525ms","start":"2026-03-04T01:22:25.42776Z","end":"2026-03-04T01:22:25.776933Z","steps":["trace[1746396874] 'process raft request' (duration: 349.041592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:25.777106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:25.427747Z","time spent":"349.282027ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:22:25.777308Z","caller":"traceutil/trace.go:171","msg":"trace[363050810] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"349.413051ms","start":"2026-03-04T01:22:25.427861Z","end":"2026-03-04T01:22:25.777274Z","steps":["trace[363050810] 'process raft request' (duration: 349.028942ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:25.777384Z","caller":"traceutil/trace.go:171","msg":"trace[602578931] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"350.949728ms","start":"2026-03-04T01:22:25.426415Z","end":"2026-03-04T01:22:25.777365Z","steps":["trace[602578931] 'process raft request' (duration: 268.134316ms)","trace[602578931] 'compare' (duration: 81.892661ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:22:25.777475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:25.426394Z","time spent":"351.030671ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:22:25.777499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:25.427851Z","time spent":"349.545464ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:22:46.065779Z","caller":"traceutil/trace.go:171","msg":"trace[1995025375] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"102.053694ms","start":"2026-03-04T01:22:45.963705Z","end":"2026-03-04T01:22:46.065759Z","steps":["trace[1995025375] 'process raft request' (duration: 64.980611ms)","trace[1995025375] 'compare' (duration: 36.991701ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:22:46.868334Z","caller":"traceutil/trace.go:171","msg":"trace[2107291019] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"296.497102ms","start":"2026-03-04T01:22:46.571809Z","end":"2026-03-04T01:22:46.868306Z","steps":["trace[2107291019] 'process raft request' (duration: 296.171174ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:48.508457Z","caller":"traceutil/trace.go:171","msg":"trace[1947335482] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"250.982523ms","start":"2026-03-04T01:22:48.25745Z","end":"2026-03-04T01:22:48.508433Z","steps":["trace[1947335482] 'process raft request' (duration: 250.827679ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:49.398249Z","caller":"traceutil/trace.go:171","msg":"trace[344124227] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"521.834434ms","start":"2026-03-04T01:22:48.876387Z","end":"2026-03-04T01:22:49.398221Z","steps":["trace[344124227] 'process raft request' (duration: 521.682291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:49.398428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:48.876365Z","time spent":"521.979318ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:22:49.896176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.819225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-04T01:22:49.89629Z","caller":"traceutil/trace.go:171","msg":"trace[1033044330] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:795; }","duration":"254.0023ms","start":"2026-03-04T01:22:49.642265Z","end":"2026-03-04T01:22:49.896267Z","steps":["trace[1033044330] 'count revisions from in-memory index tree' (duration: 253.725182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:49.89628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.050441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:22:49.896382Z","caller":"traceutil/trace.go:171","msg":"trace[1046878575] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:795; }","duration":"254.208935ms","start":"2026-03-04T01:22:49.642144Z","end":"2026-03-04T01:22:49.896353Z","steps":["trace[1046878575] 'count revisions from in-memory index tree' (duration: 253.954898ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:50.439246Z","caller":"traceutil/trace.go:171","msg":"trace[1128698893] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"424.689948ms","start":"2026-03-04T01:22:50.014531Z","end":"2026-03-04T01:22:50.439221Z","steps":["trace[1128698893] 'read index received' (duration: 424.517034ms)","trace[1128698893] 'applied index is now lower than readState.Index' (duration: 171.854µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:22:50.439329Z","caller":"traceutil/trace.go:171","msg":"trace[406082521] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"469.073608ms","start":"2026-03-04T01:22:49.970222Z","end":"2026-03-04T01:22:50.439295Z","steps":["trace[406082521] 'process raft request' (duration: 468.860943ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:50.439489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.133226ms","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-03-04T01:22:50.439555Z","caller":"traceutil/trace.go:171","msg":"trace[1197788052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"349.238528ms","start":"2026-03-04T01:22:50.090297Z","end":"2026-03-04T01:22:50.439535Z","steps":["trace[1197788052] 'agreement among raft nodes before linearized reading' (duration: 349.080524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:50.439613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:50.090283Z","time spent":"349.316391ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T01:22:50.439616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:49.970196Z","time spent":"469.214412ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:22:50.43949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.942845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-04T01:22:50.439863Z","caller":"traceutil/trace.go:171","msg":"trace[1382863430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"425.312654ms","start":"2026-03-04T01:22:50.014483Z","end":"2026-03-04T01:22:50.439796Z","steps":["trace[1382863430] 'agreement among raft nodes before linearized reading' (duration: 424.843312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:22:50.439981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:22:50.014466Z","time spent":"425.482319ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-04T01:22:50.598316Z","caller":"traceutil/trace.go:171","msg":"trace[1890453021] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"154.170933ms","start":"2026-03-04T01:22:50.444074Z","end":"2026-03-04T01:22:50.598245Z","steps":["trace[1890453021] 'process raft request' (duration: 151.960996ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:22:50.598636Z","caller":"traceutil/trace.go:171","msg":"trace[1658040833] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"154.327487ms","start":"2026-03-04T01:22:50.444205Z","end":"2026-03-04T01:22:50.598533Z","steps":["trace[1658040833] 'process raft request' (duration: 153.872556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:14.135487Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673541043912999645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T01:23:14.642554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673541043912999645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T01:23:14.678647Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.362228399s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-04T01:23:15.459207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.336469ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-04T01:23:15.459302Z","caller":"traceutil/trace.go:171","msg":"trace[1394517057] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"1.824611084s","start":"2026-03-04T01:23:13.634676Z","end":"2026-03-04T01:23:15.459287Z","steps":["trace[1394517057] 'read index received' (duration: 1.044139692s)","trace[1394517057] 'applied index is now lower than readState.Index' (duration: 780.470002ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:23:15.459608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505790758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:23:15.459638Z","caller":"traceutil/trace.go:171","msg":"trace[538730273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"1.50585943s","start":"2026-03-04T01:23:13.95377Z","end":"2026-03-04T01:23:15.459629Z","steps":["trace[538730273] 'agreement among raft nodes before linearized reading' (duration: 1.505808019s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:15.459656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:13.953754Z","time spent":"1.505898251s","remote":"127.0.0.1:45544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-04T01:23:15.459679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.825008115s","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-03-04T01:23:15.459727Z","caller":"traceutil/trace.go:171","msg":"trace[231879303] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:922; }","duration":"1.82718259s","start":"2026-03-04T01:23:13.632532Z","end":"2026-03-04T01:23:15.459715Z","steps":["trace[231879303] 'agreement among raft nodes before linearized reading' (duration: 1.827002215s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:15.459767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:13.632487Z","time spent":"1.827269092s","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-04T01:23:15.45988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.730175ms","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-03-04T01:23:15.459934Z","caller":"traceutil/trace.go:171","msg":"trace[1713924359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:922; }","duration":"816.818918ms","start":"2026-03-04T01:23:14.643103Z","end":"2026-03-04T01:23:15.459922Z","steps":["trace[1713924359] 'agreement among raft nodes before linearized reading' (duration: 816.663453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:15.459972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:14.643081Z","time spent":"816.880889ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T01:23:15.459946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.851583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-04T01:23:15.460062Z","caller":"traceutil/trace.go:171","msg":"trace[66871160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:922; }","duration":"702.008787ms","start":"2026-03-04T01:23:14.758019Z","end":"2026-03-04T01:23:15.460027Z","steps":["trace[66871160] 'agreement among raft nodes before linearized reading' (duration: 701.697718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:15.460111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.03333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-04T01:23:15.460142Z","caller":"traceutil/trace.go:171","msg":"trace[1433733722] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:922; }","duration":"953.116212ms","start":"2026-03-04T01:23:14.507017Z","end":"2026-03-04T01:23:15.460133Z","steps":["trace[1433733722] 'agreement among raft nodes before linearized reading' (duration: 953.03785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:15.460153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:14.75799Z","time spent":"702.147ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-04T01:23:15.460178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:14.506991Z","time spent":"953.179424ms","remote":"127.0.0.1:45692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-03-04T01:23:15.459978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.405021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-04T01:23:15.460222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.081222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-04T01:23:15.460251Z","caller":"traceutil/trace.go:171","msg":"trace[930386890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:922; }","duration":"701.699829ms","start":"2026-03-04T01:23:14.758532Z","end":"2026-03-04T01:23:15.460232Z","steps":["trace[930386890] 'agreement among raft nodes before linearized reading' (duration: 701.327319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:15.460317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:14.758525Z","time spent":"701.775531ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-04T01:23:15.460315Z","caller":"traceutil/trace.go:171","msg":"trace[1280537161] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:922; }","duration":"191.198516ms","start":"2026-03-04T01:23:15.269093Z","end":"2026-03-04T01:23:15.460292Z","steps":["trace[1280537161] 'agreement among raft nodes before linearized reading' (duration: 191.063852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:15.461608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.87379ms","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-03-04T01:23:15.461682Z","caller":"traceutil/trace.go:171","msg":"trace[1818948382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:922; }","duration":"620.130231ms","start":"2026-03-04T01:23:14.841531Z","end":"2026-03-04T01:23:15.461662Z","steps":["trace[1818948382] 'agreement among raft nodes before linearized reading' (duration: 618.15114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:15.461747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:14.841463Z","time spent":"620.252435ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-04T01:23:27.905957Z","caller":"traceutil/trace.go:171","msg":"trace[731245720] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"233.563943ms","start":"2026-03-04T01:23:27.672365Z","end":"2026-03-04T01:23:27.905929Z","steps":["trace[731245720] 'read index received' (duration: 232.570348ms)","trace[731245720] 'applied index is now lower than readState.Index' (duration: 992.105µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:23:27.906053Z","caller":"traceutil/trace.go:171","msg":"trace[343537544] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"238.395018ms","start":"2026-03-04T01:23:27.667625Z","end":"2026-03-04T01:23:27.90602Z","steps":["trace[343537544] 'process raft request' (duration: 237.29093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:27.906275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.93671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-04T01:23:27.906349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.362097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-04T01:23:27.906266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.893842ms","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-03-04T01:23:27.906384Z","caller":"traceutil/trace.go:171","msg":"trace[805610060] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:958; }","duration":"102.097504ms","start":"2026-03-04T01:23:27.804256Z","end":"2026-03-04T01:23:27.906354Z","steps":["trace[805610060] 'agreement among raft nodes before linearized reading' (duration: 101.897899ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:27.906447Z","caller":"traceutil/trace.go:171","msg":"trace[699129671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:958; }","duration":"234.080417ms","start":"2026-03-04T01:23:27.67235Z","end":"2026-03-04T01:23:27.906431Z","steps":["trace[699129671] 'agreement among raft nodes before linearized reading' (duration: 233.683977ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:27.906449Z","caller":"traceutil/trace.go:171","msg":"trace[314090524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:958; }","duration":"231.515841ms","start":"2026-03-04T01:23:27.674907Z","end":"2026-03-04T01:23:27.906423Z","steps":["trace[314090524] 'agreement among raft nodes before linearized reading' (duration: 231.219243ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:29.512169Z","caller":"traceutil/trace.go:171","msg":"trace[952817840] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"388.800444ms","start":"2026-03-04T01:23:29.123339Z","end":"2026-03-04T01:23:29.512139Z","steps":["trace[952817840] 'process raft request' (duration: 388.64413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:29.512391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:29.123301Z","time spent":"388.964499ms","remote":"127.0.0.1:45698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10804,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:23:32.166032Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673541043912999796,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-04T01:23:32.475422Z","caller":"traceutil/trace.go:171","msg":"trace[2115443621] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"856.452936ms","start":"2026-03-04T01:23:31.618942Z","end":"2026-03-04T01:23:32.475395Z","steps":["trace[2115443621] 'process raft request' (duration: 856.286051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:32.475663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:31.618924Z","time spent":"856.57833ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:23:32.938168Z","caller":"traceutil/trace.go:171","msg":"trace[1598666514] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"1.068791775s","start":"2026-03-04T01:23:31.869346Z","end":"2026-03-04T01:23:32.938138Z","steps":["trace[1598666514] 'process raft request' (duration: 1.068694833s)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:32.938224Z","caller":"traceutil/trace.go:171","msg":"trace[1604010579] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"1.090059122s","start":"2026-03-04T01:23:31.848113Z","end":"2026-03-04T01:23:32.938172Z","steps":["trace[1604010579] 'process raft request' (duration: 1.089744014s)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:32.938284Z","caller":"traceutil/trace.go:171","msg":"trace[520756518] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1021; }","duration":"1.275883189s","start":"2026-03-04T01:23:31.662363Z","end":"2026-03-04T01:23:32.938247Z","steps":["trace[520756518] 'read index received' (duration: 813.086271ms)","trace[520756518] 'applied index is now lower than readState.Index' (duration: 462.794308ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:23:32.938382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:31.869311Z","time spent":"1.068950209s","remote":"127.0.0.1:45694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5956,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:23:32.938485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:31.84807Z","time spent":"1.090265547s","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:23:32.938549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007645523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:23:32.938519Z","caller":"traceutil/trace.go:171","msg":"trace[1247310078] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"479.930428ms","start":"2026-03-04T01:23:32.458569Z","end":"2026-03-04T01:23:32.9385Z","steps":["trace[1247310078] 'process raft request' (duration: 479.516238ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:32.938592Z","caller":"traceutil/trace.go:171","msg":"trace[1714837786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"1.007739336s","start":"2026-03-04T01:23:31.930839Z","end":"2026-03-04T01:23:32.938579Z","steps":["trace[1714837786] 'agreement among raft nodes before linearized reading' (duration: 1.007675685s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:32.938627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:31.930811Z","time spent":"1.007811219s","remote":"127.0.0.1:45546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-04T01:23:32.938679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:32.458535Z","time spent":"480.060191ms","remote":"127.0.0.1:45598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T01:23:32.938813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.065333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-04T01:23:32.938821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.926882ms","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-03-04T01:23:32.938845Z","caller":"traceutil/trace.go:171","msg":"trace[1592798563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"992.190326ms","start":"2026-03-04T01:23:31.946648Z","end":"2026-03-04T01:23:32.938838Z","steps":["trace[1592798563] 'agreement among raft nodes before linearized reading' (duration: 992.085333ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:32.938858Z","caller":"traceutil/trace.go:171","msg":"trace[1475977652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:987; }","duration":"961.008294ms","start":"2026-03-04T01:23:31.977839Z","end":"2026-03-04T01:23:32.938847Z","steps":["trace[1475977652] 'agreement among raft nodes before linearized reading' (duration: 960.885521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:32.938898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:31.97782Z","time spent":"961.071765ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T01:23:32.939058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27670588s","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":"warn","ts":"2026-03-04T01:23:32.938866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:31.946622Z","time spent":"992.239268ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-04T01:23:32.939092Z","caller":"traceutil/trace.go:171","msg":"trace[2006209107] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:987; }","duration":"1.276794232s","start":"2026-03-04T01:23:31.66229Z","end":"2026-03-04T01:23:32.939084Z","steps":["trace[2006209107] 'agreement among raft nodes before linearized reading' (duration: 1.27670966s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:32.939118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:31.66226Z","time spent":"1.276851654s","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-04T01:23:32.939158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.975603ms","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-03-04T01:23:32.939211Z","caller":"traceutil/trace.go:171","msg":"trace[1423347796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:987; }","duration":"961.038865ms","start":"2026-03-04T01:23:31.978158Z","end":"2026-03-04T01:23:32.939197Z","steps":["trace[1423347796] 'agreement among raft nodes before linearized reading' (duration: 960.85188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:32.93925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:31.978153Z","time spent":"961.090336ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-04T01:23:33.110782Z","caller":"traceutil/trace.go:171","msg":"trace[288471145] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"164.974021ms","start":"2026-03-04T01:23:32.945773Z","end":"2026-03-04T01:23:33.110747Z","steps":["trace[288471145] 'process raft request' (duration: 164.738525ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:33.111134Z","caller":"traceutil/trace.go:171","msg":"trace[186078643] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"164.781625ms","start":"2026-03-04T01:23:32.946337Z","end":"2026-03-04T01:23:33.111119Z","steps":["trace[186078643] 'process raft request' (duration: 164.333544ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:33.111236Z","caller":"traceutil/trace.go:171","msg":"trace[1333241363] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"163.876112ms","start":"2026-03-04T01:23:32.947326Z","end":"2026-03-04T01:23:33.111202Z","steps":["trace[1333241363] 'process raft request' (duration: 163.627576ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:33.203132Z","caller":"traceutil/trace.go:171","msg":"trace[1618322721] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1024; }","duration":"252.589243ms","start":"2026-03-04T01:23:32.95051Z","end":"2026-03-04T01:23:33.203099Z","steps":["trace[1618322721] 'read index received' (duration: 75.378208ms)","trace[1618322721] 'applied index is now lower than readState.Index' (duration: 177.208955ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:23:33.203256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.729996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:23:33.20331Z","caller":"traceutil/trace.go:171","msg":"trace[1943275359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"252.830609ms","start":"2026-03-04T01:23:32.950466Z","end":"2026-03-04T01:23:33.203297Z","steps":["trace[1943275359] 'agreement among raft nodes before linearized reading' (duration: 252.730206ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:33.73253Z","caller":"traceutil/trace.go:171","msg":"trace[1155860792] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"173.88963ms","start":"2026-03-04T01:23:33.558615Z","end":"2026-03-04T01:23:33.732504Z","steps":["trace[1155860792] 'process raft request' (duration: 173.09741ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:35.220888Z","caller":"traceutil/trace.go:171","msg":"trace[1020603811] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1032; }","duration":"268.608696ms","start":"2026-03-04T01:23:34.952253Z","end":"2026-03-04T01:23:35.220862Z","steps":["trace[1020603811] 'read index received' (duration: 267.965368ms)","trace[1020603811] 'applied index is now lower than readState.Index' (duration: 642.028µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:23:35.220955Z","caller":"traceutil/trace.go:171","msg":"trace[1837490303] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"735.233401ms","start":"2026-03-04T01:23:34.485682Z","end":"2026-03-04T01:23:35.220916Z","steps":["trace[1837490303] 'process raft request' (duration: 734.534602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:35.221093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.819601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T01:23:35.221137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.076793ms","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-03-04T01:23:35.22115Z","caller":"traceutil/trace.go:171","msg":"trace[1454866406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:995; }","duration":"268.943344ms","start":"2026-03-04T01:23:34.952192Z","end":"2026-03-04T01:23:35.221135Z","steps":["trace[1454866406] 'agreement among raft nodes before linearized reading' (duration: 268.819701ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:35.221168Z","caller":"traceutil/trace.go:171","msg":"trace[266446622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:995; }","duration":"191.163995ms","start":"2026-03-04T01:23:35.029995Z","end":"2026-03-04T01:23:35.221159Z","steps":["trace[266446622] 'agreement among raft nodes before linearized reading' (duration: 191.021252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:35.221152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:34.485656Z","time spent":"735.397846ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:23:35.221377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.010387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-04T01:23:35.221408Z","caller":"traceutil/trace.go:171","msg":"trace[1656484206] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:995; }","duration":"143.072778ms","start":"2026-03-04T01:23:35.078324Z","end":"2026-03-04T01:23:35.221397Z","steps":["trace[1656484206] 'agreement among raft nodes before linearized reading' (duration: 143.028587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:35.855462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.120349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:23:35.855586Z","caller":"traceutil/trace.go:171","msg":"trace[1449585902] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"629.538124ms","start":"2026-03-04T01:23:35.226023Z","end":"2026-03-04T01:23:35.855561Z","steps":["trace[1449585902] 'process raft request' (duration: 104.2321ms)","trace[1449585902] 'compare' (duration: 509.179609ms)","trace[1449585902] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:419; } (duration: 15.838258ms)"],"step_count":3} {"level":"warn","ts":"2026-03-04T01:23:35.855641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:35.226008Z","time spent":"629.610875ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:23:36.100233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.071319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:23:36.100743Z","caller":"traceutil/trace.go:171","msg":"trace[873961451] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"874.305216ms","start":"2026-03-04T01:23:35.226415Z","end":"2026-03-04T01:23:36.10072Z","steps":["trace[873961451] 'process raft request' (duration: 721.622801ms)","trace[873961451] 'compare' (duration: 151.897465ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:23:36.100833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:35.226408Z","time spent":"874.389198ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:23:36.100901Z","caller":"traceutil/trace.go:171","msg":"trace[326951689] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"874.381907ms","start":"2026-03-04T01:23:35.226482Z","end":"2026-03-04T01:23:36.100863Z","steps":["trace[326951689] 'process raft request' (duration: 873.866363ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:36.100953Z","caller":"traceutil/trace.go:171","msg":"trace[2018249027] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"873.090934ms","start":"2026-03-04T01:23:35.227841Z","end":"2026-03-04T01:23:36.100932Z","steps":["trace[2018249027] 'process raft request' (duration: 872.562809ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:36.101126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:35.226467Z","time spent":"874.51603ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:23:36.101297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:35.227805Z","time spent":"873.203457ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:23:38.405677Z","caller":"traceutil/trace.go:171","msg":"trace[48657819] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"457.266174ms","start":"2026-03-04T01:23:37.948382Z","end":"2026-03-04T01:23:38.405649Z","steps":["trace[48657819] 'read index received' (duration: 457.09886ms)","trace[48657819] 'applied index is now lower than readState.Index' (duration: 166.414µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:23:38.411269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.868388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-04T01:23:38.411496Z","caller":"traceutil/trace.go:171","msg":"trace[12849679] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:1; response_revision:1001; }","duration":"463.039623ms","start":"2026-03-04T01:23:37.948363Z","end":"2026-03-04T01:23:38.411402Z","steps":["trace[12849679] 'agreement among raft nodes before linearized reading' (duration: 457.432159ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:38.411672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.932775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-04T01:23:38.411767Z","caller":"traceutil/trace.go:171","msg":"trace[1570771011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1001; }","duration":"306.040858ms","start":"2026-03-04T01:23:38.10568Z","end":"2026-03-04T01:23:38.41172Z","steps":["trace[1570771011] 'agreement among raft nodes before linearized reading' (duration: 305.927984ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:38.411969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:37.94833Z","time spent":"463.350262ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.195\" "} {"level":"info","ts":"2026-03-04T01:23:38.412318Z","caller":"traceutil/trace.go:171","msg":"trace[1508929748] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"537.139678ms","start":"2026-03-04T01:23:37.868586Z","end":"2026-03-04T01:23:38.405725Z","steps":["trace[1508929748] 'process raft request' (duration: 536.886052ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:38.411827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:38.105649Z","time spent":"306.168861ms","remote":"127.0.0.1:49100","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-03-04T01:23:38.412523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:37.868552Z","time spent":"543.824389ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:23:38.41277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.339891ms","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-03-04T01:23:38.412956Z","caller":"traceutil/trace.go:171","msg":"trace[1797547714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"307.533865ms","start":"2026-03-04T01:23:38.105391Z","end":"2026-03-04T01:23:38.412925Z","steps":["trace[1797547714] 'agreement among raft nodes before linearized reading' (duration: 307.159126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:38.413141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:38.105377Z","time spent":"307.747441ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T01:23:38.411486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.081027ms","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-03-04T01:23:38.417641Z","caller":"traceutil/trace.go:171","msg":"trace[211299030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"312.306808ms","start":"2026-03-04T01:23:38.105308Z","end":"2026-03-04T01:23:38.417615Z","steps":["trace[211299030] 'agreement among raft nodes before linearized reading' (duration: 305.920833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:38.417913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:38.105286Z","time spent":"312.484263ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-04T01:23:38.770224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.657041ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-03-04T01:23:38.770671Z","caller":"traceutil/trace.go:171","msg":"trace[1944602148] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"323.474306ms","start":"2026-03-04T01:23:38.447176Z","end":"2026-03-04T01:23:38.77065Z","steps":["trace[1944602148] 'process raft request' (duration: 323.434375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:38.770799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:38.447158Z","time spent":"323.564788ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:23:38.77091Z","caller":"traceutil/trace.go:171","msg":"trace[289949811] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"349.074234ms","start":"2026-03-04T01:23:38.421802Z","end":"2026-03-04T01:23:38.770876Z","steps":["trace[289949811] 'process raft request' (duration: 348.769156ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:38.771039Z","caller":"traceutil/trace.go:171","msg":"trace[539225411] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"350.039037ms","start":"2026-03-04T01:23:38.420823Z","end":"2026-03-04T01:23:38.770862Z","steps":["trace[539225411] 'process raft request' (duration: 349.582656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:38.77112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:38.421795Z","time spent":"349.230368ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:23:38.771122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:38.418573Z","time spent":"352.531242ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-04T01:23:38.771228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:38.420804Z","time spent":"350.329055ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:23:39.036743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.749693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:23:39.03683Z","caller":"traceutil/trace.go:171","msg":"trace[1089052171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"104.888857ms","start":"2026-03-04T01:23:38.93192Z","end":"2026-03-04T01:23:39.036809Z","steps":["trace[1089052171] 'range keys from in-memory index tree' (duration: 104.659971ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:39.56339Z","caller":"traceutil/trace.go:171","msg":"trace[416886935] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"234.132437ms","start":"2026-03-04T01:23:39.329233Z","end":"2026-03-04T01:23:39.563365Z","steps":["trace[416886935] 'process raft request' (duration: 233.965903ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:40.425027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.035457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:23:40.425133Z","caller":"traceutil/trace.go:171","msg":"trace[595345523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"224.222182ms","start":"2026-03-04T01:23:40.200887Z","end":"2026-03-04T01:23:40.425109Z","steps":["trace[595345523] 'range keys from in-memory index tree' (duration: 223.719169ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:26:54.891819Z","caller":"traceutil/trace.go:171","msg":"trace[723022298] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"301.360544ms","start":"2026-03-04T01:26:54.59041Z","end":"2026-03-04T01:26:54.89177Z","steps":["trace[723022298] 'process raft request' (duration: 301.20464ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:26:54.892017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:26:54.590394Z","time spent":"301.508909ms","remote":"127.0.0.1:49100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}