{"level":"warn","ts":"2026-02-07T02:23:12.138015Z","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:23:12.138195Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.41: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.41:2380","--initial-cluster=instance=https://199.204.45.41:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.41:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.41: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:23:12.138368Z","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:23:12.138391Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-07T02:23:12.138446Z","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:23:12.139132Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"]} {"level":"info","ts":"2026-02-07T02:23:12.139258Z","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.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.41: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:23:12.154328Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.798838ms"} {"level":"info","ts":"2026-02-07T02:23:12.222194Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"17dd22a2661aae79","cluster-id":"c5133f40b27eeb3"} {"level":"info","ts":"2026-02-07T02:23:12.222288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T02:23:12.222309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 0"} {"level":"info","ts":"2026-02-07T02:23:12.222319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 17dd22a2661aae79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-07T02:23:12.222327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 1"} {"level":"info","ts":"2026-02-07T02:23:12.222364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"warn","ts":"2026-02-07T02:23:12.262265Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T02:23:12.27044Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T02:23:12.272397Z","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:23:12.274048Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"17dd22a2661aae79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-07T02:23:12.279363Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"17dd22a2661aae79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-07T02:23:12.279512Z","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:23:12.279542Z","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:23:12.279562Z","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:23:12.281325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"info","ts":"2026-02-07T02:23:12.281659Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","added-peer-id":"17dd22a2661aae79","added-peer-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-07T02:23:12.28213Z","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:23:12.282288Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"17dd22a2661aae79","initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-07T02:23:12.282309Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T02:23:12.282397Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-07T02:23:12.282409Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-07T02:23:12.625341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T02:23:12.625389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T02:23:12.625409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgPreVoteResp from 17dd22a2661aae79 at term 1"} {"level":"info","ts":"2026-02-07T02:23:12.625501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became candidate at term 2"} {"level":"info","ts":"2026-02-07T02:23:12.625512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgVoteResp from 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-07T02:23:12.62552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became leader at term 2"} {"level":"info","ts":"2026-02-07T02:23:12.625572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 17dd22a2661aae79 elected leader 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-07T02:23:12.634215Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"17dd22a2661aae79","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.41:2379]}","request-path":"/0/members/17dd22a2661aae79/attributes","cluster-id":"c5133f40b27eeb3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-07T02:23:12.6344Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:23:12.634521Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T02:23:12.634878Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T02:23:12.636359Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T02:23:12.636373Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T02:23:12.636453Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:23:12.636544Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:23:12.636562Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:23:12.637713Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.41:2379"} {"level":"info","ts":"2026-02-07T02:23:12.642695Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-07T02:23:16.396081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.720882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:23:16.396533Z","caller":"traceutil/trace.go:171","msg":"trace[1564883979] transaction","detail":"{read_only:false; response_revision:189; number_of_response:1; }","duration":"241.063842ms","start":"2026-02-07T02:23:16.155412Z","end":"2026-02-07T02:23:16.396476Z","steps":["trace[1564883979] 'process raft request' (duration: 98.079876ms)","trace[1564883979] 'compare' (duration: 141.544536ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:23:17.059524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251590138748883,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T02:23:17.559935Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251590138748883,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T02:23:17.920772Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.515594971s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-07T02:23:18.560023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000563535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-07T02:23:18.560104Z","caller":"traceutil/trace.go:171","msg":"trace[681589542] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00068898s","start":"2026-02-07T02:23:16.559403Z","end":"2026-02-07T02:23:18.560092Z","steps":["trace[681589542] 'agreement among raft nodes before linearized reading' (duration: 2.000596417s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:18.560135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:16.559384Z","time spent":"2.000744571s","remote":"127.0.0.1:36554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/07 02:23:18 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-07T02:23:18.601083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.015242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:23:18.601278Z","caller":"traceutil/trace.go:171","msg":"trace[574724083] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"2.196149451s","start":"2026-02-07T02:23:16.405113Z","end":"2026-02-07T02:23:18.601262Z","steps":["trace[574724083] 'process raft request' (duration: 1.515910412s)","trace[574724083] 'compare' (duration: 679.277686ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:23:18.601369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:16.405101Z","time spent":"2.196237914s","remote":"127.0.0.1:36924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":714,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:23:18.643616Z","caller":"traceutil/trace.go:171","msg":"trace[700910111] linearizableReadLoop","detail":"{readStateIndex:196; appliedIndex:193; }","duration":"2.084151576s","start":"2026-02-07T02:23:16.559443Z","end":"2026-02-07T02:23:18.643595Z","steps":["trace[700910111] 'read index received' (duration: 1.361588363s)","trace[700910111] 'applied index is now lower than readState.Index' (duration: 722.562223ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:23:18.643654Z","caller":"traceutil/trace.go:171","msg":"trace[732762401] transaction","detail":"{read_only:false; response_revision:191; number_of_response:1; }","duration":"2.031209426s","start":"2026-02-07T02:23:16.612418Z","end":"2026-02-07T02:23:18.643628Z","steps":["trace[732762401] 'process raft request' (duration: 2.030966598s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:18.643833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203022184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:23:18.643872Z","caller":"traceutil/trace.go:171","msg":"trace[2104287511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:191; }","duration":"1.203128887s","start":"2026-02-07T02:23:17.440735Z","end":"2026-02-07T02:23:18.643864Z","steps":["trace[2104287511] 'agreement among raft nodes before linearized reading' (duration: 1.203054495s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:18.643897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:17.440714Z","time spent":"1.203178068s","remote":"127.0.0.1:36838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T02:23:18.644185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:16.612405Z","time spent":"2.031404004s","remote":"127.0.0.1:36826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:23:18.644416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.261295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:23:18.644446Z","caller":"traceutil/trace.go:171","msg":"trace[210662834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:191; }","duration":"345.322698ms","start":"2026-02-07T02:23:18.299116Z","end":"2026-02-07T02:23:18.644439Z","steps":["trace[210662834] 'agreement among raft nodes before linearized reading' (duration: 345.283656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:18.644463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:18.2991Z","time spent":"345.359488ms","remote":"127.0.0.1:36838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T02:23:18.643759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:16.889386Z","time spent":"1.754365118s","remote":"127.0.0.1:36616","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-07T02:23:18.644545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.395226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:23:18.64457Z","caller":"traceutil/trace.go:171","msg":"trace[1874810643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:191; }","duration":"927.449938ms","start":"2026-02-07T02:23:17.717113Z","end":"2026-02-07T02:23:18.644563Z","steps":["trace[1874810643] 'agreement among raft nodes before linearized reading' (duration: 927.414707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:18.644587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:17.717095Z","time spent":"927.488308ms","remote":"127.0.0.1:36554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T02:23:18.793502Z","caller":"traceutil/trace.go:171","msg":"trace[1351703695] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"138.545853ms","start":"2026-02-07T02:23:18.654935Z","end":"2026-02-07T02:23:18.793481Z","steps":["trace[1351703695] 'process raft request' (duration: 63.402117ms)","trace[1351703695] 'compare' (duration: 74.717272ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:23:18.795581Z","caller":"traceutil/trace.go:171","msg":"trace[708939398] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"139.075811ms","start":"2026-02-07T02:23:18.65647Z","end":"2026-02-07T02:23:18.795545Z","steps":["trace[708939398] 'process raft request' (duration: 136.893277ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:19.128831Z","caller":"traceutil/trace.go:171","msg":"trace[271809911] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"176.594206ms","start":"2026-02-07T02:23:18.952211Z","end":"2026-02-07T02:23:19.128806Z","steps":["trace[271809911] 'process raft request' (duration: 176.530124ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:19.129196Z","caller":"traceutil/trace.go:171","msg":"trace[1768078884] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"180.043372ms","start":"2026-02-07T02:23:18.949139Z","end":"2026-02-07T02:23:19.129182Z","steps":["trace[1768078884] 'process raft request' (duration: 89.889276ms)","trace[1768078884] 'compare' (duration: 88.992537ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:23:19.129414Z","caller":"traceutil/trace.go:171","msg":"trace[399885860] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:212; }","duration":"179.060259ms","start":"2026-02-07T02:23:18.950145Z","end":"2026-02-07T02:23:19.129205Z","steps":["trace[399885860] 'read index received' (duration: 88.895302ms)","trace[399885860] 'applied index is now lower than readState.Index' (duration: 90.162897ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:23:19.12991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.492114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.41\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:23:19.130673Z","caller":"traceutil/trace.go:171","msg":"trace[76594643] range","detail":"{range_begin:/registry/masterleases/199.204.45.41; range_end:; response_count:0; response_revision:209; }","duration":"180.265041ms","start":"2026-02-07T02:23:18.950388Z","end":"2026-02-07T02:23:19.130653Z","steps":["trace[76594643] 'agreement among raft nodes before linearized reading' (duration: 179.476483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:19.129926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.907438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T02:23:19.131145Z","caller":"traceutil/trace.go:171","msg":"trace[374428698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:209; }","duration":"181.149611ms","start":"2026-02-07T02:23:18.949982Z","end":"2026-02-07T02:23:19.131132Z","steps":["trace[374428698] 'agreement among raft nodes before linearized reading' (duration: 179.799234ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:19.2722Z","caller":"traceutil/trace.go:171","msg":"trace[745611422] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"122.313318ms","start":"2026-02-07T02:23:19.149873Z","end":"2026-02-07T02:23:19.272186Z","steps":["trace[745611422] 'process raft request' (duration: 119.482102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:20.57361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.209923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:23:20.573893Z","caller":"traceutil/trace.go:171","msg":"trace[595189894] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"167.266107ms","start":"2026-02-07T02:23:20.406582Z","end":"2026-02-07T02:23:20.573848Z","steps":["trace[595189894] 'process raft request' (duration: 167.213505ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:20.574274Z","caller":"traceutil/trace.go:171","msg":"trace[1669997349] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"169.616176ms","start":"2026-02-07T02:23:20.404645Z","end":"2026-02-07T02:23:20.574261Z","steps":["trace[1669997349] 'process raft request' (duration: 37.697149ms)","trace[1669997349] 'compare' (duration: 131.085519ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:23:20.574459Z","caller":"traceutil/trace.go:171","msg":"trace[1765493424] linearizableReadLoop","detail":"{readStateIndex:283; appliedIndex:282; }","duration":"169.401949ms","start":"2026-02-07T02:23:20.405046Z","end":"2026-02-07T02:23:20.574448Z","steps":["trace[1765493424] 'read index received' (duration: 37.373819ms)","trace[1765493424] 'applied index is now lower than readState.Index' (duration: 132.02655ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:23:20.574894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.870464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-07T02:23:20.574938Z","caller":"traceutil/trace.go:171","msg":"trace[325806327] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:278; }","duration":"169.935407ms","start":"2026-02-07T02:23:20.404992Z","end":"2026-02-07T02:23:20.574927Z","steps":["trace[325806327] 'agreement among raft nodes before linearized reading' (duration: 169.496482ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:21.904096Z","caller":"traceutil/trace.go:171","msg":"trace[1555449676] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"121.372173ms","start":"2026-02-07T02:23:21.782697Z","end":"2026-02-07T02:23:21.90407Z","steps":["trace[1555449676] 'process raft request' (duration: 64.850732ms)","trace[1555449676] 'compare' (duration: 56.300522ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:23:21.914971Z","caller":"traceutil/trace.go:171","msg":"trace[774122788] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:326; }","duration":"107.746649ms","start":"2026-02-07T02:23:21.807208Z","end":"2026-02-07T02:23:21.914955Z","steps":["trace[774122788] 'read index received' (duration: 40.402161ms)","trace[774122788] 'applied index is now lower than readState.Index' (duration: 67.343978ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:23:21.915021Z","caller":"traceutil/trace.go:171","msg":"trace[723590552] transaction","detail":"{read_only:false; number_of_response:0; response_revision:320; }","duration":"128.767394ms","start":"2026-02-07T02:23:21.786229Z","end":"2026-02-07T02:23:21.914996Z","steps":["trace[723590552] 'process raft request' (duration: 128.673621ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:21.915081Z","caller":"traceutil/trace.go:171","msg":"trace[2098722658] transaction","detail":"{read_only:false; number_of_response:0; response_revision:320; }","duration":"128.342789ms","start":"2026-02-07T02:23:21.786734Z","end":"2026-02-07T02:23:21.915076Z","steps":["trace[2098722658] 'process raft request' (duration: 128.185594ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:21.915222Z","caller":"traceutil/trace.go:171","msg":"trace[110126451] transaction","detail":"{read_only:false; number_of_response:0; response_revision:320; }","duration":"127.735649ms","start":"2026-02-07T02:23:21.787482Z","end":"2026-02-07T02:23:21.915218Z","steps":["trace[110126451] 'process raft request' (duration: 127.457738ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:21.915203Z","caller":"traceutil/trace.go:171","msg":"trace[543305364] transaction","detail":"{read_only:false; number_of_response:0; response_revision:320; }","duration":"129.021894ms","start":"2026-02-07T02:23:21.786164Z","end":"2026-02-07T02:23:21.915186Z","steps":["trace[543305364] 'process raft request' (duration: 128.677571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:21.915058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.834012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:23:21.915343Z","caller":"traceutil/trace.go:171","msg":"trace[1645469476] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:320; }","duration":"108.153684ms","start":"2026-02-07T02:23:21.807184Z","end":"2026-02-07T02:23:21.915337Z","steps":["trace[1645469476] 'agreement among raft nodes before linearized reading' (duration: 107.829832ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:24.823841Z","caller":"traceutil/trace.go:171","msg":"trace[933844945] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"165.622849ms","start":"2026-02-07T02:23:24.658121Z","end":"2026-02-07T02:23:24.823744Z","steps":["trace[933844945] 'read index received' (duration: 2.340259ms)","trace[933844945] 'applied index is now lower than readState.Index' (duration: 163.28137ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:23:24.824315Z","caller":"traceutil/trace.go:171","msg":"trace[1573127365] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"185.838727ms","start":"2026-02-07T02:23:24.638459Z","end":"2026-02-07T02:23:24.824298Z","steps":["trace[1573127365] 'process raft request' (duration: 185.143044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:24.824533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.401925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-07T02:23:24.824568Z","caller":"traceutil/trace.go:171","msg":"trace[456716865] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:356; }","duration":"166.479058ms","start":"2026-02-07T02:23:24.658079Z","end":"2026-02-07T02:23:24.824558Z","steps":["trace[456716865] 'agreement among raft nodes before linearized reading' (duration: 166.393465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:24.825317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.06498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-07T02:23:24.825364Z","caller":"traceutil/trace.go:171","msg":"trace[2009834139] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:357; }","duration":"118.148763ms","start":"2026-02-07T02:23:24.707205Z","end":"2026-02-07T02:23:24.825353Z","steps":["trace[2009834139] 'agreement among raft nodes before linearized reading' (duration: 118.038559ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:24.825898Z","caller":"traceutil/trace.go:171","msg":"trace[236695585] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"163.394914ms","start":"2026-02-07T02:23:24.66249Z","end":"2026-02-07T02:23:24.825885Z","steps":["trace[236695585] 'process raft request' (duration: 162.39019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:25.189611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.174483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:23:25.190233Z","caller":"traceutil/trace.go:171","msg":"trace[1871290750] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"344.857258ms","start":"2026-02-07T02:23:24.845348Z","end":"2026-02-07T02:23:25.190206Z","steps":["trace[1871290750] 'process raft request' (duration: 114.027662ms)","trace[1871290750] 'compare' (duration: 230.026808ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:23:25.190399Z","caller":"traceutil/trace.go:171","msg":"trace[344959582] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:371; }","duration":"344.724404ms","start":"2026-02-07T02:23:24.84544Z","end":"2026-02-07T02:23:25.190165Z","steps":["trace[344959582] 'read index received' (duration: 31.777842ms)","trace[344959582] 'applied index is now lower than readState.Index' (duration: 312.945542ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:23:25.190687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:24.845342Z","time spent":"345.263923ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:23:25.19081Z","caller":"traceutil/trace.go:171","msg":"trace[10850432] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"345.176149ms","start":"2026-02-07T02:23:24.845381Z","end":"2026-02-07T02:23:25.190557Z","steps":["trace[10850432] 'process raft request' (duration: 344.424743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:25.190926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:24.845377Z","time spent":"345.515181ms","remote":"127.0.0.1:37054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3620,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:23:25.192211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.886765ms","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:23:25.192257Z","caller":"traceutil/trace.go:171","msg":"trace[1826341038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:364; }","duration":"114.971858ms","start":"2026-02-07T02:23:25.077274Z","end":"2026-02-07T02:23:25.192246Z","steps":["trace[1826341038] 'agreement among raft nodes before linearized reading' (duration: 114.912606ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:25.191291Z","caller":"traceutil/trace.go:171","msg":"trace[1792105304] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"345.448347ms","start":"2026-02-07T02:23:24.845829Z","end":"2026-02-07T02:23:25.191277Z","steps":["trace[1792105304] 'process raft request' (duration: 344.255217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:25.192629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:24.845823Z","time spent":"346.766041ms","remote":"127.0.0.1:37060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2125,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:23:25.19152Z","caller":"traceutil/trace.go:171","msg":"trace[382319066] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"346.040187ms","start":"2026-02-07T02:23:24.845468Z","end":"2026-02-07T02:23:25.191508Z","steps":["trace[382319066] 'process raft request' (duration: 344.426412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:25.193873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.225298ms","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:23:25.193931Z","caller":"traceutil/trace.go:171","msg":"trace[938938510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:364; }","duration":"236.314911ms","start":"2026-02-07T02:23:24.957605Z","end":"2026-02-07T02:23:25.19392Z","steps":["trace[938938510] 'agreement among raft nodes before linearized reading' (duration: 236.216328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:25.194144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.030118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-07T02:23:25.194176Z","caller":"traceutil/trace.go:171","msg":"trace[1787675532] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:364; }","duration":"287.09103ms","start":"2026-02-07T02:23:24.907076Z","end":"2026-02-07T02:23:25.194167Z","steps":["trace[1787675532] 'agreement among raft nodes before linearized reading' (duration: 287.034819ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:25.191551Z","caller":"traceutil/trace.go:171","msg":"trace[1534603131] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"346.044497ms","start":"2026-02-07T02:23:24.8455Z","end":"2026-02-07T02:23:25.191544Z","steps":["trace[1534603131] 'process raft request' (duration: 344.512885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:25.196534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:24.845497Z","time spent":"350.961905ms","remote":"127.0.0.1:36932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:23:25.191854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.40237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-02-07T02:23:25.196966Z","caller":"traceutil/trace.go:171","msg":"trace[1680811155] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:364; }","duration":"351.529535ms","start":"2026-02-07T02:23:24.84542Z","end":"2026-02-07T02:23:25.196949Z","steps":["trace[1680811155] 'agreement among raft nodes before linearized reading' (duration: 346.38837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:25.198145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:24.845414Z","time spent":"352.712085ms","remote":"127.0.0.1:36734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7250,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-02-07T02:23:25.191894Z","caller":"traceutil/trace.go:171","msg":"trace[340599193] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"345.388426ms","start":"2026-02-07T02:23:24.846496Z","end":"2026-02-07T02:23:25.191884Z","steps":["trace[340599193] 'process raft request' (duration: 343.626626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:25.199029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:24.846489Z","time spent":"352.493928ms","remote":"127.0.0.1:36932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:23:25.191962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.422712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-07T02:23:25.200196Z","caller":"traceutil/trace.go:171","msg":"trace[700797872] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:364; }","duration":"342.680643ms","start":"2026-02-07T02:23:24.857504Z","end":"2026-02-07T02:23:25.200184Z","steps":["trace[700797872] 'agreement among raft nodes before linearized reading' (duration: 334.428472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:25.200676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:24.857493Z","time spent":"343.17501ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-07T02:23:25.193577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:24.845465Z","time spent":"347.87115ms","remote":"127.0.0.1:36932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:23:45.134033Z","caller":"traceutil/trace.go:171","msg":"trace[2034497707] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"275.452126ms","start":"2026-02-07T02:23:44.858557Z","end":"2026-02-07T02:23:45.134009Z","steps":["trace[2034497707] 'process raft request' (duration: 275.066383ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:49.178514Z","caller":"traceutil/trace.go:171","msg":"trace[1662804191] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"215.42291ms","start":"2026-02-07T02:23:48.96306Z","end":"2026-02-07T02:23:49.178483Z","steps":["trace[1662804191] 'process raft request' (duration: 214.508968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:57.193358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251590138749641,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T02:23:57.288865Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.074074951s","expected-duration":"1s"} {"level":"info","ts":"2026-02-07T02:23:57.289772Z","caller":"traceutil/trace.go:171","msg":"trace[1839490324] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"597.063579ms","start":"2026-02-07T02:23:56.692674Z","end":"2026-02-07T02:23:57.289738Z","steps":["trace[1839490324] 'read index received' (duration: 596.698187ms)","trace[1839490324] 'applied index is now lower than readState.Index' (duration: 363.912µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:23:57.290199Z","caller":"traceutil/trace.go:171","msg":"trace[1617897859] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"1.075576403s","start":"2026-02-07T02:23:56.214599Z","end":"2026-02-07T02:23:57.290176Z","steps":["trace[1617897859] 'process raft request' (duration: 1.074779006s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:57.290878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.185948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xdh86\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-07T02:23:57.304895Z","caller":"traceutil/trace.go:171","msg":"trace[1441499567] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xdh86; range_end:; response_count:1; response_revision:570; }","duration":"612.217276ms","start":"2026-02-07T02:23:56.692614Z","end":"2026-02-07T02:23:57.304831Z","steps":["trace[1441499567] 'agreement among raft nodes before linearized reading' (duration: 598.190108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:57.305044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:56.692578Z","time spent":"612.411443ms","remote":"127.0.0.1:36734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16674,"request content":"key:\"/registry/pods/kube-system/cilium-xdh86\" "} {"level":"warn","ts":"2026-02-07T02:23:57.306019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:56.214559Z","time spent":"1.075800521s","remote":"127.0.0.1:36838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:23:57.962274Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251590138749645,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T02:23:58.201997Z","caller":"traceutil/trace.go:171","msg":"trace[2019786221] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"740.331392ms","start":"2026-02-07T02:23:57.46165Z","end":"2026-02-07T02:23:58.201982Z","steps":["trace[2019786221] 'read index received' (duration: 740.198247ms)","trace[2019786221] 'applied index is now lower than readState.Index' (duration: 132.425µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:23:58.202245Z","caller":"traceutil/trace.go:171","msg":"trace[1423091392] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"875.914307ms","start":"2026-02-07T02:23:57.326324Z","end":"2026-02-07T02:23:58.202238Z","steps":["trace[1423091392] 'process raft request' (duration: 875.575266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:58.202307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:57.326295Z","time spent":"875.965059ms","remote":"127.0.0.1:36734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:23:58.202433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.784678ms","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:23:58.20245Z","caller":"traceutil/trace.go:171","msg":"trace[761858045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:571; }","duration":"740.84231ms","start":"2026-02-07T02:23:57.461601Z","end":"2026-02-07T02:23:58.202443Z","steps":["trace[761858045] 'agreement among raft nodes before linearized reading' (duration: 740.783888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:58.202463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:57.461578Z","time spent":"740.882221ms","remote":"127.0.0.1:36838","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:23:58.202553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.867076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T02:23:58.202565Z","caller":"traceutil/trace.go:171","msg":"trace[737429958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"643.935819ms","start":"2026-02-07T02:23:57.558625Z","end":"2026-02-07T02:23:58.202561Z","steps":["trace[737429958] 'agreement among raft nodes before linearized reading' (duration: 643.900297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:58.202577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:57.558597Z","time spent":"643.97608ms","remote":"127.0.0.1:36838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T02:23:58.202703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.728166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-07T02:23:58.202717Z","caller":"traceutil/trace.go:171","msg":"trace[2043478505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:571; }","duration":"621.753417ms","start":"2026-02-07T02:23:57.58096Z","end":"2026-02-07T02:23:58.202714Z","steps":["trace[2043478505] 'agreement among raft nodes before linearized reading' (duration: 621.728246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:58.202729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:57.580957Z","time spent":"621.769398ms","remote":"127.0.0.1:36838","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:23:58.2028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.851661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-07T02:23:58.202811Z","caller":"traceutil/trace.go:171","msg":"trace[471945772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"621.907832ms","start":"2026-02-07T02:23:57.5809Z","end":"2026-02-07T02:23:58.202808Z","steps":["trace[471945772] 'agreement among raft nodes before linearized reading' (duration: 621.873301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:58.202822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:57.580877Z","time spent":"621.942074ms","remote":"127.0.0.1:36838","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:23:58.406046Z","caller":"traceutil/trace.go:171","msg":"trace[634890686] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"191.644468ms","start":"2026-02-07T02:23:58.214378Z","end":"2026-02-07T02:23:58.406023Z","steps":["trace[634890686] 'process raft request' (duration: 191.43701ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:58.40665Z","caller":"traceutil/trace.go:171","msg":"trace[1518202903] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"191.182033ms","start":"2026-02-07T02:23:58.215458Z","end":"2026-02-07T02:23:58.40664Z","steps":["trace[1518202903] 'process raft request' (duration: 190.511829ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:23:58.408453Z","caller":"traceutil/trace.go:171","msg":"trace[1990253665] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:595; }","duration":"155.516211ms","start":"2026-02-07T02:23:58.252904Z","end":"2026-02-07T02:23:58.40842Z","steps":["trace[1990253665] 'read index received' (duration: 152.967192ms)","trace[1990253665] 'applied index is now lower than readState.Index' (duration: 2.548219ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:23:58.408571Z","caller":"traceutil/trace.go:171","msg":"trace[1826718047] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"193.040667ms","start":"2026-02-07T02:23:58.215514Z","end":"2026-02-07T02:23:58.408555Z","steps":["trace[1826718047] 'process raft request' (duration: 190.761017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:58.408632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.739168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:23:58.408661Z","caller":"traceutil/trace.go:171","msg":"trace[1509635099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"155.832361ms","start":"2026-02-07T02:23:58.252818Z","end":"2026-02-07T02:23:58.408651Z","steps":["trace[1509635099] 'agreement among raft nodes before linearized reading' (duration: 155.703317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:59.530338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.332217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.41\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T02:23:59.530425Z","caller":"traceutil/trace.go:171","msg":"trace[572624372] range","detail":"{range_begin:/registry/masterleases/199.204.45.41; range_end:; response_count:1; response_revision:575; }","duration":"725.481673ms","start":"2026-02-07T02:23:58.804922Z","end":"2026-02-07T02:23:59.530404Z","steps":["trace[572624372] 'range keys from in-memory index tree' (duration: 725.148111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:23:59.530486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:58.804903Z","time spent":"725.574596ms","remote":"127.0.0.1:36582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.41\" "} {"level":"warn","ts":"2026-02-07T02:23:59.530989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.459809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:23:59.531016Z","caller":"traceutil/trace.go:171","msg":"trace[1938640187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"248.48724ms","start":"2026-02-07T02:23:59.282522Z","end":"2026-02-07T02:23:59.531009Z","steps":["trace[1938640187] 'range keys from in-memory index tree' (duration: 248.393727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:00.734783Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251590138749657,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T02:24:00.736912Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.203436505s","expected-duration":"1s"} {"level":"info","ts":"2026-02-07T02:24:00.737206Z","caller":"traceutil/trace.go:171","msg":"trace[463582223] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"504.482502ms","start":"2026-02-07T02:24:00.232698Z","end":"2026-02-07T02:24:00.737181Z","steps":["trace[463582223] 'read index received' (duration: 504.310225ms)","trace[463582223] 'applied index is now lower than readState.Index' (duration: 171.357µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:24:00.737293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:23:59.533386Z","time spent":"1.203902262s","remote":"127.0.0.1:36582","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-07T02:24:00.737703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.979659ms","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:00.737744Z","caller":"traceutil/trace.go:171","msg":"trace[325744506] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"505.068232ms","start":"2026-02-07T02:24:00.232665Z","end":"2026-02-07T02:24:00.737733Z","steps":["trace[325744506] 'agreement among raft nodes before linearized reading' (duration: 504.959198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:00.737781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:00.232649Z","time spent":"505.125273ms","remote":"127.0.0.1:36838","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:24:00.73798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.096542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T02:24:00.738014Z","caller":"traceutil/trace.go:171","msg":"trace[138317084] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"327.157794ms","start":"2026-02-07T02:24:00.410847Z","end":"2026-02-07T02:24:00.738005Z","steps":["trace[138317084] 'agreement among raft nodes before linearized reading' (duration: 327.103042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:00.738045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:00.41083Z","time spent":"327.209566ms","remote":"127.0.0.1:36838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T02:24:00.738356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.043411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-07T02:24:00.738394Z","caller":"traceutil/trace.go:171","msg":"trace[151272209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"323.093382ms","start":"2026-02-07T02:24:00.415292Z","end":"2026-02-07T02:24:00.738385Z","steps":["trace[151272209] 'agreement among raft nodes before linearized reading' (duration: 323.03237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:00.738434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:00.415286Z","time spent":"323.129824ms","remote":"127.0.0.1:36838","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:00.738573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.612555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-07T02:24:00.738606Z","caller":"traceutil/trace.go:171","msg":"trace[582513876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"324.686688ms","start":"2026-02-07T02:24:00.413911Z","end":"2026-02-07T02:24:00.738597Z","steps":["trace[582513876] 'agreement among raft nodes before linearized reading' (duration: 324.629986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:00.738641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:24:00.413887Z","time spent":"324.748421ms","remote":"127.0.0.1:36838","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:04.146041Z","caller":"traceutil/trace.go:171","msg":"trace[1487447127] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"165.981111ms","start":"2026-02-07T02:24:03.977585Z","end":"2026-02-07T02:24:04.143566Z","steps":["trace[1487447127] 'process raft request' (duration: 162.23864ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:04.147892Z","caller":"traceutil/trace.go:171","msg":"trace[1906446555] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"169.940399ms","start":"2026-02-07T02:24:03.977928Z","end":"2026-02-07T02:24:04.147869Z","steps":["trace[1906446555] 'process raft request' (duration: 169.490003ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:04.148144Z","caller":"traceutil/trace.go:171","msg":"trace[658309227] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"163.256895ms","start":"2026-02-07T02:24:03.984881Z","end":"2026-02-07T02:24:04.148138Z","steps":["trace[658309227] 'process raft request' (duration: 162.656825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:06.431654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.587366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-07T02:24:06.431754Z","caller":"traceutil/trace.go:171","msg":"trace[1103531969] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:648; }","duration":"166.746743ms","start":"2026-02-07T02:24:06.264986Z","end":"2026-02-07T02:24:06.431733Z","steps":["trace[1103531969] 'range keys from in-memory index tree' (duration: 166.38788ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:08.976314Z","caller":"traceutil/trace.go:171","msg":"trace[925344551] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"120.742929ms","start":"2026-02-07T02:24:08.855535Z","end":"2026-02-07T02:24:08.976278Z","steps":["trace[925344551] 'process raft request' (duration: 84.486462ms)","trace[925344551] 'compare' (duration: 36.084821ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:09.005056Z","caller":"traceutil/trace.go:171","msg":"trace[1500350677] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:692; }","duration":"116.297786ms","start":"2026-02-07T02:24:08.88873Z","end":"2026-02-07T02:24:09.005028Z","steps":["trace[1500350677] 'read index received' (duration: 51.305915ms)","trace[1500350677] 'applied index is now lower than readState.Index' (duration: 64.990641ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:09.005751Z","caller":"traceutil/trace.go:171","msg":"trace[1853362246] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"149.890448ms","start":"2026-02-07T02:24:08.855845Z","end":"2026-02-07T02:24:09.005736Z","steps":["trace[1853362246] 'process raft request' (duration: 149.047819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:09.00646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.703604ms","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:09.006678Z","caller":"traceutil/trace.go:171","msg":"trace[1897517159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:668; }","duration":"117.967193ms","start":"2026-02-07T02:24:08.888697Z","end":"2026-02-07T02:24:09.006664Z","steps":["trace[1897517159] 'agreement among raft nodes before linearized reading' (duration: 117.618441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:09.007278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.084842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T02:24:09.007424Z","caller":"traceutil/trace.go:171","msg":"trace[154808782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:668; }","duration":"117.184346ms","start":"2026-02-07T02:24:08.890166Z","end":"2026-02-07T02:24:09.007351Z","steps":["trace[154808782] 'agreement among raft nodes before linearized reading' (duration: 117.032881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:09.014193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.190152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-07T02:24:09.014428Z","caller":"traceutil/trace.go:171","msg":"trace[975017832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:668; }","duration":"104.44848ms","start":"2026-02-07T02:24:08.909965Z","end":"2026-02-07T02:24:09.014413Z","steps":["trace[975017832] 'agreement among raft nodes before linearized reading' (duration: 104.049066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:09.014751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.199177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-07T02:24:09.014882Z","caller":"traceutil/trace.go:171","msg":"trace[805833126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:668; }","duration":"105.342321ms","start":"2026-02-07T02:24:08.909531Z","end":"2026-02-07T02:24:09.014873Z","steps":["trace[805833126] 'agreement among raft nodes before linearized reading' (duration: 105.168005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:09.01517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.734015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:24:09.015266Z","caller":"traceutil/trace.go:171","msg":"trace[1630075102] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:668; }","duration":"107.809618ms","start":"2026-02-07T02:24:08.907418Z","end":"2026-02-07T02:24:09.015228Z","steps":["trace[1630075102] 'agreement among raft nodes before linearized reading' (duration: 107.709994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:09.015513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.089438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:24:09.015584Z","caller":"traceutil/trace.go:171","msg":"trace[1532655038] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:668; }","duration":"108.179252ms","start":"2026-02-07T02:24:08.907396Z","end":"2026-02-07T02:24:09.015575Z","steps":["trace[1532655038] 'agreement among raft nodes before linearized reading' (duration: 108.086308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:09.015826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.412199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:24:09.015965Z","caller":"traceutil/trace.go:171","msg":"trace[870688509] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:668; }","duration":"108.604206ms","start":"2026-02-07T02:24:08.907352Z","end":"2026-02-07T02:24:09.015956Z","steps":["trace[870688509] 'agreement among raft nodes before linearized reading' (duration: 108.418889ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:09.353628Z","caller":"traceutil/trace.go:171","msg":"trace[1939577686] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"114.99252ms","start":"2026-02-07T02:24:09.238607Z","end":"2026-02-07T02:24:09.3536Z","steps":["trace[1939577686] 'process raft request' (duration: 114.812054ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:09.449306Z","caller":"traceutil/trace.go:171","msg":"trace[419748466] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"167.943021ms","start":"2026-02-07T02:24:09.281349Z","end":"2026-02-07T02:24:09.449292Z","steps":["trace[419748466] 'process raft request' (duration: 167.662891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:09.449715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.465676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:24:09.449846Z","caller":"traceutil/trace.go:171","msg":"trace[403110612] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:683; }","duration":"155.547889ms","start":"2026-02-07T02:24:09.294193Z","end":"2026-02-07T02:24:09.449741Z","steps":["trace[403110612] 'agreement among raft nodes before linearized reading' (duration: 155.473247ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:09.451368Z","caller":"traceutil/trace.go:171","msg":"trace[512787194] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"166.821002ms","start":"2026-02-07T02:24:09.284528Z","end":"2026-02-07T02:24:09.451349Z","steps":["trace[512787194] 'process raft request' (duration: 164.595264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:09.451729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.185689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:24:09.451824Z","caller":"traceutil/trace.go:171","msg":"trace[1244350158] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:683; }","duration":"186.286453ms","start":"2026-02-07T02:24:09.265466Z","end":"2026-02-07T02:24:09.451752Z","steps":["trace[1244350158] 'agreement among raft nodes before linearized reading' (duration: 184.579444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:09.453373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.114904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:24:09.453404Z","caller":"traceutil/trace.go:171","msg":"trace[829587698] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:683; }","duration":"159.153895ms","start":"2026-02-07T02:24:09.294242Z","end":"2026-02-07T02:24:09.453396Z","steps":["trace[829587698] 'agreement among raft nodes before linearized reading' (duration: 159.109533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:09.461765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.476926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:24:09.461901Z","caller":"traceutil/trace.go:171","msg":"trace[1956674357] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:683; }","duration":"167.536447ms","start":"2026-02-07T02:24:09.294257Z","end":"2026-02-07T02:24:09.461793Z","steps":["trace[1956674357] 'agreement among raft nodes before linearized reading' (duration: 167.424474ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:09.584991Z","caller":"traceutil/trace.go:171","msg":"trace[429219369] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"118.530473ms","start":"2026-02-07T02:24:09.466426Z","end":"2026-02-07T02:24:09.584956Z","steps":["trace[429219369] 'process raft request' (duration: 83.962115ms)","trace[429219369] 'compare' (duration: 34.461245ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:09.668989Z","caller":"traceutil/trace.go:171","msg":"trace[727023335] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"188.172249ms","start":"2026-02-07T02:24:09.480794Z","end":"2026-02-07T02:24:09.668966Z","steps":["trace[727023335] 'process raft request' (duration: 187.709193ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:09.86596Z","caller":"traceutil/trace.go:171","msg":"trace[931379417] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"109.004951ms","start":"2026-02-07T02:24:09.756923Z","end":"2026-02-07T02:24:09.865928Z","steps":["trace[931379417] 'process raft request' (duration: 108.362249ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:10.678858Z","caller":"traceutil/trace.go:171","msg":"trace[850867684] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"145.283082ms","start":"2026-02-07T02:24:10.533542Z","end":"2026-02-07T02:24:10.678825Z","steps":["trace[850867684] 'process raft request' (duration: 145.125866ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:10.682516Z","caller":"traceutil/trace.go:171","msg":"trace[482061736] transaction","detail":"{read_only:false; number_of_response:0; response_revision:689; }","duration":"146.763774ms","start":"2026-02-07T02:24:10.535724Z","end":"2026-02-07T02:24:10.682488Z","steps":["trace[482061736] 'process raft request' (duration: 146.681951ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:12.791249Z","caller":"traceutil/trace.go:171","msg":"trace[1118573709] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"162.603421ms","start":"2026-02-07T02:24:12.628628Z","end":"2026-02-07T02:24:12.791231Z","steps":["trace[1118573709] 'process raft request' (duration: 162.550629ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:12.795225Z","caller":"traceutil/trace.go:171","msg":"trace[1017486932] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"258.280701ms","start":"2026-02-07T02:24:12.534722Z","end":"2026-02-07T02:24:12.793003Z","steps":["trace[1017486932] 'process raft request' (duration: 256.064303ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:17.213241Z","caller":"traceutil/trace.go:171","msg":"trace[1735027798] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"106.9302ms","start":"2026-02-07T02:24:17.106295Z","end":"2026-02-07T02:24:17.213225Z","steps":["trace[1735027798] 'process raft request' (duration: 106.671941ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:17.213785Z","caller":"traceutil/trace.go:171","msg":"trace[1313156640] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"107.414866ms","start":"2026-02-07T02:24:17.106348Z","end":"2026-02-07T02:24:17.213763Z","steps":["trace[1313156640] 'process raft request' (duration: 106.682901ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:17.340871Z","caller":"traceutil/trace.go:171","msg":"trace[6724306] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"118.485805ms","start":"2026-02-07T02:24:17.222361Z","end":"2026-02-07T02:24:17.340847Z","steps":["trace[6724306] 'process raft request' (duration: 92.498204ms)","trace[6724306] 'compare' (duration: 25.359759ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:17.341294Z","caller":"traceutil/trace.go:171","msg":"trace[1706905567] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"116.650531ms","start":"2026-02-07T02:24:17.224638Z","end":"2026-02-07T02:24:17.341288Z","steps":["trace[1706905567] 'process raft request' (duration: 116.155713ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:17.341531Z","caller":"traceutil/trace.go:171","msg":"trace[379553461] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"116.536527ms","start":"2026-02-07T02:24:17.224988Z","end":"2026-02-07T02:24:17.341525Z","steps":["trace[379553461] 'process raft request' (duration: 116.149974ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:17.341652Z","caller":"traceutil/trace.go:171","msg":"trace[1709736731] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"102.838216ms","start":"2026-02-07T02:24:17.238808Z","end":"2026-02-07T02:24:17.341647Z","steps":["trace[1709736731] 'process raft request' (duration: 102.35902ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:17.341817Z","caller":"traceutil/trace.go:171","msg":"trace[784547086] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"101.400506ms","start":"2026-02-07T02:24:17.240412Z","end":"2026-02-07T02:24:17.341812Z","steps":["trace[784547086] 'process raft request' (duration: 101.024783ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:23.572666Z","caller":"traceutil/trace.go:171","msg":"trace[794878233] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"206.152934ms","start":"2026-02-07T02:24:23.366486Z","end":"2026-02-07T02:24:23.572639Z","steps":["trace[794878233] 'read index received' (duration: 205.931046ms)","trace[794878233] 'applied index is now lower than readState.Index' (duration: 220.858µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:23.573098Z","caller":"traceutil/trace.go:171","msg":"trace[1953024258] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"206.785746ms","start":"2026-02-07T02:24:23.366301Z","end":"2026-02-07T02:24:23.573086Z","steps":["trace[1953024258] 'process raft request' (duration: 206.184865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:23.57337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.865419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T02:24:23.573402Z","caller":"traceutil/trace.go:171","msg":"trace[1344699236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:790; }","duration":"206.927381ms","start":"2026-02-07T02:24:23.366465Z","end":"2026-02-07T02:24:23.573393Z","steps":["trace[1344699236] 'agreement among raft nodes before linearized reading' (duration: 206.799736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:23.573568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.185348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-07T02:24:23.573597Z","caller":"traceutil/trace.go:171","msg":"trace[1195309242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"191.23687ms","start":"2026-02-07T02:24:23.38235Z","end":"2026-02-07T02:24:23.573587Z","steps":["trace[1195309242] 'agreement among raft nodes before linearized reading' (duration: 191.165977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:24:23.573803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.888182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-07T02:24:23.573829Z","caller":"traceutil/trace.go:171","msg":"trace[1747651365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"189.934823ms","start":"2026-02-07T02:24:23.383886Z","end":"2026-02-07T02:24:23.573821Z","steps":["trace[1747651365] 'agreement among raft nodes before linearized reading' (duration: 189.86056ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:38.763011Z","caller":"traceutil/trace.go:171","msg":"trace[2085618047] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"163.915537ms","start":"2026-02-07T02:24:38.59908Z","end":"2026-02-07T02:24:38.762995Z","steps":["trace[2085618047] 'process raft request' (duration: 163.814644ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:24:55.917612Z","caller":"traceutil/trace.go:171","msg":"trace[1175687679] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"128.391485ms","start":"2026-02-07T02:24:55.789197Z","end":"2026-02-07T02:24:55.917588Z","steps":["trace[1175687679] 'process raft request' (duration: 112.6412ms)","trace[1175687679] 'compare' (duration: 15.261661ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:24:56.047534Z","caller":"traceutil/trace.go:171","msg":"trace[977774618] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"125.991903ms","start":"2026-02-07T02:24:55.921511Z","end":"2026-02-07T02:24:56.047502Z","steps":["trace[977774618] 'process raft request' (duration: 113.534612ms)","trace[977774618] 'compare' (duration: 12.333658ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:25:19.146284Z","caller":"traceutil/trace.go:171","msg":"trace[1702999398] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"117.502739ms","start":"2026-02-07T02:25:19.028759Z","end":"2026-02-07T02:25:19.146262Z","steps":["trace[1702999398] 'process raft request' (duration: 117.339366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:25:34.295915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.098448ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-07T02:25:34.296265Z","caller":"traceutil/trace.go:171","msg":"trace[578423136] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"231.83275ms","start":"2026-02-07T02:25:34.064417Z","end":"2026-02-07T02:25:34.29625Z","steps":["trace[578423136] 'read index received' (duration: 52.331886ms)","trace[578423136] 'applied index is now lower than readState.Index' (duration: 179.499884ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:25:34.296629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.193988ms","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:25:34.296753Z","caller":"traceutil/trace.go:171","msg":"trace[1583181543] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1028; }","duration":"232.341594ms","start":"2026-02-07T02:25:34.06436Z","end":"2026-02-07T02:25:34.296702Z","steps":["trace[1583181543] 'agreement among raft nodes before linearized reading' (duration: 232.13298ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:25:34.495328Z","caller":"traceutil/trace.go:171","msg":"trace[748700862] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"193.306781ms","start":"2026-02-07T02:25:34.302001Z","end":"2026-02-07T02:25:34.495308Z","steps":["trace[748700862] 'process raft request' (duration: 112.044745ms)","trace[748700862] 'compare' (duration: 81.092552ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:25:34.495538Z","caller":"traceutil/trace.go:171","msg":"trace[1809292259] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"191.064087ms","start":"2026-02-07T02:25:34.304467Z","end":"2026-02-07T02:25:34.495531Z","steps":["trace[1809292259] 'process raft request' (duration: 190.810516ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:25:34.495693Z","caller":"traceutil/trace.go:171","msg":"trace[500039553] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1076; }","duration":"124.516108ms","start":"2026-02-07T02:25:34.37117Z","end":"2026-02-07T02:25:34.495686Z","steps":["trace[500039553] 'read index received' (duration: 42.740188ms)","trace[500039553] 'applied index is now lower than readState.Index' (duration: 81.7753ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:25:34.49577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.585685ms","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:25:34.49579Z","caller":"traceutil/trace.go:171","msg":"trace[362997765] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1032; }","duration":"124.639093ms","start":"2026-02-07T02:25:34.371145Z","end":"2026-02-07T02:25:34.495784Z","steps":["trace[362997765] 'agreement among raft nodes before linearized reading' (duration: 124.562996ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:25:34.495954Z","caller":"traceutil/trace.go:171","msg":"trace[1506967095] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"190.584453ms","start":"2026-02-07T02:25:34.305363Z","end":"2026-02-07T02:25:34.495948Z","steps":["trace[1506967095] 'process raft request' (duration: 190.141087ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:25:35.157345Z","caller":"traceutil/trace.go:171","msg":"trace[1974569172] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"206.409702ms","start":"2026-02-07T02:25:34.950914Z","end":"2026-02-07T02:25:35.157324Z","steps":["trace[1974569172] 'process raft request' (duration: 206.054584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:25:35.649884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.559082ms","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:25:35.650215Z","caller":"traceutil/trace.go:171","msg":"trace[1193790219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1033; }","duration":"314.90871ms","start":"2026-02-07T02:25:35.335288Z","end":"2026-02-07T02:25:35.650197Z","steps":["trace[1193790219] 'range keys from in-memory index tree' (duration: 314.429687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:25:35.650375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:25:35.335253Z","time spent":"315.082965ms","remote":"127.0.0.1:36838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-07T02:25:40.538715Z","caller":"traceutil/trace.go:171","msg":"trace[944858497] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"120.055038ms","start":"2026-02-07T02:25:40.418608Z","end":"2026-02-07T02:25:40.538663Z","steps":["trace[944858497] 'process raft request' (duration: 119.834975ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:25:52.953677Z","caller":"traceutil/trace.go:171","msg":"trace[576582552] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"161.073517ms","start":"2026-02-07T02:25:52.79259Z","end":"2026-02-07T02:25:52.953663Z","steps":["trace[576582552] 'process raft request' (duration: 161.049667ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:25:52.953851Z","caller":"traceutil/trace.go:171","msg":"trace[1382865505] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"161.507725ms","start":"2026-02-07T02:25:52.792321Z","end":"2026-02-07T02:25:52.953829Z","steps":["trace[1382865505] 'process raft request' (duration: 158.63696ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:26:09.203867Z","caller":"traceutil/trace.go:171","msg":"trace[865617673] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"108.252605ms","start":"2026-02-07T02:26:09.095595Z","end":"2026-02-07T02:26:09.203847Z","steps":["trace[865617673] 'process raft request' (duration: 96.504364ms)","trace[865617673] 'compare' (duration: 11.166132ms)"],"step_count":2}