{"level":"warn","ts":"2026-01-31T18:49:12.002745Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T18:49:12.002845Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.250: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.250:2380","--initial-cluster=instance=https://162.253.55.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.250:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-01-31T18:49:12.002941Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T18:49:12.00447Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.250:2380"]} {"level":"info","ts":"2026-01-31T18:49:12.004497Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-31T18:49:12.006195Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"]} {"level":"info","ts":"2026-01-31T18:49:12.009052Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.250:2380"],"listen-peer-urls":["https://162.253.55.250:2380"],"advertise-client-urls":["https://162.253.55.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.250:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-01-31T18:49:12.016563Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.197783ms"} {"level":"info","ts":"2026-01-31T18:49:12.056373Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1e4a0bccff66c64d","cluster-id":"8ba64f6e83da771"} {"level":"info","ts":"2026-01-31T18:49:12.056456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=()"} {"level":"info","ts":"2026-01-31T18:49:12.05648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became follower at term 0"} {"level":"info","ts":"2026-01-31T18:49:12.056491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1e4a0bccff66c64d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-31T18:49:12.056519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became follower at term 1"} {"level":"info","ts":"2026-01-31T18:49:12.05659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=(2182569944500586061)"} {"level":"warn","ts":"2026-01-31T18:49:12.06384Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-31T18:49:12.067352Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-31T18:49:12.071335Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-01-31T18:49:12.074566Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1e4a0bccff66c64d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-31T18:49:12.074873Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1e4a0bccff66c64d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-31T18:49:12.074996Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-31T18:49:12.075048Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-31T18:49:12.075056Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-31T18:49:12.076555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=(2182569944500586061)"} {"level":"info","ts":"2026-01-31T18:49:12.076695Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ba64f6e83da771","local-member-id":"1e4a0bccff66c64d","added-peer-id":"1e4a0bccff66c64d","added-peer-peer-urls":["https://162.253.55.250:2380"]} {"level":"info","ts":"2026-01-31T18:49:12.090841Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-31T18:49:12.090947Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.250:2380"} {"level":"info","ts":"2026-01-31T18:49:12.09096Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.250:2380"} {"level":"info","ts":"2026-01-31T18:49:12.09304Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1e4a0bccff66c64d","initial-advertise-peer-urls":["https://162.253.55.250:2380"],"listen-peer-urls":["https://162.253.55.250:2380"],"advertise-client-urls":["https://162.253.55.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-31T18:49:12.093068Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-31T18:49:12.857665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d is starting a new election at term 1"} {"level":"info","ts":"2026-01-31T18:49:12.857707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became pre-candidate at term 1"} {"level":"info","ts":"2026-01-31T18:49:12.857728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d received MsgPreVoteResp from 1e4a0bccff66c64d at term 1"} {"level":"info","ts":"2026-01-31T18:49:12.857739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became candidate at term 2"} {"level":"info","ts":"2026-01-31T18:49:12.857772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d received MsgVoteResp from 1e4a0bccff66c64d at term 2"} {"level":"info","ts":"2026-01-31T18:49:12.857783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became leader at term 2"} {"level":"info","ts":"2026-01-31T18:49:12.85779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1e4a0bccff66c64d elected leader 1e4a0bccff66c64d at term 2"} {"level":"info","ts":"2026-01-31T18:49:12.860016Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T18:49:12.862105Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1e4a0bccff66c64d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.250:2379]}","request-path":"/0/members/1e4a0bccff66c64d/attributes","cluster-id":"8ba64f6e83da771","publish-timeout":"7s"} {"level":"info","ts":"2026-01-31T18:49:12.862148Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T18:49:12.863118Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T18:49:12.867307Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ba64f6e83da771","local-member-id":"1e4a0bccff66c64d","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T18:49:12.867458Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T18:49:12.867601Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T18:49:12.869016Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-31T18:49:12.869033Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-31T18:49:12.869459Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-31T18:49:12.87059Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.250:2379"} {"level":"warn","ts":"2026-01-31T18:49:31.898899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.207628ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-31T18:49:31.899097Z","caller":"traceutil/trace.go:171","msg":"trace[1191739307] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"571.915166ms","start":"2026-01-31T18:49:31.327157Z","end":"2026-01-31T18:49:31.899072Z","steps":["trace[1191739307] 'read index received' (duration: 55.386561ms)","trace[1191739307] 'applied index is now lower than readState.Index' (duration: 516.526625ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:49:31.899245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.065909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-31T18:49:31.899275Z","caller":"traceutil/trace.go:171","msg":"trace[75634250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:450; }","duration":"572.147301ms","start":"2026-01-31T18:49:31.327117Z","end":"2026-01-31T18:49:31.899264Z","steps":["trace[75634250] 'agreement among raft nodes before linearized reading' (duration: 572.030349ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:31.899346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:31.327095Z","time spent":"572.215442ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-31T18:49:32.032153Z","caller":"traceutil/trace.go:171","msg":"trace[1429341847] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"124.578542ms","start":"2026-01-31T18:49:31.907543Z","end":"2026-01-31T18:49:32.032122Z","steps":["trace[1429341847] 'process raft request' (duration: 124.364157ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:32.032709Z","caller":"traceutil/trace.go:171","msg":"trace[722292151] linearizableReadLoop","detail":"{readStateIndex:467; appliedIndex:467; }","duration":"115.079696ms","start":"2026-01-31T18:49:31.917619Z","end":"2026-01-31T18:49:32.032698Z","steps":["trace[722292151] 'read index received' (duration: 115.073386ms)","trace[722292151] 'applied index is now lower than readState.Index' (duration: 5.17µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:49:32.032854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.20991ms","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-01-31T18:49:32.032881Z","caller":"traceutil/trace.go:171","msg":"trace[1563552859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:451; }","duration":"115.294212ms","start":"2026-01-31T18:49:31.917579Z","end":"2026-01-31T18:49:32.032873Z","steps":["trace[1563552859] 'agreement among raft nodes before linearized reading' (duration: 115.20057ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:38.944526Z","caller":"traceutil/trace.go:171","msg":"trace[2062509231] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"125.89025ms","start":"2026-01-31T18:49:38.818611Z","end":"2026-01-31T18:49:38.944501Z","steps":["trace[2062509231] 'process raft request' (duration: 125.742277ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:45.730507Z","caller":"traceutil/trace.go:171","msg":"trace[1497987713] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"108.050094ms","start":"2026-01-31T18:49:45.622443Z","end":"2026-01-31T18:49:45.730493Z","steps":["trace[1497987713] 'process raft request' (duration: 107.502382ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:46.524609Z","caller":"traceutil/trace.go:171","msg":"trace[1100348899] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"508.482341ms","start":"2026-01-31T18:49:46.016109Z","end":"2026-01-31T18:49:46.524592Z","steps":["trace[1100348899] 'process raft request' (duration: 508.363368ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:46.524929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:46.01609Z","time spent":"508.575912ms","remote":"127.0.0.1:49444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-31T18:49:46.525283Z","caller":"traceutil/trace.go:171","msg":"trace[1996952929] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:569; }","duration":"413.06333ms","start":"2026-01-31T18:49:46.112212Z","end":"2026-01-31T18:49:46.525275Z","steps":["trace[1996952929] 'read index received' (duration: 413.06036ms)","trace[1996952929] 'applied index is now lower than readState.Index' (duration: 2.42µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:49:46.525403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.186293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-31T18:49:46.525425Z","caller":"traceutil/trace.go:171","msg":"trace[1733944083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"413.244665ms","start":"2026-01-31T18:49:46.112173Z","end":"2026-01-31T18:49:46.525418Z","steps":["trace[1733944083] 'agreement among raft nodes before linearized reading' (duration: 413.149933ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:46.525458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:46.112153Z","time spent":"413.287315ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-31T18:49:46.738403Z","caller":"traceutil/trace.go:171","msg":"trace[224128786] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:569; }","duration":"213.075832ms","start":"2026-01-31T18:49:46.525308Z","end":"2026-01-31T18:49:46.738383Z","steps":["trace[224128786] 'read index received' (duration: 192.536727ms)","trace[224128786] 'applied index is now lower than readState.Index' (duration: 20.538145ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:49:46.738626Z","caller":"traceutil/trace.go:171","msg":"trace[1095689140] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"522.77329ms","start":"2026-01-31T18:49:46.215841Z","end":"2026-01-31T18:49:46.738615Z","steps":["trace[1095689140] 'process raft request' (duration: 501.98874ms)","trace[1095689140] 'compare' (duration: 20.034554ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:49:46.738712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:46.215822Z","time spent":"522.839092ms","remote":"127.0.0.1:49444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-31T18:49:46.738832Z","caller":"traceutil/trace.go:171","msg":"trace[923275646] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"324.312476ms","start":"2026-01-31T18:49:46.41451Z","end":"2026-01-31T18:49:46.738823Z","steps":["trace[923275646] 'process raft request' (duration: 323.793075ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:46.738884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:46.414499Z","time spent":"324.363927ms","remote":"127.0.0.1:49444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-31T18:49:46.739026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.908598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:49:46.739057Z","caller":"traceutil/trace.go:171","msg":"trace[970794580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"289.96173ms","start":"2026-01-31T18:49:46.449087Z","end":"2026-01-31T18:49:46.739048Z","steps":["trace[970794580] 'agreement among raft nodes before linearized reading' (duration: 289.888608ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:46.739249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.796761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T18:49:46.739282Z","caller":"traceutil/trace.go:171","msg":"trace[270284175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"327.856762ms","start":"2026-01-31T18:49:46.411418Z","end":"2026-01-31T18:49:46.739274Z","steps":["trace[270284175] 'agreement among raft nodes before linearized reading' (duration: 327.74833ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:46.739308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:46.411402Z","time spent":"327.899853ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T18:49:46.73949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.939503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-31T18:49:46.739526Z","caller":"traceutil/trace.go:171","msg":"trace[192141019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"610.998144ms","start":"2026-01-31T18:49:46.12852Z","end":"2026-01-31T18:49:46.739518Z","steps":["trace[192141019] 'agreement among raft nodes before linearized reading' (duration: 610.907622ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:46.739552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:46.128508Z","time spent":"611.037794ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-31T18:49:46.990885Z","caller":"traceutil/trace.go:171","msg":"trace[2049948035] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"252.399585ms","start":"2026-01-31T18:49:46.73847Z","end":"2026-01-31T18:49:46.99087Z","steps":["trace[2049948035] 'read index received' (duration: 252.225572ms)","trace[2049948035] 'applied index is now lower than readState.Index' (duration: 173.373µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:49:46.990968Z","caller":"traceutil/trace.go:171","msg":"trace[1012485644] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"458.505706ms","start":"2026-01-31T18:49:46.532454Z","end":"2026-01-31T18:49:46.990959Z","steps":["trace[1012485644] 'process raft request' (duration: 458.059607ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:46.99116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:46.532436Z","time spent":"458.557698ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:49:46.992029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.848204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:49:46.992063Z","caller":"traceutil/trace.go:171","msg":"trace[1186122336] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:554; }","duration":"376.925066ms","start":"2026-01-31T18:49:46.61513Z","end":"2026-01-31T18:49:46.992055Z","steps":["trace[1186122336] 'agreement among raft nodes before linearized reading' (duration: 376.868694ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:46.992086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:46.615093Z","time spent":"376.988867ms","remote":"127.0.0.1:49444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-01-31T18:49:46.99215Z","caller":"traceutil/trace.go:171","msg":"trace[54812717] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"454.063841ms","start":"2026-01-31T18:49:46.538081Z","end":"2026-01-31T18:49:46.992145Z","steps":["trace[54812717] 'process raft request' (duration: 452.552127ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:46.992183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:46.538068Z","time spent":"454.094262ms","remote":"127.0.0.1:49444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:49:47.012884Z","caller":"traceutil/trace.go:171","msg":"trace[65211455] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"260.182134ms","start":"2026-01-31T18:49:46.752689Z","end":"2026-01-31T18:49:47.012871Z","steps":["trace[65211455] 'process raft request' (duration: 260.154913ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:47.013315Z","caller":"traceutil/trace.go:171","msg":"trace[2049943773] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"260.728856ms","start":"2026-01-31T18:49:46.752569Z","end":"2026-01-31T18:49:47.013298Z","steps":["trace[2049943773] 'process raft request' (duration: 260.210545ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:47.013669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.931694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:49:47.013743Z","caller":"traceutil/trace.go:171","msg":"trace[406342816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"263.025056ms","start":"2026-01-31T18:49:46.750703Z","end":"2026-01-31T18:49:47.013728Z","steps":["trace[406342816] 'agreement among raft nodes before linearized reading' (duration: 262.938524ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:47.014808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.008086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T18:49:47.014874Z","caller":"traceutil/trace.go:171","msg":"trace[259588962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:556; }","duration":"139.113957ms","start":"2026-01-31T18:49:46.875753Z","end":"2026-01-31T18:49:47.014867Z","steps":["trace[259588962] 'agreement among raft nodes before linearized reading' (duration: 139.015595ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:47.015123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.873858ms","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-01-31T18:49:47.015801Z","caller":"traceutil/trace.go:171","msg":"trace[868606397] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"201.574483ms","start":"2026-01-31T18:49:46.814217Z","end":"2026-01-31T18:49:47.015792Z","steps":["trace[868606397] 'agreement among raft nodes before linearized reading' (duration: 200.887099ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:50.126549Z","caller":"traceutil/trace.go:171","msg":"trace[651524712] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"288.301164ms","start":"2026-01-31T18:49:49.837612Z","end":"2026-01-31T18:49:50.125914Z","steps":["trace[651524712] 'process raft request' (duration: 287.927526ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:51.527747Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14289248808389868771,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-31T18:49:51.661996Z","caller":"traceutil/trace.go:171","msg":"trace[912239463] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"634.420092ms","start":"2026-01-31T18:49:51.027555Z","end":"2026-01-31T18:49:51.661975Z","steps":["trace[912239463] 'read index received' (duration: 634.191637ms)","trace[912239463] 'applied index is now lower than readState.Index' (duration: 227.385µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:49:51.662103Z","caller":"traceutil/trace.go:171","msg":"trace[2074529239] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"645.501733ms","start":"2026-01-31T18:49:51.016592Z","end":"2026-01-31T18:49:51.662094Z","steps":["trace[2074529239] 'process raft request' (duration: 645.240677ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:51.662212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:51.016583Z","time spent":"645.545694ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:49:51.662385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.785641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-31T18:49:51.662447Z","caller":"traceutil/trace.go:171","msg":"trace[1786020553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"634.860452ms","start":"2026-01-31T18:49:51.027569Z","end":"2026-01-31T18:49:51.66243Z","steps":["trace[1786020553] 'agreement among raft nodes before linearized reading' (duration: 634.650578ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:51.662483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:51.027567Z","time spent":"634.908493ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T18:49:51.662609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.049856ms","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-01-31T18:49:51.662632Z","caller":"traceutil/trace.go:171","msg":"trace[709778531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"635.080307ms","start":"2026-01-31T18:49:51.027544Z","end":"2026-01-31T18:49:51.662624Z","steps":["trace[709778531] 'agreement among raft nodes before linearized reading' (duration: 635.013956ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:51.662656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:51.027539Z","time spent":"635.111808ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T18:49:51.662778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.863902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-31T18:49:51.662818Z","caller":"traceutil/trace.go:171","msg":"trace[66282067] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:578; }","duration":"575.932804ms","start":"2026-01-31T18:49:51.086876Z","end":"2026-01-31T18:49:51.662808Z","steps":["trace[66282067] 'agreement among raft nodes before linearized reading' (duration: 575.808562ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:51.66282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.473391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-01-31T18:49:51.662852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:51.086861Z","time spent":"575.981515ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-31T18:49:51.662849Z","caller":"traceutil/trace.go:171","msg":"trace[11051083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"621.511922ms","start":"2026-01-31T18:49:51.041329Z","end":"2026-01-31T18:49:51.662841Z","steps":["trace[11051083] 'agreement among raft nodes before linearized reading' (duration: 621.43309ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:51.662902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:51.041324Z","time spent":"621.570144ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-31T18:49:51.885315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.953466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T18:49:51.885865Z","caller":"traceutil/trace.go:171","msg":"trace[1355860899] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"215.245078ms","start":"2026-01-31T18:49:51.670603Z","end":"2026-01-31T18:49:51.885848Z","steps":["trace[1355860899] 'process raft request' (duration: 215.146115ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:51.886156Z","caller":"traceutil/trace.go:171","msg":"trace[1320307338] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"218.123232ms","start":"2026-01-31T18:49:51.668018Z","end":"2026-01-31T18:49:51.886141Z","steps":["trace[1320307338] 'process raft request' (duration: 53.282896ms)","trace[1320307338] 'compare' (duration: 163.839003ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:49:51.886521Z","caller":"traceutil/trace.go:171","msg":"trace[2095590724] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"216.408915ms","start":"2026-01-31T18:49:51.670098Z","end":"2026-01-31T18:49:51.886507Z","steps":["trace[2095590724] 'process raft request' (duration: 215.595596ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:53.996015Z","caller":"traceutil/trace.go:171","msg":"trace[1904681407] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"293.267002ms","start":"2026-01-31T18:49:53.702723Z","end":"2026-01-31T18:49:53.99599Z","steps":["trace[1904681407] 'read index received' (duration: 293.026987ms)","trace[1904681407] 'applied index is now lower than readState.Index' (duration: 239.105µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:49:53.996389Z","caller":"traceutil/trace.go:171","msg":"trace[809477046] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"324.586581ms","start":"2026-01-31T18:49:53.671791Z","end":"2026-01-31T18:49:53.996378Z","steps":["trace[809477046] 'process raft request' (duration: 324.04152ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:53.996509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:53.671782Z","time spent":"324.637082ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:49:54.361599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.861822ms","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-01-31T18:49:54.361656Z","caller":"traceutil/trace.go:171","msg":"trace[1577195382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"658.935613ms","start":"2026-01-31T18:49:53.702708Z","end":"2026-01-31T18:49:54.361644Z","steps":["trace[1577195382] 'agreement among raft nodes before linearized reading' (duration: 294.59314ms)","trace[1577195382] 'range keys from in-memory index tree' (duration: 364.203731ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:49:54.361678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:53.702701Z","time spent":"658.972644ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T18:49:54.361789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.961406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-31T18:49:54.361802Z","caller":"traceutil/trace.go:171","msg":"trace[1417596542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"470.976327ms","start":"2026-01-31T18:49:53.890821Z","end":"2026-01-31T18:49:54.361797Z","steps":["trace[1417596542] 'agreement among raft nodes before linearized reading' (duration: 106.503681ms)","trace[1417596542] 'range keys from in-memory index tree' (duration: 364.435835ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:49:54.361814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:53.890764Z","time spent":"471.047238ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-31T18:49:54.36188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.604236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-31T18:49:54.361892Z","caller":"traceutil/trace.go:171","msg":"trace[2081223291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:583; }","duration":"469.638067ms","start":"2026-01-31T18:49:53.89225Z","end":"2026-01-31T18:49:54.361888Z","steps":["trace[2081223291] 'agreement among raft nodes before linearized reading' (duration: 105.235832ms)","trace[2081223291] 'range keys from in-memory index tree' (duration: 364.367714ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:49:54.361903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:53.892246Z","time spent":"469.654737ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-31T18:49:54.361976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.382013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-31T18:49:54.361991Z","caller":"traceutil/trace.go:171","msg":"trace[1154358438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"470.407814ms","start":"2026-01-31T18:49:53.891579Z","end":"2026-01-31T18:49:54.361987Z","steps":["trace[1154358438] 'agreement among raft nodes before linearized reading' (duration: 105.915227ms)","trace[1154358438] 'range keys from in-memory index tree' (duration: 364.444216ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:49:54.362003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:49:53.891575Z","time spent":"470.425054ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T18:49:55.189517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.59537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-01-31T18:49:55.189558Z","caller":"traceutil/trace.go:171","msg":"trace[2069614095] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:590; }","duration":"216.666751ms","start":"2026-01-31T18:49:54.972882Z","end":"2026-01-31T18:49:55.189549Z","steps":["trace[2069614095] 'range keys from in-memory index tree' (duration: 216.538078ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:55.189965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.700953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mzwwg\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-01-31T18:49:55.190012Z","caller":"traceutil/trace.go:171","msg":"trace[1117148535] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mzwwg; range_end:; response_count:1; response_revision:590; }","duration":"182.794875ms","start":"2026-01-31T18:49:55.007205Z","end":"2026-01-31T18:49:55.189999Z","steps":["trace[1117148535] 'range keys from in-memory index tree' (duration: 182.573021ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:08.373052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237121196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-01-31T18:50:08.373111Z","caller":"traceutil/trace.go:171","msg":"trace[1091603618] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:675; }","duration":"1.237196688s","start":"2026-01-31T18:50:07.1359Z","end":"2026-01-31T18:50:08.373097Z","steps":["trace[1091603618] 'range keys from in-memory index tree' (duration: 1.237055815s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:08.373138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:07.135897Z","time spent":"1.237234868s","remote":"127.0.0.1:49584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":201,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-01-31T18:50:08.373289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23727257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"warn","ts":"2026-01-31T18:50:08.37328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.003496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-31T18:50:08.373309Z","caller":"traceutil/trace.go:171","msg":"trace[248992269] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:675; }","duration":"1.23730003s","start":"2026-01-31T18:50:07.136004Z","end":"2026-01-31T18:50:08.373304Z","steps":["trace[248992269] 'range keys from in-memory index tree' (duration: 1.237245968s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:08.373329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:07.135999Z","time spent":"1.237326341s","remote":"127.0.0.1:49584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":201,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"info","ts":"2026-01-31T18:50:08.373332Z","caller":"traceutil/trace.go:171","msg":"trace[374365813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:675; }","duration":"305.090828ms","start":"2026-01-31T18:50:08.068229Z","end":"2026-01-31T18:50:08.37332Z","steps":["trace[374365813] 'range keys from in-memory index tree' (duration: 304.887014ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:08.373361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:08.06821Z","time spent":"305.145329ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-31T18:50:08.373415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237371191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-01-31T18:50:08.373435Z","caller":"traceutil/trace.go:171","msg":"trace[1352541731] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:675; }","duration":"1.237558566s","start":"2026-01-31T18:50:07.13587Z","end":"2026-01-31T18:50:08.373429Z","steps":["trace[1352541731] 'range keys from in-memory index tree' (duration: 1.237343551s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:08.373454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:07.135857Z","time spent":"1.237592556s","remote":"127.0.0.1:49584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":201,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-01-31T18:50:08.373674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.664882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:08.373693Z","caller":"traceutil/trace.go:171","msg":"trace[2122054898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"926.701982ms","start":"2026-01-31T18:50:07.446985Z","end":"2026-01-31T18:50:08.373687Z","steps":["trace[2122054898] 'range keys from in-memory index tree' (duration: 926.623331ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:08.373711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:07.44697Z","time spent":"926.736133ms","remote":"127.0.0.1:49388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-31T18:50:08.570214Z","caller":"traceutil/trace.go:171","msg":"trace[430183138] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"192.222039ms","start":"2026-01-31T18:50:08.377973Z","end":"2026-01-31T18:50:08.570195Z","steps":["trace[430183138] 'process raft request' (duration: 192.058315ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:08.570491Z","caller":"traceutil/trace.go:171","msg":"trace[1936486366] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:700; }","duration":"192.341702ms","start":"2026-01-31T18:50:08.378139Z","end":"2026-01-31T18:50:08.570481Z","steps":["trace[1936486366] 'read index received' (duration: 192.337162ms)","trace[1936486366] 'applied index is now lower than readState.Index' (duration: 3.76µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:50:08.570664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.982287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-31T18:50:08.570703Z","caller":"traceutil/trace.go:171","msg":"trace[989867439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:676; }","duration":"129.05733ms","start":"2026-01-31T18:50:08.441637Z","end":"2026-01-31T18:50:08.570694Z","steps":["trace[989867439] 'agreement among raft nodes before linearized reading' (duration: 128.918817ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:08.571025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.878393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-4cn47\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-01-31T18:50:08.571068Z","caller":"traceutil/trace.go:171","msg":"trace[1676918967] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-4cn47; range_end:; response_count:1; response_revision:676; }","duration":"192.933345ms","start":"2026-01-31T18:50:08.378126Z","end":"2026-01-31T18:50:08.571059Z","steps":["trace[1676918967] 'agreement among raft nodes before linearized reading' (duration: 192.808862ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:08.571366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.573086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T18:50:08.571404Z","caller":"traceutil/trace.go:171","msg":"trace[1451569655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:676; }","duration":"102.626087ms","start":"2026-01-31T18:50:08.46877Z","end":"2026-01-31T18:50:08.571396Z","steps":["trace[1451569655] 'agreement among raft nodes before linearized reading' (duration: 102.544155ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:08.571554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.593567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T18:50:08.571588Z","caller":"traceutil/trace.go:171","msg":"trace[1676413909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:676; }","duration":"109.650988ms","start":"2026-01-31T18:50:08.46193Z","end":"2026-01-31T18:50:08.571581Z","steps":["trace[1676413909] 'agreement among raft nodes before linearized reading' (duration: 109.572037ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:08.571709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.532469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:08.571741Z","caller":"traceutil/trace.go:171","msg":"trace[1375326602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"123.578531ms","start":"2026-01-31T18:50:08.448155Z","end":"2026-01-31T18:50:08.571733Z","steps":["trace[1375326602] 'agreement among raft nodes before linearized reading' (duration: 123.53324ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:18.95365Z","caller":"traceutil/trace.go:171","msg":"trace[960997297] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"199.888557ms","start":"2026-01-31T18:50:18.753723Z","end":"2026-01-31T18:50:18.953611Z","steps":["trace[960997297] 'process raft request' (duration: 199.813475ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:18.954061Z","caller":"traceutil/trace.go:171","msg":"trace[451384058] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"267.848801ms","start":"2026-01-31T18:50:18.686199Z","end":"2026-01-31T18:50:18.954048Z","steps":["trace[451384058] 'process raft request' (duration: 195.554953ms)","trace[451384058] 'compare' (duration: 71.458671ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:50:18.954252Z","caller":"traceutil/trace.go:171","msg":"trace[1658960691] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"267.141005ms","start":"2026-01-31T18:50:18.687101Z","end":"2026-01-31T18:50:18.954242Z","steps":["trace[1658960691] 'process raft request' (duration: 266.246635ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:19.270676Z","caller":"traceutil/trace.go:171","msg":"trace[1642807510] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"141.629092ms","start":"2026-01-31T18:50:19.129026Z","end":"2026-01-31T18:50:19.270655Z","steps":["trace[1642807510] 'process raft request' (duration: 141.501669ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:19.827575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.100381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:19.827676Z","caller":"traceutil/trace.go:171","msg":"trace[1954766399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"378.256894ms","start":"2026-01-31T18:50:19.449395Z","end":"2026-01-31T18:50:19.827652Z","steps":["trace[1954766399] 'range keys from in-memory index tree' (duration: 378.033789ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:19.827729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:19.449388Z","time spent":"378.332116ms","remote":"127.0.0.1:49388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-31T18:50:31.174685Z","caller":"traceutil/trace.go:171","msg":"trace[1932981462] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"374.91542ms","start":"2026-01-31T18:50:30.79975Z","end":"2026-01-31T18:50:31.174665Z","steps":["trace[1932981462] 'process raft request' (duration: 374.792197ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:31.175822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:30.799737Z","time spent":"375.437841ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:50:31.265295Z","caller":"traceutil/trace.go:171","msg":"trace[1085723403] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"257.788353ms","start":"2026-01-31T18:50:31.007487Z","end":"2026-01-31T18:50:31.265276Z","steps":["trace[1085723403] 'read index received' (duration: 167.669999ms)","trace[1085723403] 'applied index is now lower than readState.Index' (duration: 90.117104ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:50:31.265624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.11643ms","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-01-31T18:50:31.265694Z","caller":"traceutil/trace.go:171","msg":"trace[1869821251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"258.229212ms","start":"2026-01-31T18:50:31.007456Z","end":"2026-01-31T18:50:31.265685Z","steps":["trace[1869821251] 'agreement among raft nodes before linearized reading' (duration: 258.059908ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:31.266109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.341874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T18:50:31.266168Z","caller":"traceutil/trace.go:171","msg":"trace[1119948122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"258.427347ms","start":"2026-01-31T18:50:31.007733Z","end":"2026-01-31T18:50:31.266161Z","steps":["trace[1119948122] 'agreement among raft nodes before linearized reading' (duration: 258.199232ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:31.266446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.80502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-31T18:50:31.266667Z","caller":"traceutil/trace.go:171","msg":"trace[806766847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"255.034484ms","start":"2026-01-31T18:50:31.011622Z","end":"2026-01-31T18:50:31.266657Z","steps":["trace[806766847] 'agreement among raft nodes before linearized reading' (duration: 254.778449ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:33.523322Z","caller":"traceutil/trace.go:171","msg":"trace[1657422443] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"148.274308ms","start":"2026-01-31T18:50:33.375027Z","end":"2026-01-31T18:50:33.523301Z","steps":["trace[1657422443] 'process raft request' (duration: 144.796373ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:45.99369Z","caller":"traceutil/trace.go:171","msg":"trace[426223354] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"352.493693ms","start":"2026-01-31T18:50:45.641177Z","end":"2026-01-31T18:50:45.993671Z","steps":["trace[426223354] 'process raft request' (duration: 352.147266ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:45.993791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:45.641161Z","time spent":"352.573805ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:50:46.212011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.486995ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-01-31T18:50:46.605666Z","caller":"traceutil/trace.go:171","msg":"trace[712117312] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"158.445984ms","start":"2026-01-31T18:50:46.447197Z","end":"2026-01-31T18:50:46.605643Z","steps":["trace[712117312] 'read index received' (duration: 99.494493ms)","trace[712117312] 'applied index is now lower than readState.Index' (duration: 58.950531ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:50:46.605837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.620508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:46.605869Z","caller":"traceutil/trace.go:171","msg":"trace[288812380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:881; }","duration":"158.69353ms","start":"2026-01-31T18:50:46.447165Z","end":"2026-01-31T18:50:46.605858Z","steps":["trace[288812380] 'agreement among raft nodes before linearized reading' (duration: 158.562827ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:46.606165Z","caller":"traceutil/trace.go:171","msg":"trace[2113222366] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"393.059134ms","start":"2026-01-31T18:50:46.213093Z","end":"2026-01-31T18:50:46.606152Z","steps":["trace[2113222366] 'process raft request' (duration: 333.591331ms)","trace[2113222366] 'compare' (duration: 58.82837ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:50:46.606243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:46.213079Z","time spent":"393.111825ms","remote":"127.0.0.1:49426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:50:46.620087Z","caller":"traceutil/trace.go:171","msg":"trace[213908996] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"142.342332ms","start":"2026-01-31T18:50:46.477728Z","end":"2026-01-31T18:50:46.62007Z","steps":["trace[213908996] 'process raft request' (duration: 142.2171ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:57.608501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.169083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:57.608551Z","caller":"traceutil/trace.go:171","msg":"trace[827129605] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:911; }","duration":"310.254426ms","start":"2026-01-31T18:50:57.298286Z","end":"2026-01-31T18:50:57.608541Z","steps":["trace[827129605] 'count revisions from in-memory index tree' (duration: 310.099392ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:57.60857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:57.298257Z","time spent":"310.307436ms","remote":"127.0.0.1:49692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-31T18:50:57.608645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.436907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:57.608662Z","caller":"traceutil/trace.go:171","msg":"trace[329081418] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:911; }","duration":"275.462167ms","start":"2026-01-31T18:50:57.333191Z","end":"2026-01-31T18:50:57.608654Z","steps":["trace[329081418] 'count revisions from in-memory index tree' (duration: 275.405556ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:57.608763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.07867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T18:50:57.608776Z","caller":"traceutil/trace.go:171","msg":"trace[268032753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:911; }","duration":"268.102731ms","start":"2026-01-31T18:50:57.340669Z","end":"2026-01-31T18:50:57.608772Z","steps":["trace[268032753] 'range keys from in-memory index tree' (duration: 268.007599ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:57.608837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.529191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:57.608848Z","caller":"traceutil/trace.go:171","msg":"trace[1998675675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"162.590202ms","start":"2026-01-31T18:50:57.446254Z","end":"2026-01-31T18:50:57.608844Z","steps":["trace[1998675675] 'range keys from in-memory index tree' (duration: 162.463689ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:57.608927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.63745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T18:50:57.608939Z","caller":"traceutil/trace.go:171","msg":"trace[1644952653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:911; }","duration":"136.66636ms","start":"2026-01-31T18:50:57.472269Z","end":"2026-01-31T18:50:57.608935Z","steps":["trace[1644952653] 'range keys from in-memory index tree' (duration: 136.568048ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:58.349126Z","caller":"traceutil/trace.go:171","msg":"trace[397691100] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"318.188586ms","start":"2026-01-31T18:50:58.030922Z","end":"2026-01-31T18:50:58.349111Z","steps":["trace[397691100] 'process raft request' (duration: 317.89646ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:58.349213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:58.030914Z","time spent":"318.250437ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:50:59.775934Z","caller":"traceutil/trace.go:171","msg":"trace[2033025257] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"153.768484ms","start":"2026-01-31T18:50:59.622149Z","end":"2026-01-31T18:50:59.775918Z","steps":["trace[2033025257] 'process raft request' (duration: 153.670322ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:59.826701Z","caller":"traceutil/trace.go:171","msg":"trace[2000751537] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"201.330974ms","start":"2026-01-31T18:50:59.625353Z","end":"2026-01-31T18:50:59.826684Z","steps":["trace[2000751537] 'process raft request' (duration: 201.304593ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:59.826859Z","caller":"traceutil/trace.go:171","msg":"trace[310595347] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"202.759924ms","start":"2026-01-31T18:50:59.624092Z","end":"2026-01-31T18:50:59.826852Z","steps":["trace[310595347] 'process raft request' (duration: 202.468408ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:00.698294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.886555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-31T18:51:00.698364Z","caller":"traceutil/trace.go:171","msg":"trace[1203446792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:920; }","duration":"346.986186ms","start":"2026-01-31T18:51:00.351366Z","end":"2026-01-31T18:51:00.698353Z","steps":["trace[1203446792] 'range keys from in-memory index tree' (duration: 346.783832ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:00.698385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:00.351352Z","time spent":"347.028237ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T18:51:00.698492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.673402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:51:00.698507Z","caller":"traceutil/trace.go:171","msg":"trace[22944355] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:920; }","duration":"259.712882ms","start":"2026-01-31T18:51:00.438789Z","end":"2026-01-31T18:51:00.698502Z","steps":["trace[22944355] 'count revisions from in-memory index tree' (duration: 259.630571ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:00.698573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.711053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:51:00.698584Z","caller":"traceutil/trace.go:171","msg":"trace[959006807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"251.731513ms","start":"2026-01-31T18:51:00.446849Z","end":"2026-01-31T18:51:00.69858Z","steps":["trace[959006807] 'range keys from in-memory index tree' (duration: 251.654732ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:01.124963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.021944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T18:51:01.125017Z","caller":"traceutil/trace.go:171","msg":"trace[791789100] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:921; }","duration":"190.107805ms","start":"2026-01-31T18:51:00.934896Z","end":"2026-01-31T18:51:01.125003Z","steps":["trace[791789100] 'count revisions from in-memory index tree' (duration: 189.942172ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:05.099338Z","caller":"traceutil/trace.go:171","msg":"trace[461958566] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"214.46757ms","start":"2026-01-31T18:51:04.884851Z","end":"2026-01-31T18:51:05.099319Z","steps":["trace[461958566] 'process raft request' (duration: 214.334978ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:11.465008Z","caller":"traceutil/trace.go:171","msg":"trace[1515241407] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"333.442559ms","start":"2026-01-31T18:51:11.131552Z","end":"2026-01-31T18:51:11.464995Z","steps":["trace[1515241407] 'process raft request' (duration: 333.352837ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:11.465116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:11.131537Z","time spent":"333.51561ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:51:11.465559Z","caller":"traceutil/trace.go:171","msg":"trace[744143571] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1015; }","duration":"287.951153ms","start":"2026-01-31T18:51:11.177602Z","end":"2026-01-31T18:51:11.465553Z","steps":["trace[744143571] 'read index received' (duration: 287.948853ms)","trace[744143571] 'applied index is now lower than readState.Index' (duration: 1.99µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:11.465638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.034875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-31T18:51:11.465653Z","caller":"traceutil/trace.go:171","msg":"trace[1424403813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:976; }","duration":"288.072236ms","start":"2026-01-31T18:51:11.177576Z","end":"2026-01-31T18:51:11.465648Z","steps":["trace[1424403813] 'agreement among raft nodes before linearized reading' (duration: 288.007995ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:11.75401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.715202ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-31T18:51:11.754269Z","caller":"traceutil/trace.go:171","msg":"trace[1735165852] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"288.676398ms","start":"2026-01-31T18:51:11.465572Z","end":"2026-01-31T18:51:11.754249Z","steps":["trace[1735165852] 'read index received' (duration: 117.816811ms)","trace[1735165852] 'applied index is now lower than readState.Index' (duration: 170.858007ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:11.754419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.114137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:51:11.754449Z","caller":"traceutil/trace.go:171","msg":"trace[481046989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"305.179819ms","start":"2026-01-31T18:51:11.449259Z","end":"2026-01-31T18:51:11.754439Z","steps":["trace[481046989] 'agreement among raft nodes before linearized reading' (duration: 305.114618ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:11.754484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:11.449244Z","time spent":"305.23241ms","remote":"127.0.0.1:49372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-31T18:51:11.756135Z","caller":"traceutil/trace.go:171","msg":"trace[209928556] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"273.64169ms","start":"2026-01-31T18:51:11.482483Z","end":"2026-01-31T18:51:11.756125Z","steps":["trace[209928556] 'process raft request' (duration: 271.702629ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:12.010464Z","caller":"traceutil/trace.go:171","msg":"trace[365044837] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"139.255966ms","start":"2026-01-31T18:51:11.871174Z","end":"2026-01-31T18:51:12.01043Z","steps":["trace[365044837] 'read index received' (duration: 138.622623ms)","trace[365044837] 'applied index is now lower than readState.Index' (duration: 632.073µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:51:12.01061Z","caller":"traceutil/trace.go:171","msg":"trace[1758497916] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"190.691128ms","start":"2026-01-31T18:51:11.819903Z","end":"2026-01-31T18:51:12.010594Z","steps":["trace[1758497916] 'process raft request' (duration: 189.964753ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:12.010804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.188099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-31T18:51:12.010863Z","caller":"traceutil/trace.go:171","msg":"trace[1478473161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:978; }","duration":"111.274651ms","start":"2026-01-31T18:51:11.89957Z","end":"2026-01-31T18:51:12.010844Z","steps":["trace[1478473161] 'agreement among raft nodes before linearized reading' (duration: 111.094108ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:12.011106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.919411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T18:51:12.011157Z","caller":"traceutil/trace.go:171","msg":"trace[495928074] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:978; }","duration":"139.999332ms","start":"2026-01-31T18:51:11.871143Z","end":"2026-01-31T18:51:12.011142Z","steps":["trace[495928074] 'agreement among raft nodes before linearized reading' (duration: 139.864929ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:12.584558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.287017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:51:12.584646Z","caller":"traceutil/trace.go:171","msg":"trace[1491461057] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:980; }","duration":"384.410961ms","start":"2026-01-31T18:51:12.200219Z","end":"2026-01-31T18:51:12.58463Z","steps":["trace[1491461057] 'count revisions from in-memory index tree' (duration: 384.234956ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:12.584686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:12.200204Z","time spent":"384.470691ms","remote":"127.0.0.1:49534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-01-31T18:51:17.488682Z","caller":"traceutil/trace.go:171","msg":"trace[986719917] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"213.050243ms","start":"2026-01-31T18:51:17.275613Z","end":"2026-01-31T18:51:17.488663Z","steps":["trace[986719917] 'process raft request' (duration: 212.587274ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:17.779425Z","caller":"traceutil/trace.go:171","msg":"trace[219422060] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"284.627833ms","start":"2026-01-31T18:51:17.49478Z","end":"2026-01-31T18:51:17.779408Z","steps":["trace[219422060] 'process raft request' (duration: 269.330357ms)","trace[219422060] 'compare' (duration: 14.759794ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:51:18.243618Z","caller":"traceutil/trace.go:171","msg":"trace[624077394] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"139.830718ms","start":"2026-01-31T18:51:18.103774Z","end":"2026-01-31T18:51:18.243605Z","steps":["trace[624077394] 'read index received' (duration: 139.713856ms)","trace[624077394] 'applied index is now lower than readState.Index' (duration: 116.262µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:51:18.243773Z","caller":"traceutil/trace.go:171","msg":"trace[160381272] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"145.192502ms","start":"2026-01-31T18:51:18.098571Z","end":"2026-01-31T18:51:18.243763Z","steps":["trace[160381272] 'process raft request' (duration: 144.953027ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:18.243934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.147486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-01-31T18:51:18.24396Z","caller":"traceutil/trace.go:171","msg":"trace[1418866698] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1012; }","duration":"140.199256ms","start":"2026-01-31T18:51:18.103754Z","end":"2026-01-31T18:51:18.243953Z","steps":["trace[1418866698] 'agreement among raft nodes before linearized reading' (duration: 140.119224ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:54:27.003594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.218542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-01-31T18:54:27.003653Z","caller":"traceutil/trace.go:171","msg":"trace[1692765003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1826; }","duration":"109.338255ms","start":"2026-01-31T18:54:26.894302Z","end":"2026-01-31T18:54:27.003641Z","steps":["trace[1692765003] 'range keys from in-memory index tree' (duration: 109.060249ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:54:27.00389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.478058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T18:54:27.003953Z","caller":"traceutil/trace.go:171","msg":"trace[1477322914] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1826; }","duration":"109.58439ms","start":"2026-01-31T18:54:26.894352Z","end":"2026-01-31T18:54:27.003937Z","steps":["trace[1477322914] 'count revisions from in-memory index tree' (duration: 109.435406ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:54:30.023739Z","caller":"traceutil/trace.go:171","msg":"trace[99148392] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"187.108017ms","start":"2026-01-31T18:54:29.836607Z","end":"2026-01-31T18:54:30.023715Z","steps":["trace[99148392] 'process raft request' (duration: 186.988754ms)"],"step_count":1}