{"level":"warn","ts":"2026-02-07T02:24:02.502374Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-07T02:24:02.502641Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-07T02:24:02.502786Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-07T02:24:02.502817Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-02-07T02:24:02.502877Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-07T02:24:02.503532Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-02-07T02:24:02.503657Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-07T02:24:02.511216Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.280428ms"} {"level":"info","ts":"2026-02-07T02:24:02.560541Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-02-07T02:24:02.560674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T02:24:02.560717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-02-07T02:24:02.560764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-07T02:24:02.560816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-02-07T02:24:02.560898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-02-07T02:24:02.570128Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T02:24:02.574661Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T02:24:02.57648Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-07T02:24:02.579935Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-07T02:24:02.583143Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-07T02:24:02.592462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-02-07T02:24:02.59228Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-07T02:24:02.599213Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-07T02:24:02.599238Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-07T02:24:02.5926Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-02-07T02:24:02.604708Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-07T02:24:02.605784Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-07T02:24:02.606342Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-02-07T02:24:02.60639Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-02-07T02:24:02.605809Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T02:24:03.062323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T02:24:03.062418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T02:24:03.062559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-02-07T02:24:03.062578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-02-07T02:24:03.06262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-02-07T02:24:03.062635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-02-07T02:24:03.062654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-02-07T02:24:03.065601Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-02-07T02:24:03.0712Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:24:03.071338Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T02:24:03.071588Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T02:24:03.072929Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-07T02:24:03.073056Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:24:03.073138Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:24:03.073153Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:24:03.087387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-02-07T02:24:03.115129Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T02:24:03.11515Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T02:24:25.269792Z","caller":"traceutil/trace.go:171","msg":"trace[1796318575] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"183.998803ms","start":"2026-02-07T02:24:25.085779Z","end":"2026-02-07T02:24:25.269778Z","steps":["trace[1796318575] 'process raft request' (duration: 183.870105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:25.49157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.068458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:24:25.491693Z","caller":"traceutil/trace.go:171","msg":"trace[1061163939] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"216.288415ms","start":"2026-02-07T02:24:25.275392Z","end":"2026-02-07T02:24:25.49168Z","steps":["trace[1061163939] 'process raft request' (duration: 216.250032ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:25.4918Z","caller":"traceutil/trace.go:171","msg":"trace[2094708379] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"218.037747ms","start":"2026-02-07T02:24:25.273751Z","end":"2026-02-07T02:24:25.491789Z","steps":["trace[2094708379] 'process raft request' (duration: 57.328491ms)","trace[2094708379] 'compare' (duration: 159.833425ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:31.700759Z","caller":"traceutil/trace.go:171","msg":"trace[454721915] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"392.900591ms","start":"2026-02-07T02:24:31.30784Z","end":"2026-02-07T02:24:31.700741Z","steps":["trace[454721915] 'process raft request' (duration: 392.788825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:31.700873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:31.307831Z","time spent":"392.983787ms","remote":"127.0.0.1:33848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:24:31.701216Z","caller":"traceutil/trace.go:171","msg":"trace[1551077708] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:479; }","duration":"223.33003ms","start":"2026-02-07T02:24:31.477878Z","end":"2026-02-07T02:24:31.701208Z","steps":["trace[1551077708] 'read index received' (duration: 223.32666ms)","trace[1551077708] 'applied index is now lower than readState.Index' (duration: 2.76µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:24:31.701279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.395974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-07T02:24:31.701299Z","caller":"traceutil/trace.go:171","msg":"trace[910416429] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:467; }","duration":"223.449887ms","start":"2026-02-07T02:24:31.477842Z","end":"2026-02-07T02:24:31.701292Z","steps":["trace[910416429] 'agreement among raft nodes before linearized reading' (duration: 223.409485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:31.707333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.310323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T02:24:31.707415Z","caller":"traceutil/trace.go:171","msg":"trace[2126339642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"195.43826ms","start":"2026-02-07T02:24:31.511955Z","end":"2026-02-07T02:24:31.707394Z","steps":["trace[2126339642] 'agreement among raft nodes before linearized reading' (duration: 195.26871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:31.707663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.908037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-07T02:24:31.7077Z","caller":"traceutil/trace.go:171","msg":"trace[799316611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:467; }","duration":"185.960991ms","start":"2026-02-07T02:24:31.52173Z","end":"2026-02-07T02:24:31.707691Z","steps":["trace[799316611] 'agreement among raft nodes before linearized reading' (duration: 185.842664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:31.707701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.283821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T02:24:31.707733Z","caller":"traceutil/trace.go:171","msg":"trace[546793813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"195.332265ms","start":"2026-02-07T02:24:31.51239Z","end":"2026-02-07T02:24:31.707723Z","steps":["trace[546793813] 'agreement among raft nodes before linearized reading' (duration: 195.25327ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:35.854105Z","caller":"traceutil/trace.go:171","msg":"trace[1221760591] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"127.564569ms","start":"2026-02-07T02:24:35.726524Z","end":"2026-02-07T02:24:35.854088Z","steps":["trace[1221760591] 'read index received' (duration: 127.424321ms)","trace[1221760591] 'applied index is now lower than readState.Index' (duration: 139.338µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:24:35.855558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.758505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T02:24:35.85559Z","caller":"traceutil/trace.go:171","msg":"trace[540653437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:499; }","duration":"119.824748ms","start":"2026-02-07T02:24:35.735756Z","end":"2026-02-07T02:24:35.855581Z","steps":["trace[540653437] 'agreement among raft nodes before linearized reading' (duration: 119.743843ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:35.855672Z","caller":"traceutil/trace.go:171","msg":"trace[490031968] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"142.212422ms","start":"2026-02-07T02:24:35.713452Z","end":"2026-02-07T02:24:35.855664Z","steps":["trace[490031968] 'process raft request' (duration: 140.537762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:35.855728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.201847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-07T02:24:35.855743Z","caller":"traceutil/trace.go:171","msg":"trace[166469522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:499; }","duration":"129.229528ms","start":"2026-02-07T02:24:35.726509Z","end":"2026-02-07T02:24:35.855739Z","steps":["trace[166469522] 'agreement among raft nodes before linearized reading' (duration: 129.201407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:35.8558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.01183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T02:24:35.855814Z","caller":"traceutil/trace.go:171","msg":"trace[309217727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:499; }","duration":"120.033301ms","start":"2026-02-07T02:24:35.735777Z","end":"2026-02-07T02:24:35.85581Z","steps":["trace[309217727] 'agreement among raft nodes before linearized reading' (duration: 120.000029ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:39.327421Z","caller":"traceutil/trace.go:171","msg":"trace[1066379613] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"144.488477ms","start":"2026-02-07T02:24:39.182914Z","end":"2026-02-07T02:24:39.327403Z","steps":["trace[1066379613] 'read index received' (duration: 144.340148ms)","trace[1066379613] 'applied index is now lower than readState.Index' (duration: 147.639µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:24:39.327528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.601364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8pv7b\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-07T02:24:39.327549Z","caller":"traceutil/trace.go:171","msg":"trace[920588610] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8pv7b; range_end:; response_count:1; response_revision:511; }","duration":"144.662487ms","start":"2026-02-07T02:24:39.18288Z","end":"2026-02-07T02:24:39.327542Z","steps":["trace[920588610] 'agreement among raft nodes before linearized reading' (duration: 144.588653ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:39.327693Z","caller":"traceutil/trace.go:171","msg":"trace[508728990] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"319.977853ms","start":"2026-02-07T02:24:39.007696Z","end":"2026-02-07T02:24:39.327674Z","steps":["trace[508728990] 'process raft request' (duration: 319.59519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:39.327783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:39.00767Z","time spent":"320.064169ms","remote":"127.0.0.1:33848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:24:40.120833Z","caller":"traceutil/trace.go:171","msg":"trace[78762022] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"254.114234ms","start":"2026-02-07T02:24:39.866701Z","end":"2026-02-07T02:24:40.120815Z","steps":["trace[78762022] 'read index received' (duration: 253.936864ms)","trace[78762022] 'applied index is now lower than readState.Index' (duration: 176.73µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:40.121077Z","caller":"traceutil/trace.go:171","msg":"trace[1194900967] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"774.671609ms","start":"2026-02-07T02:24:39.346398Z","end":"2026-02-07T02:24:40.12107Z","steps":["trace[1194900967] 'process raft request' (duration: 774.297926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:40.121138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:39.346381Z","time spent":"774.713851ms","remote":"127.0.0.1:33758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:24:40.121241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.53971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-07T02:24:40.121257Z","caller":"traceutil/trace.go:171","msg":"trace[356832611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"254.577821ms","start":"2026-02-07T02:24:39.866674Z","end":"2026-02-07T02:24:40.121252Z","steps":["trace[356832611] 'agreement among raft nodes before linearized reading' (duration: 254.54778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:40.121343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.806096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-07T02:24:40.121356Z","caller":"traceutil/trace.go:171","msg":"trace[505151957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"244.847738ms","start":"2026-02-07T02:24:39.876504Z","end":"2026-02-07T02:24:40.121352Z","steps":["trace[505151957] 'agreement among raft nodes before linearized reading' (duration: 244.823977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:40.121531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.884432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T02:24:40.121544Z","caller":"traceutil/trace.go:171","msg":"trace[1914076403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"143.910994ms","start":"2026-02-07T02:24:39.977629Z","end":"2026-02-07T02:24:40.12154Z","steps":["trace[1914076403] 'agreement among raft nodes before linearized reading' (duration: 143.84514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:40.121619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.201541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T02:24:40.121635Z","caller":"traceutil/trace.go:171","msg":"trace[254388885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"146.237553ms","start":"2026-02-07T02:24:39.975392Z","end":"2026-02-07T02:24:40.12163Z","steps":["trace[254388885] 'agreement among raft nodes before linearized reading' (duration: 146.200271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:41.832227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.526074678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:24:41.832275Z","caller":"traceutil/trace.go:171","msg":"trace[1832778279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"1.526127812s","start":"2026-02-07T02:24:40.306135Z","end":"2026-02-07T02:24:41.832262Z","steps":["trace[1832778279] 'range keys from in-memory index tree' (duration: 1.526016995s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:41.832297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:40.306095Z","time spent":"1.526198116s","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T02:24:43.802145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.127351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-07T02:24:43.802202Z","caller":"traceutil/trace.go:171","msg":"trace[590390360] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:530; }","duration":"242.225607ms","start":"2026-02-07T02:24:43.559963Z","end":"2026-02-07T02:24:43.802189Z","steps":["trace[590390360] 'range keys from in-memory index tree' (duration: 242.041686ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:45.431558Z","caller":"traceutil/trace.go:171","msg":"trace[870002793] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"159.885815ms","start":"2026-02-07T02:24:45.271634Z","end":"2026-02-07T02:24:45.43152Z","steps":["trace[870002793] 'process raft request' (duration: 159.8141ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:45.431684Z","caller":"traceutil/trace.go:171","msg":"trace[1920469717] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"181.007913ms","start":"2026-02-07T02:24:45.250647Z","end":"2026-02-07T02:24:45.431655Z","steps":["trace[1920469717] 'process raft request' (duration: 145.618711ms)","trace[1920469717] 'compare' (duration: 35.02476ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:45.604068Z","caller":"traceutil/trace.go:171","msg":"trace[974447155] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"154.387773ms","start":"2026-02-07T02:24:45.449664Z","end":"2026-02-07T02:24:45.604052Z","steps":["trace[974447155] 'process raft request' (duration: 83.571388ms)","trace[974447155] 'compare' (duration: 70.751811ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:45.609386Z","caller":"traceutil/trace.go:171","msg":"trace[1304591408] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"157.962099ms","start":"2026-02-07T02:24:45.451396Z","end":"2026-02-07T02:24:45.609358Z","steps":["trace[1304591408] 'process raft request' (duration: 157.863903ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:45.774757Z","caller":"traceutil/trace.go:171","msg":"trace[1433096077] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"159.437758ms","start":"2026-02-07T02:24:45.615296Z","end":"2026-02-07T02:24:45.774733Z","steps":["trace[1433096077] 'process raft request' (duration: 97.506221ms)","trace[1433096077] 'compare' (duration: 61.828091ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:45.790309Z","caller":"traceutil/trace.go:171","msg":"trace[631231884] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"148.774773ms","start":"2026-02-07T02:24:45.641523Z","end":"2026-02-07T02:24:45.790298Z","steps":["trace[631231884] 'read index received' (duration: 71.286034ms)","trace[631231884] 'applied index is now lower than readState.Index' (duration: 77.488079ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:24:45.790382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.846457ms","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-02-07T02:24:45.790398Z","caller":"traceutil/trace.go:171","msg":"trace[262836603] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:574; }","duration":"148.908651ms","start":"2026-02-07T02:24:45.641484Z","end":"2026-02-07T02:24:45.790393Z","steps":["trace[262836603] 'agreement among raft nodes before linearized reading' (duration: 148.859888ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:45.790543Z","caller":"traceutil/trace.go:171","msg":"trace[1141790254] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"172.193039ms","start":"2026-02-07T02:24:45.618345Z","end":"2026-02-07T02:24:45.790538Z","steps":["trace[1141790254] 'process raft request' (duration: 171.8685ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:46.015958Z","caller":"traceutil/trace.go:171","msg":"trace[1165909573] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"135.450437ms","start":"2026-02-07T02:24:45.880493Z","end":"2026-02-07T02:24:46.015943Z","steps":["trace[1165909573] 'process raft request' (duration: 135.423356ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:46.016159Z","caller":"traceutil/trace.go:171","msg":"trace[105175891] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"214.604107ms","start":"2026-02-07T02:24:45.801549Z","end":"2026-02-07T02:24:46.016153Z","steps":["trace[105175891] 'process raft request' (duration: 129.392859ms)","trace[105175891] 'compare' (duration: 84.848746ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:46.016221Z","caller":"traceutil/trace.go:171","msg":"trace[1409212226] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"174.868093ms","start":"2026-02-07T02:24:45.841347Z","end":"2026-02-07T02:24:46.016215Z","steps":["trace[1409212226] 'read index received' (duration: 89.604302ms)","trace[1409212226] 'applied index is now lower than readState.Index' (duration: 85.262961ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:46.016301Z","caller":"traceutil/trace.go:171","msg":"trace[1498808593] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"158.379516ms","start":"2026-02-07T02:24:45.857917Z","end":"2026-02-07T02:24:46.016296Z","steps":["trace[1498808593] 'process raft request' (duration: 157.974911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:46.016412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.053924ms","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-02-07T02:24:46.016435Z","caller":"traceutil/trace.go:171","msg":"trace[822252338] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:577; }","duration":"175.106108ms","start":"2026-02-07T02:24:45.841321Z","end":"2026-02-07T02:24:46.016427Z","steps":["trace[822252338] 'agreement among raft nodes before linearized reading' (duration: 175.066275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:46.016532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.158953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T02:24:46.016547Z","caller":"traceutil/trace.go:171","msg":"trace[1607329048] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:577; }","duration":"121.202906ms","start":"2026-02-07T02:24:45.895341Z","end":"2026-02-07T02:24:46.016544Z","steps":["trace[1607329048] 'agreement among raft nodes before linearized reading' (duration: 121.146422ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:46.218707Z","caller":"traceutil/trace.go:171","msg":"trace[1835810000] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"186.463978ms","start":"2026-02-07T02:24:46.032229Z","end":"2026-02-07T02:24:46.218693Z","steps":["trace[1835810000] 'process raft request' (duration: 173.828052ms)","trace[1835810000] 'compare' (duration: 12.560631ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:46.237134Z","caller":"traceutil/trace.go:171","msg":"trace[2100564917] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:593; }","duration":"195.569869ms","start":"2026-02-07T02:24:46.04155Z","end":"2026-02-07T02:24:46.23712Z","steps":["trace[2100564917] 'read index received' (duration: 164.458564ms)","trace[2100564917] 'applied index is now lower than readState.Index' (duration: 31.110655ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:46.237337Z","caller":"traceutil/trace.go:171","msg":"trace[31791369] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"198.418422ms","start":"2026-02-07T02:24:46.038912Z","end":"2026-02-07T02:24:46.23733Z","steps":["trace[31791369] 'process raft request' (duration: 198.132235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:46.237495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.931252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-07T02:24:46.237518Z","caller":"traceutil/trace.go:171","msg":"trace[1330989555] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:579; }","duration":"195.981975ms","start":"2026-02-07T02:24:46.04153Z","end":"2026-02-07T02:24:46.237512Z","steps":["trace[1330989555] 'agreement among raft nodes before linearized reading' (duration: 195.89836ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:47.46222Z","caller":"traceutil/trace.go:171","msg":"trace[1338898968] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"154.603249ms","start":"2026-02-07T02:24:47.307599Z","end":"2026-02-07T02:24:47.462202Z","steps":["trace[1338898968] 'read index received' (duration: 2.132899ms)","trace[1338898968] 'applied index is now lower than readState.Index' (duration: 152.46957ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:24:47.46235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.744048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:24:47.462377Z","caller":"traceutil/trace.go:171","msg":"trace[305596551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"154.811302ms","start":"2026-02-07T02:24:47.307559Z","end":"2026-02-07T02:24:47.46237Z","steps":["trace[305596551] 'agreement among raft nodes before linearized reading' (duration: 154.723427ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:47.46265Z","caller":"traceutil/trace.go:171","msg":"trace[1370401257] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"197.178302ms","start":"2026-02-07T02:24:47.265463Z","end":"2026-02-07T02:24:47.462641Z","steps":["trace[1370401257] 'process raft request' (duration: 189.448573ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:47.695701Z","caller":"traceutil/trace.go:171","msg":"trace[1373206442] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"214.209215ms","start":"2026-02-07T02:24:47.481469Z","end":"2026-02-07T02:24:47.695678Z","steps":["trace[1373206442] 'process raft request' (duration: 185.505954ms)","trace[1373206442] 'compare' (duration: 28.160698ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:24:48.049453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.496338ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:24:48.049522Z","caller":"traceutil/trace.go:171","msg":"trace[766564403] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:588; }","duration":"112.586343ms","start":"2026-02-07T02:24:47.936924Z","end":"2026-02-07T02:24:48.049511Z","steps":["trace[766564403] 'range keys from in-memory index tree' (duration: 112.487678ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:48.107829Z","caller":"traceutil/trace.go:171","msg":"trace[859106088] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"203.984063ms","start":"2026-02-07T02:24:47.903806Z","end":"2026-02-07T02:24:48.10779Z","steps":["trace[859106088] 'process raft request' (duration: 203.133502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:48.365877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.073813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:24:48.365966Z","caller":"traceutil/trace.go:171","msg":"trace[2105306706] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"325.108207ms","start":"2026-02-07T02:24:48.040827Z","end":"2026-02-07T02:24:48.365935Z","steps":["trace[2105306706] 'read index received' (duration: 66.630127ms)","trace[2105306706] 'applied index is now lower than readState.Index' (duration: 258.47697ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:48.366141Z","caller":"traceutil/trace.go:171","msg":"trace[1153895418] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"444.355444ms","start":"2026-02-07T02:24:47.921777Z","end":"2026-02-07T02:24:48.366132Z","steps":["trace[1153895418] 'process raft request' (duration: 338.966053ms)","trace[1153895418] 'compare' (duration: 104.911132ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:24:48.366211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:47.921755Z","time spent":"444.430439ms","remote":"127.0.0.1:33610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:24:48.366481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.64932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-07T02:24:48.366512Z","caller":"traceutil/trace.go:171","msg":"trace[790207407] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:590; }","duration":"325.703223ms","start":"2026-02-07T02:24:48.040796Z","end":"2026-02-07T02:24:48.366499Z","steps":["trace[790207407] 'agreement among raft nodes before linearized reading' (duration: 325.546384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:48.366535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:48.040782Z","time spent":"325.747466ms","remote":"127.0.0.1:33610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-02-07T02:24:48.369204Z","caller":"traceutil/trace.go:171","msg":"trace[1942645167] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"146.904713ms","start":"2026-02-07T02:24:48.222284Z","end":"2026-02-07T02:24:48.369189Z","steps":["trace[1942645167] 'process raft request' (duration: 146.659878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:49.328795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.90516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:24:49.328919Z","caller":"traceutil/trace.go:171","msg":"trace[1439031831] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"962.91741ms","start":"2026-02-07T02:24:48.365982Z","end":"2026-02-07T02:24:49.3289Z","steps":["trace[1439031831] 'read index received' (duration: 3.836383ms)","trace[1439031831] 'applied index is now lower than readState.Index' (duration: 959.078527ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:24:49.329167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021983169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-07T02:24:49.329112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021145378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-07T02:24:49.329241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063158729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-07T02:24:49.329258Z","caller":"traceutil/trace.go:171","msg":"trace[720313166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"1.063195292s","start":"2026-02-07T02:24:48.266059Z","end":"2026-02-07T02:24:49.329254Z","steps":["trace[720313166] 'agreement among raft nodes before linearized reading' (duration: 1.063137818s)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:49.329253Z","caller":"traceutil/trace.go:171","msg":"trace[827479631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"1.021315198s","start":"2026-02-07T02:24:48.307915Z","end":"2026-02-07T02:24:49.32923Z","steps":["trace[827479631] 'agreement among raft nodes before linearized reading' (duration: 1.021004159s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:49.329333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084435612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T02:24:49.329349Z","caller":"traceutil/trace.go:171","msg":"trace[1308619368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:592; }","duration":"1.084474533s","start":"2026-02-07T02:24:48.24487Z","end":"2026-02-07T02:24:49.329345Z","steps":["trace[1308619368] 'agreement among raft nodes before linearized reading' (duration: 1.084429301s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:49.329364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:48.244855Z","time spent":"1.084505715s","remote":"127.0.0.1:33848","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-02-07T02:24:49.329334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:48.307911Z","time spent":"1.021412194s","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T02:24:49.329182Z","caller":"traceutil/trace.go:171","msg":"trace[1178940748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"1.022033641s","start":"2026-02-07T02:24:48.307142Z","end":"2026-02-07T02:24:49.329176Z","steps":["trace[1178940748] 'agreement among raft nodes before linearized reading' (duration: 1.021831909s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:49.329809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:48.307088Z","time spent":"1.022704383s","remote":"127.0.0.1:33848","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-02-07T02:24:49.329987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.558627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-07T02:24:49.330012Z","caller":"traceutil/trace.go:171","msg":"trace[1376783322] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:592; }","duration":"973.60593ms","start":"2026-02-07T02:24:48.356398Z","end":"2026-02-07T02:24:49.330004Z","steps":["trace[1376783322] 'agreement among raft nodes before linearized reading' (duration: 973.200395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:49.330029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:48.356385Z","time spent":"973.640702ms","remote":"127.0.0.1:33610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-07T02:24:49.329285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:48.266047Z","time spent":"1.063232133s","remote":"127.0.0.1:33848","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-02-07T02:24:49.332752Z","caller":"traceutil/trace.go:171","msg":"trace[271956539] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.010578006s","start":"2026-02-07T02:24:48.32216Z","end":"2026-02-07T02:24:49.332738Z","steps":["trace[271956539] 'process raft request' (duration: 340.640871ms)","trace[271956539] 'compare' (duration: 665.599271ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:24:49.332841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:48.322147Z","time spent":"1.010661571s","remote":"127.0.0.1:33638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:24:49.33367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.407809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T02:24:49.33372Z","caller":"traceutil/trace.go:171","msg":"trace[55650673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"839.481263ms","start":"2026-02-07T02:24:48.494233Z","end":"2026-02-07T02:24:49.333714Z","steps":["trace[55650673] 'agreement among raft nodes before linearized reading' (duration: 839.360326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:49.333748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:48.494204Z","time spent":"839.537337ms","remote":"127.0.0.1:33848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-07T02:24:49.334009Z","caller":"traceutil/trace.go:171","msg":"trace[461930750] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"778.059533ms","start":"2026-02-07T02:24:48.555943Z","end":"2026-02-07T02:24:49.334002Z","steps":["trace[461930750] 'process raft request' (duration: 777.453646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:49.334074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:48.555927Z","time spent":"778.117746ms","remote":"127.0.0.1:33610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:24:49.334385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.508119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188243"} {"level":"info","ts":"2026-02-07T02:24:49.334436Z","caller":"traceutil/trace.go:171","msg":"trace[1106937938] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:593; }","duration":"383.588044ms","start":"2026-02-07T02:24:48.950837Z","end":"2026-02-07T02:24:49.334425Z","steps":["trace[1106937938] 'agreement among raft nodes before linearized reading' (duration: 383.475487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:49.334467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:48.950818Z","time spent":"383.641987ms","remote":"127.0.0.1:33610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-07T02:24:49.334663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.41516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T02:24:49.334705Z","caller":"traceutil/trace.go:171","msg":"trace[637836830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"812.489134ms","start":"2026-02-07T02:24:48.52221Z","end":"2026-02-07T02:24:49.334699Z","steps":["trace[637836830] 'agreement among raft nodes before linearized reading' (duration: 812.41447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:49.334732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:48.522197Z","time spent":"812.529246ms","remote":"127.0.0.1:33848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T02:24:49.611263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.105643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:24:49.611458Z","caller":"traceutil/trace.go:171","msg":"trace[1365720672] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:610; }","duration":"144.060541ms","start":"2026-02-07T02:24:49.467388Z","end":"2026-02-07T02:24:49.611448Z","steps":["trace[1365720672] 'read index received' (duration: 109.610016ms)","trace[1365720672] 'applied index is now lower than readState.Index' (duration: 34.450055ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:24:49.611638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.248812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188243"} {"level":"info","ts":"2026-02-07T02:24:49.611653Z","caller":"traceutil/trace.go:171","msg":"trace[2019148202] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:600; }","duration":"144.290475ms","start":"2026-02-07T02:24:49.467357Z","end":"2026-02-07T02:24:49.611648Z","steps":["trace[2019148202] 'agreement among raft nodes before linearized reading' (duration: 144.117824ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:49.611867Z","caller":"traceutil/trace.go:171","msg":"trace[1768942836] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"259.210473ms","start":"2026-02-07T02:24:49.35265Z","end":"2026-02-07T02:24:49.61186Z","steps":["trace[1768942836] 'process raft request' (duration: 77.46236ms)","trace[1768942836] 'compare' (duration: 181.03516ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:49.611923Z","caller":"traceutil/trace.go:171","msg":"trace[174834938] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"257.772585ms","start":"2026-02-07T02:24:49.354146Z","end":"2026-02-07T02:24:49.611919Z","steps":["trace[174834938] 'process raft request' (duration: 257.175959ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:49.612006Z","caller":"traceutil/trace.go:171","msg":"trace[1793709167] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"243.905103ms","start":"2026-02-07T02:24:49.368097Z","end":"2026-02-07T02:24:49.612002Z","steps":["trace[1793709167] 'process raft request' (duration: 243.258153ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:49.612052Z","caller":"traceutil/trace.go:171","msg":"trace[1173639650] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"236.750407ms","start":"2026-02-07T02:24:49.375297Z","end":"2026-02-07T02:24:49.612047Z","steps":["trace[1173639650] 'process raft request' (duration: 236.081127ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:49.612095Z","caller":"traceutil/trace.go:171","msg":"trace[1637728027] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"236.727866ms","start":"2026-02-07T02:24:49.375364Z","end":"2026-02-07T02:24:49.612092Z","steps":["trace[1637728027] 'process raft request' (duration: 236.035394ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:49.612144Z","caller":"traceutil/trace.go:171","msg":"trace[658429129] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"224.736337ms","start":"2026-02-07T02:24:49.387403Z","end":"2026-02-07T02:24:49.61214Z","steps":["trace[658429129] 'process raft request' (duration: 224.010683ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:49.612191Z","caller":"traceutil/trace.go:171","msg":"trace[676965736] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"216.528058ms","start":"2026-02-07T02:24:49.39566Z","end":"2026-02-07T02:24:49.612188Z","steps":["trace[676965736] 'process raft request' (duration: 215.773892ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:49.665783Z","caller":"traceutil/trace.go:171","msg":"trace[1556891042] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"134.488539ms","start":"2026-02-07T02:24:49.531273Z","end":"2026-02-07T02:24:49.665762Z","steps":["trace[1556891042] 'process raft request' (duration: 133.975047ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:49.668112Z","caller":"traceutil/trace.go:171","msg":"trace[986081297] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"152.532696ms","start":"2026-02-07T02:24:49.515567Z","end":"2026-02-07T02:24:49.6681Z","steps":["trace[986081297] 'process raft request' (duration: 148.859283ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:50.409765Z","caller":"traceutil/trace.go:171","msg":"trace[1417412148] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"130.471294ms","start":"2026-02-07T02:24:50.279277Z","end":"2026-02-07T02:24:50.409748Z","steps":["trace[1417412148] 'process raft request' (duration: 130.320545ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:50.422785Z","caller":"traceutil/trace.go:171","msg":"trace[1491656074] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"137.720364ms","start":"2026-02-07T02:24:50.285046Z","end":"2026-02-07T02:24:50.422766Z","steps":["trace[1491656074] 'process raft request' (duration: 137.610718ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:57.773442Z","caller":"traceutil/trace.go:171","msg":"trace[1187687982] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"133.91077ms","start":"2026-02-07T02:24:57.639508Z","end":"2026-02-07T02:24:57.773419Z","steps":["trace[1187687982] 'read index received' (duration: 42.135564ms)","trace[1187687982] 'applied index is now lower than readState.Index' (duration: 91.774326ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:57.773562Z","caller":"traceutil/trace.go:171","msg":"trace[180614823] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"150.460104ms","start":"2026-02-07T02:24:57.623091Z","end":"2026-02-07T02:24:57.773551Z","steps":["trace[180614823] 'process raft request' (duration: 80.29625ms)","trace[180614823] 'compare' (duration: 69.911751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:24:57.773899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.373857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-q9c4j\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-07T02:24:57.773936Z","caller":"traceutil/trace.go:171","msg":"trace[1400682862] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-q9c4j; range_end:; response_count:1; response_revision:676; }","duration":"134.420146ms","start":"2026-02-07T02:24:57.639504Z","end":"2026-02-07T02:24:57.773924Z","steps":["trace[1400682862] 'agreement among raft nodes before linearized reading' (duration: 134.236841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:26:08.541808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.643166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-07T02:26:08.541858Z","caller":"traceutil/trace.go:171","msg":"trace[697586138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:939; }","duration":"338.76183ms","start":"2026-02-07T02:26:08.203085Z","end":"2026-02-07T02:26:08.541847Z","steps":["trace[697586138] 'range keys from in-memory index tree' (duration: 338.524514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:26:08.541878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:26:08.203057Z","time spent":"338.816832ms","remote":"127.0.0.1:33848","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-02-07T02:26:08.542006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.641281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-07T02:26:08.542019Z","caller":"traceutil/trace.go:171","msg":"trace[2019858488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:939; }","duration":"337.729122ms","start":"2026-02-07T02:26:08.204286Z","end":"2026-02-07T02:26:08.542015Z","steps":["trace[2019858488] 'range keys from in-memory index tree' (duration: 337.571998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:26:08.542031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:26:08.204277Z","time spent":"337.751494ms","remote":"127.0.0.1:33848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T02:26:08.542096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.505344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:26:08.542151Z","caller":"traceutil/trace.go:171","msg":"trace[286274599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"235.604796ms","start":"2026-02-07T02:26:08.306535Z","end":"2026-02-07T02:26:08.54214Z","steps":["trace[286274599] 'range keys from in-memory index tree' (duration: 235.419131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:26:08.542245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.945623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T02:26:08.542262Z","caller":"traceutil/trace.go:171","msg":"trace[2096666540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:939; }","duration":"305.000055ms","start":"2026-02-07T02:26:08.237257Z","end":"2026-02-07T02:26:08.542257Z","steps":["trace[2096666540] 'range keys from in-memory index tree' (duration: 304.871622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:26:08.542274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:26:08.237242Z","time spent":"305.028786ms","remote":"127.0.0.1:33848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T02:26:08.542354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.666332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T02:26:08.542368Z","caller":"traceutil/trace.go:171","msg":"trace[1313572601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:939; }","duration":"305.719803ms","start":"2026-02-07T02:26:08.236642Z","end":"2026-02-07T02:26:08.542362Z","steps":["trace[1313572601] 'range keys from in-memory index tree' (duration: 305.590051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:26:08.542379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:26:08.236624Z","time spent":"305.752225ms","remote":"127.0.0.1:33848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T02:26:08.543455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.774843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-07T02:26:08.543486Z","caller":"traceutil/trace.go:171","msg":"trace[1973380459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:939; }","duration":"287.835715ms","start":"2026-02-07T02:26:08.255643Z","end":"2026-02-07T02:26:08.543479Z","steps":["trace[1973380459] 'range keys from in-memory index tree' (duration: 286.618843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:26:09.364451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.497292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:26:09.365423Z","caller":"traceutil/trace.go:171","msg":"trace[781534260] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:976; }","duration":"293.351081ms","start":"2026-02-07T02:26:09.072052Z","end":"2026-02-07T02:26:09.365403Z","steps":["trace[781534260] 'read index received' (duration: 31.271µs)","trace[781534260] 'applied index is now lower than readState.Index' (duration: 293.31875ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:26:09.36553Z","caller":"traceutil/trace.go:171","msg":"trace[783687371] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"810.307091ms","start":"2026-02-07T02:26:08.555213Z","end":"2026-02-07T02:26:09.36552Z","steps":["trace[783687371] 'process raft request' (duration: 100.686189ms)","trace[783687371] 'compare' (duration: 708.153503ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:26:09.365619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:26:08.555154Z","time spent":"810.400503ms","remote":"127.0.0.1:33848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:26:09.365843Z","caller":"traceutil/trace.go:171","msg":"trace[644011309] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"809.402747ms","start":"2026-02-07T02:26:08.556431Z","end":"2026-02-07T02:26:09.365834Z","steps":["trace[644011309] 'process raft request' (duration: 808.813332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:26:09.365891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:26:08.556418Z","time spent":"809.448558ms","remote":"127.0.0.1:33848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:26:09.366218Z","caller":"traceutil/trace.go:171","msg":"trace[1290502849] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"805.832073ms","start":"2026-02-07T02:26:08.560376Z","end":"2026-02-07T02:26:09.366208Z","steps":["trace[1290502849] 'process raft request' (duration: 804.96413ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:26:09.366256Z","caller":"traceutil/trace.go:171","msg":"trace[1577991716] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"805.829023ms","start":"2026-02-07T02:26:08.560421Z","end":"2026-02-07T02:26:09.36625Z","steps":["trace[1577991716] 'process raft request' (duration: 804.95063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:26:09.368671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:26:08.560417Z","time spent":"808.230527ms","remote":"127.0.0.1:33848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:26:09.36629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.235624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:26:09.369396Z","caller":"traceutil/trace.go:171","msg":"trace[1118388080] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:944; }","duration":"297.365328ms","start":"2026-02-07T02:26:09.072019Z","end":"2026-02-07T02:26:09.369385Z","steps":["trace[1118388080] 'agreement among raft nodes before linearized reading' (duration: 294.250195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:26:09.368009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:26:08.560365Z","time spent":"807.61431ms","remote":"127.0.0.1:33848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}