{"level":"warn","ts":"2026-09-15T04:40:07.803238Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-15T04:40:07.80334Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.76:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.76:2380","--initial-cluster=instance=https://162.253.55.76:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.76:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.76: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-09-15T04:40:07.803414Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-15T04:40:07.803429Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.76:2380"]} {"level":"info","ts":"2026-09-15T04:40:07.803454Z","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-09-15T04:40:07.804117Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.76:2379"]} {"level":"info","ts":"2026-09-15T04:40:07.804207Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.76:2380"],"listen-peer-urls":["https://162.253.55.76:2380"],"advertise-client-urls":["https://162.253.55.76:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.76:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.76: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-09-15T04:40:07.808487Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.051365ms"} {"level":"info","ts":"2026-09-15T04:40:07.81523Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30ad0c2b6ecdd25e","cluster-id":"855d190e6ef0a61f"} {"level":"info","ts":"2026-09-15T04:40:07.815374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e switched to configuration voters=()"} {"level":"info","ts":"2026-09-15T04:40:07.8154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became follower at term 0"} {"level":"info","ts":"2026-09-15T04:40:07.815411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30ad0c2b6ecdd25e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-15T04:40:07.815433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became follower at term 1"} {"level":"info","ts":"2026-09-15T04:40:07.815477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e switched to configuration voters=(3507473065473593950)"} {"level":"warn","ts":"2026-09-15T04:40:07.822117Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-15T04:40:07.825071Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-15T04:40:07.826352Z","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-09-15T04:40:07.828477Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30ad0c2b6ecdd25e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-15T04:40:07.828696Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30ad0c2b6ecdd25e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-15T04:40:07.828889Z","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-09-15T04:40:07.828961Z","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-09-15T04:40:07.82897Z","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-09-15T04:40:07.831426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e switched to configuration voters=(3507473065473593950)"} {"level":"info","ts":"2026-09-15T04:40:07.831642Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"855d190e6ef0a61f","local-member-id":"30ad0c2b6ecdd25e","added-peer-id":"30ad0c2b6ecdd25e","added-peer-peer-urls":["https://162.253.55.76:2380"]} {"level":"info","ts":"2026-09-15T04:40:07.832703Z","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-09-15T04:40:07.832916Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.76:2380"} {"level":"info","ts":"2026-09-15T04:40:07.832997Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.76:2380"} {"level":"info","ts":"2026-09-15T04:40:07.833114Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30ad0c2b6ecdd25e","initial-advertise-peer-urls":["https://162.253.55.76:2380"],"listen-peer-urls":["https://162.253.55.76:2380"],"advertise-client-urls":["https://162.253.55.76:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.76:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-15T04:40:07.83316Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-15T04:40:08.115923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e is starting a new election at term 1"} {"level":"info","ts":"2026-09-15T04:40:08.116035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became pre-candidate at term 1"} {"level":"info","ts":"2026-09-15T04:40:08.116081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e received MsgPreVoteResp from 30ad0c2b6ecdd25e at term 1"} {"level":"info","ts":"2026-09-15T04:40:08.1161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became candidate at term 2"} {"level":"info","ts":"2026-09-15T04:40:08.11611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e received MsgVoteResp from 30ad0c2b6ecdd25e at term 2"} {"level":"info","ts":"2026-09-15T04:40:08.116123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became leader at term 2"} {"level":"info","ts":"2026-09-15T04:40:08.116134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30ad0c2b6ecdd25e elected leader 30ad0c2b6ecdd25e at term 2"} {"level":"info","ts":"2026-09-15T04:40:08.117231Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30ad0c2b6ecdd25e","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.76:2379]}","request-path":"/0/members/30ad0c2b6ecdd25e/attributes","cluster-id":"855d190e6ef0a61f","publish-timeout":"7s"} {"level":"info","ts":"2026-09-15T04:40:08.117476Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T04:40:08.117493Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T04:40:08.117573Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T04:40:08.118305Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"855d190e6ef0a61f","local-member-id":"30ad0c2b6ecdd25e","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T04:40:08.118379Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T04:40:08.118401Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T04:40:08.120356Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-15T04:40:08.120432Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-15T04:40:08.120731Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.76:2379"} {"level":"info","ts":"2026-09-15T04:40:08.122074Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-15T04:40:16.80559Z","caller":"traceutil/trace.go:171","msg":"trace[181381890] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"133.534825ms","start":"2026-09-15T04:40:16.672036Z","end":"2026-09-15T04:40:16.805571Z","steps":["trace[181381890] 'process raft request' (duration: 133.409842ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:17.158279Z","caller":"traceutil/trace.go:171","msg":"trace[1002858351] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"332.125367ms","start":"2026-09-15T04:40:16.826129Z","end":"2026-09-15T04:40:17.158254Z","steps":["trace[1002858351] 'read index received' (duration: 331.925162ms)","trace[1002858351] 'applied index is now lower than readState.Index' (duration: 199.165µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:40:17.158406Z","caller":"traceutil/trace.go:171","msg":"trace[1884991053] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"332.277672ms","start":"2026-09-15T04:40:16.82608Z","end":"2026-09-15T04:40:17.158358Z","steps":["trace[1884991053] 'process raft request' (duration: 332.047676ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:40:17.159103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.948999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-09-15T04:40:17.159233Z","caller":"traceutil/trace.go:171","msg":"trace[304083673] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:305; }","duration":"333.098292ms","start":"2026-09-15T04:40:16.826093Z","end":"2026-09-15T04:40:17.159191Z","steps":["trace[304083673] 'agreement among raft nodes before linearized reading' (duration: 332.358273ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:40:17.159299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:40:16.826082Z","time spent":"333.201076ms","remote":"127.0.0.1:52278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-15T04:40:17.159634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.913887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"warn","ts":"2026-09-15T04:40:17.159787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.321478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-09-15T04:40:17.159818Z","caller":"traceutil/trace.go:171","msg":"trace[1315352793] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:305; }","duration":"333.117113ms","start":"2026-09-15T04:40:16.826684Z","end":"2026-09-15T04:40:17.159801Z","steps":["trace[1315352793] 'agreement among raft nodes before linearized reading' (duration: 332.677932ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:40:17.159902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:40:16.826674Z","time spent":"333.217375ms","remote":"127.0.0.1:52278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-09-15T04:40:17.159969Z","caller":"traceutil/trace.go:171","msg":"trace[385464521] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:305; }","duration":"190.497773ms","start":"2026-09-15T04:40:16.969372Z","end":"2026-09-15T04:40:17.15987Z","steps":["trace[385464521] 'agreement among raft nodes before linearized reading' (duration: 190.029661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:40:17.160082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.998369ms","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-09-15T04:40:17.160146Z","caller":"traceutil/trace.go:171","msg":"trace[1686119567] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:305; }","duration":"241.057441ms","start":"2026-09-15T04:40:16.919073Z","end":"2026-09-15T04:40:17.160131Z","steps":["trace[1686119567] 'agreement among raft nodes before linearized reading' (duration: 240.777393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:40:17.159788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.932322ms","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-09-15T04:40:17.160595Z","caller":"traceutil/trace.go:171","msg":"trace[1264583545] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:305; }","duration":"291.759714ms","start":"2026-09-15T04:40:16.868817Z","end":"2026-09-15T04:40:17.160577Z","steps":["trace[1264583545] 'agreement among raft nodes before linearized reading' (duration: 290.591694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:40:17.160459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.645217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-09-15T04:40:17.160896Z","caller":"traceutil/trace.go:171","msg":"trace[23837235] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:305; }","duration":"334.137079ms","start":"2026-09-15T04:40:16.826717Z","end":"2026-09-15T04:40:17.160854Z","steps":["trace[23837235] 'agreement among raft nodes before linearized reading' (duration: 333.362559ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:40:17.160953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:40:16.826705Z","time spent":"334.233871ms","remote":"127.0.0.1:52278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-15T04:40:17.16116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.265667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-15T04:40:17.161205Z","caller":"traceutil/trace.go:171","msg":"trace[516348836] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:305; }","duration":"142.456322ms","start":"2026-09-15T04:40:17.018736Z","end":"2026-09-15T04:40:17.161193Z","steps":["trace[516348836] 'agreement among raft nodes before linearized reading' (duration: 142.163284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:40:17.161321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:40:16.826069Z","time spent":"332.417645ms","remote":"127.0.0.1:52274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T04:40:17.273734Z","caller":"traceutil/trace.go:171","msg":"trace[1306816027] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"102.341505ms","start":"2026-09-15T04:40:17.171363Z","end":"2026-09-15T04:40:17.273705Z","steps":["trace[1306816027] 'process raft request' (duration: 94.247426ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:17.278348Z","caller":"traceutil/trace.go:171","msg":"trace[1218835085] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"102.291344ms","start":"2026-09-15T04:40:17.176002Z","end":"2026-09-15T04:40:17.278293Z","steps":["trace[1218835085] 'process raft request' (duration: 101.845782ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:17.278827Z","caller":"traceutil/trace.go:171","msg":"trace[999025022] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"105.076636ms","start":"2026-09-15T04:40:17.173718Z","end":"2026-09-15T04:40:17.278795Z","steps":["trace[999025022] 'process raft request' (duration: 104.06461ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:45.942799Z","caller":"traceutil/trace.go:171","msg":"trace[1975570761] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"159.646927ms","start":"2026-09-15T04:40:45.783128Z","end":"2026-09-15T04:40:45.942775Z","steps":["trace[1975570761] 'process raft request' (duration: 130.796791ms)","trace[1975570761] 'compare' (duration: 28.672192ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:40:46.020098Z","caller":"traceutil/trace.go:171","msg":"trace[2085619382] transaction","detail":"{read_only:false; number_of_response:1; response_revision:506; }","duration":"236.733526ms","start":"2026-09-15T04:40:45.783328Z","end":"2026-09-15T04:40:46.020062Z","steps":["trace[2085619382] 'process raft request' (duration: 236.486811ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:46.020261Z","caller":"traceutil/trace.go:171","msg":"trace[1349139758] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"227.171332ms","start":"2026-09-15T04:40:45.793045Z","end":"2026-09-15T04:40:46.020217Z","steps":["trace[1349139758] 'process raft request' (duration: 226.856236ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:46.020292Z","caller":"traceutil/trace.go:171","msg":"trace[1130667334] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"217.495336ms","start":"2026-09-15T04:40:45.802771Z","end":"2026-09-15T04:40:46.020266Z","steps":["trace[1130667334] 'process raft request' (duration: 217.276592ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:46.020296Z","caller":"traceutil/trace.go:171","msg":"trace[1244194523] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"215.355783ms","start":"2026-09-15T04:40:45.804916Z","end":"2026-09-15T04:40:46.020272Z","steps":["trace[1244194523] 'process raft request' (duration: 215.179989ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:46.020308Z","caller":"traceutil/trace.go:171","msg":"trace[137129918] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"224.206182ms","start":"2026-09-15T04:40:45.796079Z","end":"2026-09-15T04:40:46.020285Z","steps":["trace[137129918] 'process raft request' (duration: 223.882235ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:46.020381Z","caller":"traceutil/trace.go:171","msg":"trace[930439164] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"222.353284ms","start":"2026-09-15T04:40:45.79801Z","end":"2026-09-15T04:40:46.020363Z","steps":["trace[930439164] 'process raft request' (duration: 221.980567ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:46.020418Z","caller":"traceutil/trace.go:171","msg":"trace[1263688432] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"213.466114ms","start":"2026-09-15T04:40:45.806945Z","end":"2026-09-15T04:40:46.020411Z","steps":["trace[1263688432] 'process raft request' (duration: 213.187769ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:46.020422Z","caller":"traceutil/trace.go:171","msg":"trace[392165156] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"114.183222ms","start":"2026-09-15T04:40:45.906231Z","end":"2026-09-15T04:40:46.020414Z","steps":["trace[392165156] 'process raft request' (duration: 113.959228ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:46.020292Z","caller":"traceutil/trace.go:171","msg":"trace[1252472316] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:526; }","duration":"234.027381ms","start":"2026-09-15T04:40:45.786249Z","end":"2026-09-15T04:40:46.020276Z","steps":["trace[1252472316] 'read index received' (duration: 127.763399ms)","trace[1252472316] 'applied index is now lower than readState.Index' (duration: 106.263242ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:40:46.020449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.182245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-09-15T04:40:46.020484Z","caller":"traceutil/trace.go:171","msg":"trace[1977075281] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:513; }","duration":"234.243476ms","start":"2026-09-15T04:40:45.78623Z","end":"2026-09-15T04:40:46.020474Z","steps":["trace[1977075281] 'agreement among raft nodes before linearized reading' (duration: 234.099923ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:52.290756Z","caller":"traceutil/trace.go:171","msg":"trace[591636256] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"108.20663ms","start":"2026-09-15T04:40:52.182519Z","end":"2026-09-15T04:40:52.290725Z","steps":["trace[591636256] 'process raft request' (duration: 106.940276ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:52.844423Z","caller":"traceutil/trace.go:171","msg":"trace[1055415943] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"545.570545ms","start":"2026-09-15T04:40:52.298828Z","end":"2026-09-15T04:40:52.844398Z","steps":["trace[1055415943] 'process raft request' (duration: 514.741212ms)","trace[1055415943] 'compare' (duration: 30.284682ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:40:52.84444Z","caller":"traceutil/trace.go:171","msg":"trace[18552944] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"543.880002ms","start":"2026-09-15T04:40:52.300549Z","end":"2026-09-15T04:40:52.844429Z","steps":["trace[18552944] 'process raft request' (duration: 543.680448ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:52.844378Z","caller":"traceutil/trace.go:171","msg":"trace[1898559345] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"543.564036ms","start":"2026-09-15T04:40:52.300792Z","end":"2026-09-15T04:40:52.844356Z","steps":["trace[1898559345] 'process raft request' (duration: 543.511525ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:40:52.844898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:40:52.298811Z","time spent":"545.676596ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T04:40:52.845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:40:52.300523Z","time spent":"543.967793ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T04:40:52.845211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:40:52.300779Z","time spent":"543.913063ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T04:40:52.845529Z","caller":"traceutil/trace.go:171","msg":"trace[1546102656] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:611; }","duration":"184.001155ms","start":"2026-09-15T04:40:52.661499Z","end":"2026-09-15T04:40:52.8455Z","steps":["trace[1546102656] 'read index received' (duration: 152.235145ms)","trace[1546102656] 'applied index is now lower than readState.Index' (duration: 31.76524ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:40:52.845557Z","caller":"traceutil/trace.go:171","msg":"trace[907001597] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"478.622268ms","start":"2026-09-15T04:40:52.366919Z","end":"2026-09-15T04:40:52.845542Z","steps":["trace[907001597] 'process raft request' (duration: 478.477565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:40:52.846272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:40:52.366899Z","time spent":"478.72493ms","remote":"127.0.0.1:52310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T04:40:52.846502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.970855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j2f68\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-15T04:40:52.846554Z","caller":"traceutil/trace.go:171","msg":"trace[277403604] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j2f68; range_end:; response_count:1; response_revision:590; }","duration":"185.077746ms","start":"2026-09-15T04:40:52.661463Z","end":"2026-09-15T04:40:52.846541Z","steps":["trace[277403604] 'agreement among raft nodes before linearized reading' (duration: 184.151348ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:40:53.90966Z","caller":"traceutil/trace.go:171","msg":"trace[1138287225] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"113.355023ms","start":"2026-09-15T04:40:53.796274Z","end":"2026-09-15T04:40:53.909629Z","steps":["trace[1138287225] 'process raft request' (duration: 106.045273ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:41:09.917885Z","caller":"traceutil/trace.go:171","msg":"trace[1671581391] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"148.167744ms","start":"2026-09-15T04:41:09.769694Z","end":"2026-09-15T04:41:09.917861Z","steps":["trace[1671581391] 'process raft request' (duration: 147.982681ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:28.003656Z","caller":"traceutil/trace.go:171","msg":"trace[1556908080] linearizableReadLoop","detail":"{readStateIndex:1460; appliedIndex:1459; }","duration":"108.704795ms","start":"2026-09-15T04:43:27.894925Z","end":"2026-09-15T04:43:28.00363Z","steps":["trace[1556908080] 'read index received' (duration: 108.427372ms)","trace[1556908080] 'applied index is now lower than readState.Index' (duration: 276.503µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:28.003793Z","caller":"traceutil/trace.go:171","msg":"trace[1539965224] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"112.932331ms","start":"2026-09-15T04:43:27.890826Z","end":"2026-09-15T04:43:28.003758Z","steps":["trace[1539965224] 'process raft request' (duration: 112.634658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:28.003837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.892667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/envoy-gateway-system/\" range_end:\"/registry/daemonsets/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T04:43:28.003894Z","caller":"traceutil/trace.go:171","msg":"trace[314676819] range","detail":"{range_begin:/registry/daemonsets/envoy-gateway-system/; range_end:/registry/daemonsets/envoy-gateway-system0; response_count:0; response_revision:1400; }","duration":"109.008599ms","start":"2026-09-15T04:43:27.894863Z","end":"2026-09-15T04:43:28.003872Z","steps":["trace[314676819] 'agreement among raft nodes before linearized reading' (duration: 108.912738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:28.003983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.68843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/envoy-gateway-system/envoy-gateway-config\" ","response":"range_response_count:1 size:1550"} {"level":"info","ts":"2026-09-15T04:43:28.004037Z","caller":"traceutil/trace.go:171","msg":"trace[1589089916] range","detail":"{range_begin:/registry/configmaps/envoy-gateway-system/envoy-gateway-config; range_end:; response_count:1; response_revision:1400; }","duration":"103.77662ms","start":"2026-09-15T04:43:27.900251Z","end":"2026-09-15T04:43:28.004027Z","steps":["trace[1589089916] 'agreement among raft nodes before linearized reading' (duration: 103.619149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:28.004152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.840681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/\" range_end:\"/registry/events/default0\" limit:500 ","response":"range_response_count:18 size:12442"} {"level":"info","ts":"2026-09-15T04:43:28.004219Z","caller":"traceutil/trace.go:171","msg":"trace[867450521] range","detail":"{range_begin:/registry/events/default/; range_end:/registry/events/default0; response_count:18; response_revision:1400; }","duration":"103.954392ms","start":"2026-09-15T04:43:27.900251Z","end":"2026-09-15T04:43:28.004206Z","steps":["trace[867450521] 'agreement among raft nodes before linearized reading' (duration: 103.667409ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:29.531345Z","caller":"traceutil/trace.go:171","msg":"trace[1821706639] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"234.415311ms","start":"2026-09-15T04:43:29.296905Z","end":"2026-09-15T04:43:29.531321Z","steps":["trace[1821706639] 'process raft request' (duration: 234.140178ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:29.53134Z","caller":"traceutil/trace.go:171","msg":"trace[1624174479] linearizableReadLoop","detail":"{readStateIndex:1462; appliedIndex:1461; }","duration":"222.217067ms","start":"2026-09-15T04:43:29.309026Z","end":"2026-09-15T04:43:29.531243Z","steps":["trace[1624174479] 'read index received' (duration: 221.974255ms)","trace[1624174479] 'applied index is now lower than readState.Index' (duration: 241.542µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:29.53175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.502266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-node-lease/\" range_end:\"/registry/daemonsets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-15T04:43:29.53174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.689672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/envoy-gateway-system/\" range_end:\"/registry/services/specs/envoy-gateway-system0\" limit:500 ","response":"range_response_count:1 size:1912"} {"level":"info","ts":"2026-09-15T04:43:29.53183Z","caller":"traceutil/trace.go:171","msg":"trace[628757812] range","detail":"{range_begin:/registry/services/specs/envoy-gateway-system/; range_end:/registry/services/specs/envoy-gateway-system0; response_count:1; response_revision:1402; }","duration":"222.820794ms","start":"2026-09-15T04:43:29.308992Z","end":"2026-09-15T04:43:29.531812Z","steps":["trace[628757812] 'agreement among raft nodes before linearized reading' (duration: 222.53539ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:29.531832Z","caller":"traceutil/trace.go:171","msg":"trace[1440723625] range","detail":"{range_begin:/registry/daemonsets/kube-node-lease/; range_end:/registry/daemonsets/kube-node-lease0; response_count:0; response_revision:1402; }","duration":"196.612566ms","start":"2026-09-15T04:43:29.335187Z","end":"2026-09-15T04:43:29.5318Z","steps":["trace[1440723625] 'agreement among raft nodes before linearized reading' (duration: 196.498125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:29.531867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.901839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-15T04:43:29.531911Z","caller":"traceutil/trace.go:171","msg":"trace[2023263787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1402; }","duration":"175.97437ms","start":"2026-09-15T04:43:29.355923Z","end":"2026-09-15T04:43:29.531898Z","steps":["trace[2023263787] 'agreement among raft nodes before linearized reading' (duration: 175.829758ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:29.532392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.727391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:912"} {"level":"info","ts":"2026-09-15T04:43:29.532512Z","caller":"traceutil/trace.go:171","msg":"trace[1276456751] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1402; }","duration":"168.856932ms","start":"2026-09-15T04:43:29.363636Z","end":"2026-09-15T04:43:29.532493Z","steps":["trace[1276456751] 'agreement among raft nodes before linearized reading' (duration: 168.371307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:29.532924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.899369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding\" ","response":"range_response_count:1 size:1146"} {"level":"info","ts":"2026-09-15T04:43:29.533007Z","caller":"traceutil/trace.go:171","msg":"trace[1787870295] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding; range_end:; response_count:1; response_revision:1402; }","duration":"197.02896ms","start":"2026-09-15T04:43:29.335958Z","end":"2026-09-15T04:43:29.532986Z","steps":["trace[1787870295] 'agreement among raft nodes before linearized reading' (duration: 196.773018ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:30.009243Z","caller":"traceutil/trace.go:171","msg":"trace[1223429326] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"156.204493ms","start":"2026-09-15T04:43:29.853014Z","end":"2026-09-15T04:43:30.009218Z","steps":["trace[1223429326] 'process raft request' (duration: 132.811207ms)","trace[1223429326] 'compare' (duration: 23.277995ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:30.014924Z","caller":"traceutil/trace.go:171","msg":"trace[682707782] linearizableReadLoop","detail":"{readStateIndex:1466; appliedIndex:1464; }","duration":"136.170463ms","start":"2026-09-15T04:43:29.878733Z","end":"2026-09-15T04:43:30.014904Z","steps":["trace[682707782] 'read index received' (duration: 107.215077ms)","trace[682707782] 'applied index is now lower than readState.Index' (duration: 28.953956ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:30.015024Z","caller":"traceutil/trace.go:171","msg":"trace[1455949562] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"161.758763ms","start":"2026-09-15T04:43:29.853239Z","end":"2026-09-15T04:43:30.014998Z","steps":["trace[1455949562] 'process raft request' (duration: 161.552581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:30.015049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.297075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/kube-node-lease/\" range_end:\"/registry/networkpolicies/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T04:43:30.015193Z","caller":"traceutil/trace.go:171","msg":"trace[829236941] range","detail":"{range_begin:/registry/networkpolicies/kube-node-lease/; range_end:/registry/networkpolicies/kube-node-lease0; response_count:0; response_revision:1406; }","duration":"136.470306ms","start":"2026-09-15T04:43:29.878696Z","end":"2026-09-15T04:43:30.015166Z","steps":["trace[829236941] 'agreement among raft nodes before linearized reading' (duration: 136.305174ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:30.441936Z","caller":"traceutil/trace.go:171","msg":"trace[701501404] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"101.662313ms","start":"2026-09-15T04:43:30.340245Z","end":"2026-09-15T04:43:30.441908Z","steps":["trace[701501404] 'process raft request' (duration: 101.508582ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:31.650976Z","caller":"traceutil/trace.go:171","msg":"trace[1147420532] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1467; }","duration":"104.866797ms","start":"2026-09-15T04:43:31.546086Z","end":"2026-09-15T04:43:31.650953Z","steps":["trace[1147420532] 'read index received' (duration: 104.713056ms)","trace[1147420532] 'applied index is now lower than readState.Index' (duration: 152.471µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:31.651035Z","caller":"traceutil/trace.go:171","msg":"trace[1144159709] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"112.683244ms","start":"2026-09-15T04:43:31.538324Z","end":"2026-09-15T04:43:31.651008Z","steps":["trace[1144159709] 'process raft request' (duration: 112.434861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:31.651157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.036529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-09-15T04:43:31.651249Z","caller":"traceutil/trace.go:171","msg":"trace[1303298512] range","detail":"{range_begin:/registry/roles/kube-public/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1408; }","duration":"105.157311ms","start":"2026-09-15T04:43:31.546067Z","end":"2026-09-15T04:43:31.651224Z","steps":["trace[1303298512] 'agreement among raft nodes before linearized reading' (duration: 104.980029ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:32.074606Z","caller":"traceutil/trace.go:171","msg":"trace[1797359578] linearizableReadLoop","detail":"{readStateIndex:1472; appliedIndex:1471; }","duration":"190.228522ms","start":"2026-09-15T04:43:31.884354Z","end":"2026-09-15T04:43:32.074583Z","steps":["trace[1797359578] 'read index received' (duration: 189.985919ms)","trace[1797359578] 'applied index is now lower than readState.Index' (duration: 241.622µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:32.074663Z","caller":"traceutil/trace.go:171","msg":"trace[1189227123] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"217.468849ms","start":"2026-09-15T04:43:31.857175Z","end":"2026-09-15T04:43:32.074644Z","steps":["trace[1189227123] 'process raft request' (duration: 217.237687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:32.07479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.818619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-15T04:43:32.074849Z","caller":"traceutil/trace.go:171","msg":"trace[2018716849] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:1411; }","duration":"171.90887ms","start":"2026-09-15T04:43:31.90292Z","end":"2026-09-15T04:43:32.074829Z","steps":["trace[2018716849] 'agreement among raft nodes before linearized reading' (duration: 171.797679ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:32.074967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.022916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/local-path-storage/\" range_end:\"/registry/daemonsets/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-15T04:43:32.074795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.425264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:1 size:612"} {"level":"info","ts":"2026-09-15T04:43:32.075018Z","caller":"traceutil/trace.go:171","msg":"trace[722838788] range","detail":"{range_begin:/registry/daemonsets/local-path-storage/; range_end:/registry/daemonsets/local-path-storage0; response_count:0; response_revision:1411; }","duration":"166.118087ms","start":"2026-09-15T04:43:31.908885Z","end":"2026-09-15T04:43:32.075003Z","steps":["trace[722838788] 'agreement among raft nodes before linearized reading' (duration: 166.030616ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:32.075054Z","caller":"traceutil/trace.go:171","msg":"trace[1286798336] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:1; response_revision:1411; }","duration":"190.715656ms","start":"2026-09-15T04:43:31.884309Z","end":"2026-09-15T04:43:32.075025Z","steps":["trace[1286798336] 'agreement among raft nodes before linearized reading' (duration: 190.392533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:32.075055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.921177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 ","response":"range_response_count:10 size:65234"} {"level":"info","ts":"2026-09-15T04:43:32.075119Z","caller":"traceutil/trace.go:171","msg":"trace[2033814390] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:10; response_revision:1411; }","duration":"158.002218ms","start":"2026-09-15T04:43:31.917101Z","end":"2026-09-15T04:43:32.075103Z","steps":["trace[2033814390] 'agreement among raft nodes before linearized reading' (duration: 157.753795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:32.363855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.371264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T04:43:32.363998Z","caller":"traceutil/trace.go:171","msg":"trace[639308716] linearizableReadLoop","detail":"{readStateIndex:1473; appliedIndex:1472; }","duration":"282.808755ms","start":"2026-09-15T04:43:32.081173Z","end":"2026-09-15T04:43:32.363982Z","steps":["trace[639308716] 'read index received' (duration: 27.123076ms)","trace[639308716] 'applied index is now lower than readState.Index' (duration: 255.684589ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:32.364007Z","caller":"traceutil/trace.go:171","msg":"trace[971321260] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"283.754006ms","start":"2026-09-15T04:43:32.080231Z","end":"2026-09-15T04:43:32.363985Z","steps":["trace[971321260] 'process raft request' (duration: 28.036467ms)","trace[971321260] 'compare' (duration: 255.268182ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:32.364186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.173241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:1 size:612"} {"level":"info","ts":"2026-09-15T04:43:32.364219Z","caller":"traceutil/trace.go:171","msg":"trace[1866229797] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:1; response_revision:1413; }","duration":"177.241372ms","start":"2026-09-15T04:43:32.186968Z","end":"2026-09-15T04:43:32.364209Z","steps":["trace[1866229797] 'agreement among raft nodes before linearized reading' (duration: 177.159691ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:32.364211Z","caller":"traceutil/trace.go:171","msg":"trace[991170355] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"282.433032ms","start":"2026-09-15T04:43:32.081748Z","end":"2026-09-15T04:43:32.364181Z","steps":["trace[991170355] 'process raft request' (duration: 282.195328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:32.364324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.338578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T04:43:32.364356Z","caller":"traceutil/trace.go:171","msg":"trace[2009993867] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1413; }","duration":"111.403048ms","start":"2026-09-15T04:43:32.252944Z","end":"2026-09-15T04:43:32.364347Z","steps":["trace[2009993867] 'agreement among raft nodes before linearized reading' (duration: 111.331677ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:32.36439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.213251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-15T04:43:32.364426Z","caller":"traceutil/trace.go:171","msg":"trace[269738787] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:1413; }","duration":"283.286922ms","start":"2026-09-15T04:43:32.081129Z","end":"2026-09-15T04:43:32.364416Z","steps":["trace[269738787] 'agreement among raft nodes before linearized reading' (duration: 282.957327ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:32.364553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.203574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/\" range_end:\"/registry/deployments/local-path-storage0\" limit:500 ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-09-15T04:43:32.364605Z","caller":"traceutil/trace.go:171","msg":"trace[2004161108] range","detail":"{range_begin:/registry/deployments/local-path-storage/; range_end:/registry/deployments/local-path-storage0; response_count:1; response_revision:1413; }","duration":"115.293276ms","start":"2026-09-15T04:43:32.249296Z","end":"2026-09-15T04:43:32.36459Z","steps":["trace[2004161108] 'agreement among raft nodes before linearized reading' (duration: 114.984401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:32.606677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.879872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T04:43:32.606756Z","caller":"traceutil/trace.go:171","msg":"trace[308532438] linearizableReadLoop","detail":"{readStateIndex:1475; appliedIndex:1474; }","duration":"157.315658ms","start":"2026-09-15T04:43:32.449428Z","end":"2026-09-15T04:43:32.606744Z","steps":["trace[308532438] 'read index received' (duration: 54.325064ms)","trace[308532438] 'applied index is now lower than readState.Index' (duration: 102.989444ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:32.606818Z","caller":"traceutil/trace.go:171","msg":"trace[1553480857] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"159.972717ms","start":"2026-09-15T04:43:32.446835Z","end":"2026-09-15T04:43:32.606808Z","steps":["trace[1553480857] 'process raft request' (duration: 56.904852ms)","trace[1553480857] 'compare' (duration: 102.795961ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:32.606965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.803758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/local-path-storage/\" range_end:\"/registry/services/endpoints/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T04:43:32.607043Z","caller":"traceutil/trace.go:171","msg":"trace[2128667085] range","detail":"{range_begin:/registry/services/endpoints/local-path-storage/; range_end:/registry/services/endpoints/local-path-storage0; response_count:0; response_revision:1414; }","duration":"143.915089ms","start":"2026-09-15T04:43:32.463107Z","end":"2026-09-15T04:43:32.607022Z","steps":["trace[2128667085] 'agreement among raft nodes before linearized reading' (duration: 143.796257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:32.607104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.679353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"info","ts":"2026-09-15T04:43:32.607135Z","caller":"traceutil/trace.go:171","msg":"trace[1227532711] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:1414; }","duration":"157.736384ms","start":"2026-09-15T04:43:32.449391Z","end":"2026-09-15T04:43:32.607127Z","steps":["trace[1227532711] 'agreement among raft nodes before linearized reading' (duration: 157.681614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:32.60712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.226839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-09-15T04:43:32.607169Z","caller":"traceutil/trace.go:171","msg":"trace[1501966218] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:1414; }","duration":"120.30093ms","start":"2026-09-15T04:43:32.486853Z","end":"2026-09-15T04:43:32.607154Z","steps":["trace[1501966218] 'agreement among raft nodes before linearized reading' (duration: 120.206698ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:32.607347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.663628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 ","response":"range_response_count:11 size:7467"} {"level":"info","ts":"2026-09-15T04:43:32.607391Z","caller":"traceutil/trace.go:171","msg":"trace[1364058192] range","detail":"{range_begin:/registry/rolebindings/kube-system/; range_end:/registry/rolebindings/kube-system0; response_count:11; response_revision:1414; }","duration":"129.709059ms","start":"2026-09-15T04:43:32.477671Z","end":"2026-09-15T04:43:32.60738Z","steps":["trace[1364058192] 'agreement among raft nodes before linearized reading' (duration: 129.584497ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:34.216742Z","caller":"traceutil/trace.go:171","msg":"trace[1642980352] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1477; }","duration":"121.63044ms","start":"2026-09-15T04:43:34.095089Z","end":"2026-09-15T04:43:34.216719Z","steps":["trace[1642980352] 'read index received' (duration: 121.495274ms)","trace[1642980352] 'applied index is now lower than readState.Index' (duration: 134.036µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:34.216779Z","caller":"traceutil/trace.go:171","msg":"trace[1208395120] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"134.61889ms","start":"2026-09-15T04:43:34.082133Z","end":"2026-09-15T04:43:34.216752Z","steps":["trace[1208395120] 'process raft request' (duration: 134.451963ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:34.21701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.907173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-jgl9h\" ","response":"range_response_count:1 size:4690"} {"level":"info","ts":"2026-09-15T04:43:34.217058Z","caller":"traceutil/trace.go:171","msg":"trace[1548905419] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-jgl9h; range_end:; response_count:1; response_revision:1417; }","duration":"121.991226ms","start":"2026-09-15T04:43:34.095056Z","end":"2026-09-15T04:43:34.217047Z","steps":["trace[1548905419] 'agreement among raft nodes before linearized reading' (duration: 121.780497ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:34.707244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.015616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5154"} {"level":"info","ts":"2026-09-15T04:43:34.707318Z","caller":"traceutil/trace.go:171","msg":"trace[207969859] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1420; }","duration":"107.134672ms","start":"2026-09-15T04:43:34.600166Z","end":"2026-09-15T04:43:34.707301Z","steps":["trace[207969859] 'range keys from in-memory index tree' (duration: 106.87879ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:35.946146Z","caller":"traceutil/trace.go:171","msg":"trace[1271232334] linearizableReadLoop","detail":"{readStateIndex:1484; appliedIndex:1483; }","duration":"140.467458ms","start":"2026-09-15T04:43:35.805656Z","end":"2026-09-15T04:43:35.946123Z","steps":["trace[1271232334] 'read index received' (duration: 140.369554ms)","trace[1271232334] 'applied index is now lower than readState.Index' (duration: 97.024µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:35.946213Z","caller":"traceutil/trace.go:171","msg":"trace[374011597] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"160.620405ms","start":"2026-09-15T04:43:35.785568Z","end":"2026-09-15T04:43:35.946188Z","steps":["trace[374011597] 'process raft request' (duration: 160.381575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:35.947096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.40364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader\" ","response":"range_response_count:1 size:856"} {"level":"info","ts":"2026-09-15T04:43:35.947171Z","caller":"traceutil/trace.go:171","msg":"trace[2083191356] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader; range_end:; response_count:1; response_revision:1423; }","duration":"141.565387ms","start":"2026-09-15T04:43:35.805583Z","end":"2026-09-15T04:43:35.947149Z","steps":["trace[2083191356] 'agreement among raft nodes before linearized reading' (duration: 140.643176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:36.167106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.005209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:1 size:853"} {"level":"info","ts":"2026-09-15T04:43:36.167197Z","caller":"traceutil/trace.go:171","msg":"trace[103094806] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:1423; }","duration":"120.120595ms","start":"2026-09-15T04:43:36.047056Z","end":"2026-09-15T04:43:36.167177Z","steps":["trace[103094806] 'range keys from in-memory index tree' (duration: 119.924186ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:36.501161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.368189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:1 size:733"} {"level":"info","ts":"2026-09-15T04:43:36.501237Z","caller":"traceutil/trace.go:171","msg":"trace[404042476] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:cloud-provider; range_end:; response_count:1; response_revision:1424; }","duration":"128.485785ms","start":"2026-09-15T04:43:36.372732Z","end":"2026-09-15T04:43:36.501218Z","steps":["trace[404042476] 'range keys from in-memory index tree' (duration: 128.281416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:36.501433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.314064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"info","ts":"2026-09-15T04:43:36.50147Z","caller":"traceutil/trace.go:171","msg":"trace[1612169052] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1424; }","duration":"125.360877ms","start":"2026-09-15T04:43:36.376099Z","end":"2026-09-15T04:43:36.50146Z","steps":["trace[1612169052] 'range keys from in-memory index tree' (duration: 125.276713ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:36.686832Z","caller":"traceutil/trace.go:171","msg":"trace[847696972] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1486; }","duration":"179.837687ms","start":"2026-09-15T04:43:36.506963Z","end":"2026-09-15T04:43:36.686801Z","steps":["trace[847696972] 'read index received' (duration: 68.199627ms)","trace[847696972] 'applied index is now lower than readState.Index' (duration: 111.63635ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:36.686936Z","caller":"traceutil/trace.go:171","msg":"trace[1792405674] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"180.763999ms","start":"2026-09-15T04:43:36.506148Z","end":"2026-09-15T04:43:36.686912Z","steps":["trace[1792405674] 'process raft request' (duration: 97.347638ms)","trace[1792405674] 'compare' (duration: 82.964611ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:36.68704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.049877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"warn","ts":"2026-09-15T04:43:36.687097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.381947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:1 size:733"} {"level":"info","ts":"2026-09-15T04:43:36.6872Z","caller":"traceutil/trace.go:171","msg":"trace[386740826] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:cloud-provider; range_end:; response_count:1; response_revision:1426; }","duration":"179.518583ms","start":"2026-09-15T04:43:36.507662Z","end":"2026-09-15T04:43:36.687181Z","steps":["trace[386740826] 'agreement among raft nodes before linearized reading' (duration: 179.338725ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:36.687123Z","caller":"traceutil/trace.go:171","msg":"trace[1156872933] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1426; }","duration":"180.188142ms","start":"2026-09-15T04:43:36.506919Z","end":"2026-09-15T04:43:36.687107Z","steps":["trace[1156872933] 'agreement among raft nodes before linearized reading' (duration: 180.037455ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:37.959824Z","caller":"traceutil/trace.go:171","msg":"trace[809659919] linearizableReadLoop","detail":"{readStateIndex:1490; appliedIndex:1489; }","duration":"166.628263ms","start":"2026-09-15T04:43:37.793177Z","end":"2026-09-15T04:43:37.959805Z","steps":["trace[809659919] 'read index received' (duration: 166.419544ms)","trace[809659919] 'applied index is now lower than readState.Index' (duration: 207.799µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:37.959938Z","caller":"traceutil/trace.go:171","msg":"trace[1422638139] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"184.537752ms","start":"2026-09-15T04:43:37.775378Z","end":"2026-09-15T04:43:37.959916Z","steps":["trace[1422638139] 'process raft request' (duration: 184.26018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:37.960081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.217933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system:controller:token-cleaner\" ","response":"range_response_count:1 size:670"} {"level":"info","ts":"2026-09-15T04:43:37.960151Z","caller":"traceutil/trace.go:171","msg":"trace[1305207424] range","detail":"{range_begin:/registry/roles/kube-system/system:controller:token-cleaner; range_end:; response_count:1; response_revision:1428; }","duration":"128.320318ms","start":"2026-09-15T04:43:37.831811Z","end":"2026-09-15T04:43:37.960131Z","steps":["trace[1305207424] 'agreement among raft nodes before linearized reading' (duration: 128.1509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:37.960249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.066683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:1 size:625"} {"level":"info","ts":"2026-09-15T04:43:37.960357Z","caller":"traceutil/trace.go:171","msg":"trace[336163387] range","detail":"{range_begin:/registry/roles/kube-system/system:controller:cloud-provider; range_end:; response_count:1; response_revision:1428; }","duration":"167.204619ms","start":"2026-09-15T04:43:37.793135Z","end":"2026-09-15T04:43:37.96034Z","steps":["trace[336163387] 'agreement among raft nodes before linearized reading' (duration: 166.78416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:38.49953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.527158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-09-15T04:43:38.499575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.713831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-09-15T04:43:38.499668Z","caller":"traceutil/trace.go:171","msg":"trace[1433338124] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:1431; }","duration":"139.697776ms","start":"2026-09-15T04:43:38.359941Z","end":"2026-09-15T04:43:38.499639Z","steps":["trace[1433338124] 'range keys from in-memory index tree' (duration: 139.35246ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:38.49968Z","caller":"traceutil/trace.go:171","msg":"trace[1467030597] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:1431; }","duration":"135.840226ms","start":"2026-09-15T04:43:38.363813Z","end":"2026-09-15T04:43:38.499653Z","steps":["trace[1467030597] 'range keys from in-memory index tree' (duration: 135.632137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:38.862228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.82164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T04:43:38.862524Z","caller":"traceutil/trace.go:171","msg":"trace[1085803288] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"166.582503ms","start":"2026-09-15T04:43:38.695918Z","end":"2026-09-15T04:43:38.862501Z","steps":["trace[1085803288] 'process raft request' (duration: 58.389186ms)","trace[1085803288] 'compare' (duration: 107.547368ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:39.074098Z","caller":"traceutil/trace.go:171","msg":"trace[1644639246] linearizableReadLoop","detail":"{readStateIndex:1496; appliedIndex:1494; }","duration":"333.711072ms","start":"2026-09-15T04:43:38.74036Z","end":"2026-09-15T04:43:39.074071Z","steps":["trace[1644639246] 'read index received' (duration: 13.902138ms)","trace[1644639246] 'applied index is now lower than readState.Index' (duration: 319.807534ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:39.074156Z","caller":"traceutil/trace.go:171","msg":"trace[1440058530] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"335.25812ms","start":"2026-09-15T04:43:38.738875Z","end":"2026-09-15T04:43:39.074133Z","steps":["trace[1440058530] 'process raft request' (duration: 292.99412ms)","trace[1440058530] 'compare' (duration: 42.098523ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:39.074387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.012075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-09-15T04:43:39.07445Z","caller":"traceutil/trace.go:171","msg":"trace[84716264] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:1434; }","duration":"334.101169ms","start":"2026-09-15T04:43:38.740333Z","end":"2026-09-15T04:43:39.074434Z","steps":["trace[84716264] 'agreement among raft nodes before linearized reading' (duration: 333.979614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:39.074491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:38.740326Z","time spent":"334.154252ms","remote":"127.0.0.1:52310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":543,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-09-15T04:43:39.074415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:38.738856Z","time spent":"335.46963ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T04:43:39.074695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.190075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-15T04:43:39.075237Z","caller":"traceutil/trace.go:171","msg":"trace[438136120] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:1434; }","duration":"321.750979ms","start":"2026-09-15T04:43:38.753473Z","end":"2026-09-15T04:43:39.075224Z","steps":["trace[438136120] 'agreement among raft nodes before linearized reading' (duration: 320.978165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:39.075282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:38.753466Z","time spent":"321.802111ms","remote":"127.0.0.1:52310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-09-15T04:43:39.074708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.505196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T04:43:39.075384Z","caller":"traceutil/trace.go:171","msg":"trace[791935738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1434; }","duration":"115.235488ms","start":"2026-09-15T04:43:38.96011Z","end":"2026-09-15T04:43:39.075346Z","steps":["trace[791935738] 'agreement among raft nodes before linearized reading' (duration: 114.415472ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:40.283282Z","caller":"traceutil/trace.go:171","msg":"trace[1368167790] linearizableReadLoop","detail":"{readStateIndex:1497; appliedIndex:1496; }","duration":"300.69842ms","start":"2026-09-15T04:43:39.982562Z","end":"2026-09-15T04:43:40.283261Z","steps":["trace[1368167790] 'read index received' (duration: 300.526172ms)","trace[1368167790] 'applied index is now lower than readState.Index' (duration: 171.448µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:40.283378Z","caller":"traceutil/trace.go:171","msg":"trace[1418633591] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"316.862553ms","start":"2026-09-15T04:43:39.96648Z","end":"2026-09-15T04:43:40.283343Z","steps":["trace[1418633591] 'process raft request' (duration: 316.664945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:40.283464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.882558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-15T04:43:40.283497Z","caller":"traceutil/trace.go:171","msg":"trace[1428611601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1435; }","duration":"300.949641ms","start":"2026-09-15T04:43:39.982536Z","end":"2026-09-15T04:43:40.283485Z","steps":["trace[1428611601] 'agreement among raft nodes before linearized reading' (duration: 300.824325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:40.283505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.864879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-09-15T04:43:40.283541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:39.96647Z","time spent":"316.980808ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T04:43:40.283537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.534392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-09-15T04:43:40.283598Z","caller":"traceutil/trace.go:171","msg":"trace[858084180] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:1435; }","duration":"247.629236ms","start":"2026-09-15T04:43:40.035953Z","end":"2026-09-15T04:43:40.283582Z","steps":["trace[858084180] 'agreement among raft nodes before linearized reading' (duration: 247.525821ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:40.283557Z","caller":"traceutil/trace.go:171","msg":"trace[1052679146] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:1435; }","duration":"257.954903ms","start":"2026-09-15T04:43:40.02559Z","end":"2026-09-15T04:43:40.283545Z","steps":["trace[1052679146] 'agreement among raft nodes before linearized reading' (duration: 257.851819ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:40.283525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:39.982522Z","time spent":"300.996262ms","remote":"127.0.0.1:52382","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":"warn","ts":"2026-09-15T04:43:40.72476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.364036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T04:43:40.724866Z","caller":"traceutil/trace.go:171","msg":"trace[2068934527] linearizableReadLoop","detail":"{readStateIndex:1498; appliedIndex:1497; }","duration":"416.854006ms","start":"2026-09-15T04:43:40.307993Z","end":"2026-09-15T04:43:40.724847Z","steps":["trace[2068934527] 'read index received' (duration: 268.278251ms)","trace[2068934527] 'applied index is now lower than readState.Index' (duration: 148.574255ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:40.724982Z","caller":"traceutil/trace.go:171","msg":"trace[1253134376] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"435.052844ms","start":"2026-09-15T04:43:40.289913Z","end":"2026-09-15T04:43:40.724966Z","steps":["trace[1253134376] 'process raft request' (duration: 286.373194ms)","trace[1253134376] 'compare' (duration: 148.091855ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:40.725064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:40.28989Z","time spent":"435.126207ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T04:43:40.725147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.85104ms","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-09-15T04:43:40.725221Z","caller":"traceutil/trace.go:171","msg":"trace[1323600947] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:1436; }","duration":"339.958416ms","start":"2026-09-15T04:43:40.385246Z","end":"2026-09-15T04:43:40.725204Z","steps":["trace[1323600947] 'agreement among raft nodes before linearized reading' (duration: 339.803919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:40.725264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:40.38523Z","time spent":"340.022258ms","remote":"127.0.0.1:52310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-09-15T04:43:40.725345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.344487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-15T04:43:40.725407Z","caller":"traceutil/trace.go:171","msg":"trace[1779820889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1436; }","duration":"417.452412ms","start":"2026-09-15T04:43:40.307938Z","end":"2026-09-15T04:43:40.72539Z","steps":["trace[1779820889] 'agreement among raft nodes before linearized reading' (duration: 417.261923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:40.725414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.357209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-09-15T04:43:40.725451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:40.307912Z","time spent":"417.527715ms","remote":"127.0.0.1:52382","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":"info","ts":"2026-09-15T04:43:40.72547Z","caller":"traceutil/trace.go:171","msg":"trace[1394107418] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:1436; }","duration":"336.440512ms","start":"2026-09-15T04:43:40.389012Z","end":"2026-09-15T04:43:40.725453Z","steps":["trace[1394107418] 'agreement among raft nodes before linearized reading' (duration: 336.343068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:40.725555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:40.388996Z","time spent":"336.543107ms","remote":"127.0.0.1:52310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-09-15T04:43:41.007546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.572977ms","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-09-15T04:43:41.007639Z","caller":"traceutil/trace.go:171","msg":"trace[552635067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1438; }","duration":"140.681782ms","start":"2026-09-15T04:43:40.866925Z","end":"2026-09-15T04:43:41.007607Z","steps":["trace[552635067] 'range keys from in-memory index tree' (duration: 140.449291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:41.00814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.210164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:71 size:52699"} {"level":"warn","ts":"2026-09-15T04:43:41.008074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.40387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:71 size:52699"} {"level":"info","ts":"2026-09-15T04:43:41.008195Z","caller":"traceutil/trace.go:171","msg":"trace[540936750] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:71; response_revision:1438; }","duration":"146.603838ms","start":"2026-09-15T04:43:40.861583Z","end":"2026-09-15T04:43:41.008187Z","steps":["trace[540936750] 'range keys from in-memory index tree' (duration: 146.092866ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:41.008201Z","caller":"traceutil/trace.go:171","msg":"trace[1683754237] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:71; response_revision:1438; }","duration":"153.302048ms","start":"2026-09-15T04:43:40.854884Z","end":"2026-09-15T04:43:41.008186Z","steps":["trace[1683754237] 'range keys from in-memory index tree' (duration: 152.698192ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:41.360105Z","caller":"traceutil/trace.go:171","msg":"trace[1621611408] linearizableReadLoop","detail":"{readStateIndex:1502; appliedIndex:1501; }","duration":"238.647662ms","start":"2026-09-15T04:43:41.121402Z","end":"2026-09-15T04:43:41.360049Z","steps":["trace[1621611408] 'read index received' (duration: 215.452273ms)","trace[1621611408] 'applied index is now lower than readState.Index' (duration: 23.193329ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:41.360291Z","caller":"traceutil/trace.go:171","msg":"trace[1322895648] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"280.081068ms","start":"2026-09-15T04:43:41.080189Z","end":"2026-09-15T04:43:41.36027Z","steps":["trace[1322895648] 'process raft request' (duration: 256.626397ms)","trace[1322895648] 'compare' (duration: 22.794903ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:41.360507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.224608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:1 size:220"} {"level":"info","ts":"2026-09-15T04:43:41.360595Z","caller":"traceutil/trace.go:171","msg":"trace[1785542200] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:1; response_revision:1440; }","duration":"236.350384ms","start":"2026-09-15T04:43:41.124221Z","end":"2026-09-15T04:43:41.360572Z","steps":["trace[1785542200] 'agreement among raft nodes before linearized reading' (duration: 236.185737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:41.360709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.202266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:1 size:232"} {"level":"info","ts":"2026-09-15T04:43:41.361055Z","caller":"traceutil/trace.go:171","msg":"trace[2084299713] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:1; response_revision:1440; }","duration":"239.677227ms","start":"2026-09-15T04:43:41.12136Z","end":"2026-09-15T04:43:41.361037Z","steps":["trace[2084299713] 'agreement among raft nodes before linearized reading' (duration: 239.201216ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:41.592875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.607398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:1 size:220"} {"level":"info","ts":"2026-09-15T04:43:41.592961Z","caller":"traceutil/trace.go:171","msg":"trace[793758839] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:1; response_revision:1440; }","duration":"127.733594ms","start":"2026-09-15T04:43:41.46521Z","end":"2026-09-15T04:43:41.592944Z","steps":["trace[793758839] 'range keys from in-memory index tree' (duration: 127.535065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:41.593164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.521457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:1 size:232"} {"level":"info","ts":"2026-09-15T04:43:41.593716Z","caller":"traceutil/trace.go:171","msg":"trace[1391588542] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:1; response_revision:1440; }","duration":"132.171685ms","start":"2026-09-15T04:43:41.46152Z","end":"2026-09-15T04:43:41.593691Z","steps":["trace[1391588542] 'range keys from in-memory index tree' (duration: 131.430353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:41.928297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.324581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:1 size:199"} {"level":"info","ts":"2026-09-15T04:43:41.928376Z","caller":"traceutil/trace.go:171","msg":"trace[1109208366] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:1; response_revision:1441; }","duration":"119.455266ms","start":"2026-09-15T04:43:41.808903Z","end":"2026-09-15T04:43:41.928358Z","steps":["trace[1109208366] 'range keys from in-memory index tree' (duration: 119.202896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:41.92838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.217784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-09-15T04:43:41.928469Z","caller":"traceutil/trace.go:171","msg":"trace[734500039] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:1441; }","duration":"102.34982ms","start":"2026-09-15T04:43:41.826095Z","end":"2026-09-15T04:43:41.928445Z","steps":["trace[734500039] 'range keys from in-memory index tree' (duration: 102.1087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:42.915742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.104905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T04:43:42.915899Z","caller":"traceutil/trace.go:171","msg":"trace[1958490596] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"139.172335ms","start":"2026-09-15T04:43:42.776673Z","end":"2026-09-15T04:43:42.915846Z","steps":["trace[1958490596] 'process raft request' (duration: 33.861271ms)","trace[1958490596] 'compare' (duration: 104.99368ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:43.181222Z","caller":"traceutil/trace.go:171","msg":"trace[1090742879] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"122.784615ms","start":"2026-09-15T04:43:43.058415Z","end":"2026-09-15T04:43:43.181199Z","steps":["trace[1090742879] 'process raft request' (duration: 122.652009ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:43.181415Z","caller":"traceutil/trace.go:171","msg":"trace[63252459] linearizableReadLoop","detail":"{readStateIndex:1509; appliedIndex:1509; }","duration":"101.34489ms","start":"2026-09-15T04:43:43.080058Z","end":"2026-09-15T04:43:43.181403Z","steps":["trace[63252459] 'read index received' (duration: 100.928933ms)","trace[63252459] 'applied index is now lower than readState.Index' (duration: 414.587µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:43.181581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.511148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-07inuf\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-09-15T04:43:43.181616Z","caller":"traceutil/trace.go:171","msg":"trace[408386522] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-07inuf; range_end:; response_count:1; response_revision:1446; }","duration":"101.618602ms","start":"2026-09-15T04:43:43.079987Z","end":"2026-09-15T04:43:43.181606Z","steps":["trace[408386522] 'agreement among raft nodes before linearized reading' (duration: 101.471896ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:44.476427Z","caller":"traceutil/trace.go:171","msg":"trace[789515468] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"137.859946ms","start":"2026-09-15T04:43:44.338536Z","end":"2026-09-15T04:43:44.476396Z","steps":["trace[789515468] 'process raft request' (duration: 137.609456ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:44.585044Z","caller":"traceutil/trace.go:171","msg":"trace[426588540] linearizableReadLoop","detail":"{readStateIndex:1512; appliedIndex:1511; }","duration":"108.677448ms","start":"2026-09-15T04:43:44.47635Z","end":"2026-09-15T04:43:44.585028Z","steps":["trace[426588540] 'read index received' (duration: 49.165435ms)","trace[426588540] 'applied index is now lower than readState.Index' (duration: 59.508983ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:44.585134Z","caller":"traceutil/trace.go:171","msg":"trace[1274436316] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"129.959901ms","start":"2026-09-15T04:43:44.455166Z","end":"2026-09-15T04:43:44.585126Z","steps":["trace[1274436316] 'process raft request' (duration: 70.312242ms)","trace[1274436316] 'compare' (duration: 59.291574ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:44.585257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.378144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-15T04:43:44.585361Z","caller":"traceutil/trace.go:171","msg":"trace[891778938] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:1; response_revision:1449; }","duration":"149.52734ms","start":"2026-09-15T04:43:44.435797Z","end":"2026-09-15T04:43:44.585324Z","steps":["trace[891778938] 'agreement among raft nodes before linearized reading' (duration: 149.404965ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:44.877384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.850616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-15T04:43:44.87746Z","caller":"traceutil/trace.go:171","msg":"trace[286591588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1449; }","duration":"104.972342ms","start":"2026-09-15T04:43:44.772468Z","end":"2026-09-15T04:43:44.87744Z","steps":["trace[286591588] 'range keys from in-memory index tree' (duration: 104.677229ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:44.877981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.828996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-09-15T04:43:44.878111Z","caller":"traceutil/trace.go:171","msg":"trace[1937264935] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1449; }","duration":"191.970842ms","start":"2026-09-15T04:43:44.686076Z","end":"2026-09-15T04:43:44.878047Z","steps":["trace[1937264935] 'range keys from in-memory index tree' (duration: 191.272973ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:44.878335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.114501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:1 size:191"} {"level":"info","ts":"2026-09-15T04:43:44.878391Z","caller":"traceutil/trace.go:171","msg":"trace[2053220538] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:1; response_revision:1449; }","duration":"161.198987ms","start":"2026-09-15T04:43:44.717177Z","end":"2026-09-15T04:43:44.878376Z","steps":["trace[2053220538] 'range keys from in-memory index tree' (duration: 159.996235ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:45.148948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.289311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T04:43:45.149046Z","caller":"traceutil/trace.go:171","msg":"trace[95419462] linearizableReadLoop","detail":"{readStateIndex:1514; appliedIndex:1513; }","duration":"192.960072ms","start":"2026-09-15T04:43:44.95607Z","end":"2026-09-15T04:43:45.14903Z","steps":["trace[95419462] 'read index received' (duration: 34.137638ms)","trace[95419462] 'applied index is now lower than readState.Index' (duration: 158.821063ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:45.149119Z","caller":"traceutil/trace.go:171","msg":"trace[2141618928] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"224.776051ms","start":"2026-09-15T04:43:44.924331Z","end":"2026-09-15T04:43:45.149107Z","steps":["trace[2141618928] 'process raft request' (duration: 65.960317ms)","trace[2141618928] 'compare' (duration: 158.02354ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:45.149336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.609569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T04:43:45.149435Z","caller":"traceutil/trace.go:171","msg":"trace[460155641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1451; }","duration":"189.729034ms","start":"2026-09-15T04:43:44.959653Z","end":"2026-09-15T04:43:45.149382Z","steps":["trace[460155641] 'agreement among raft nodes before linearized reading' (duration: 189.601229ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:45.149718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.613659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-15T04:43:45.149788Z","caller":"traceutil/trace.go:171","msg":"trace[493400114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1451; }","duration":"193.780647ms","start":"2026-09-15T04:43:44.955989Z","end":"2026-09-15T04:43:45.14977Z","steps":["trace[493400114] 'agreement among raft nodes before linearized reading' (duration: 193.576498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:45.149666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.610213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-09-15T04:43:45.149896Z","caller":"traceutil/trace.go:171","msg":"trace[27589251] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1451; }","duration":"131.878734ms","start":"2026-09-15T04:43:45.018001Z","end":"2026-09-15T04:43:45.14988Z","steps":["trace[27589251] 'agreement among raft nodes before linearized reading' (duration: 131.342182ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:45.149634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.023167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:1 size:191"} {"level":"info","ts":"2026-09-15T04:43:45.150091Z","caller":"traceutil/trace.go:171","msg":"trace[424996451] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:1; response_revision:1451; }","duration":"161.530429ms","start":"2026-09-15T04:43:44.988541Z","end":"2026-09-15T04:43:45.150071Z","steps":["trace[424996451] 'agreement among raft nodes before linearized reading' (duration: 160.964085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:45.419487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.495081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T04:43:45.419587Z","caller":"traceutil/trace.go:171","msg":"trace[295888046] linearizableReadLoop","detail":"{readStateIndex:1515; appliedIndex:1514; }","duration":"264.331881ms","start":"2026-09-15T04:43:45.155238Z","end":"2026-09-15T04:43:45.41957Z","steps":["trace[295888046] 'read index received' (duration: 153.704684ms)","trace[295888046] 'applied index is now lower than readState.Index' (duration: 110.625997ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:45.419622Z","caller":"traceutil/trace.go:171","msg":"trace[1071320869] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"264.470868ms","start":"2026-09-15T04:43:45.155118Z","end":"2026-09-15T04:43:45.419588Z","steps":["trace[1071320869] 'process raft request' (duration: 153.773088ms)","trace[1071320869] 'compare' (duration: 110.374997ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:45.419684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.441097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:1 size:191"} {"level":"info","ts":"2026-09-15T04:43:45.419745Z","caller":"traceutil/trace.go:171","msg":"trace[1226434405] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:1; response_revision:1452; }","duration":"264.52488ms","start":"2026-09-15T04:43:45.15521Z","end":"2026-09-15T04:43:45.419735Z","steps":["trace[1226434405] 'agreement among raft nodes before linearized reading' (duration: 264.426176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:45.454357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.479297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"warn","ts":"2026-09-15T04:43:45.454458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.904755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-15T04:43:45.454519Z","caller":"traceutil/trace.go:171","msg":"trace[1863556831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1453; }","duration":"269.007979ms","start":"2026-09-15T04:43:45.185494Z","end":"2026-09-15T04:43:45.454502Z","steps":["trace[1863556831] 'agreement among raft nodes before linearized reading' (duration: 268.748738ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:45.454464Z","caller":"traceutil/trace.go:171","msg":"trace[235848267] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1453; }","duration":"204.645543ms","start":"2026-09-15T04:43:45.249793Z","end":"2026-09-15T04:43:45.454439Z","steps":["trace[235848267] 'agreement among raft nodes before linearized reading' (duration: 204.417404ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:45.636641Z","caller":"traceutil/trace.go:171","msg":"trace[116442358] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"176.688855ms","start":"2026-09-15T04:43:45.459931Z","end":"2026-09-15T04:43:45.636619Z","steps":["trace[116442358] 'process raft request' (duration: 79.126429ms)","trace[116442358] 'compare' (duration: 97.42873ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:45.636791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.502415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-09-15T04:43:45.636833Z","caller":"traceutil/trace.go:171","msg":"trace[1152414131] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1454; }","duration":"175.593558ms","start":"2026-09-15T04:43:45.46123Z","end":"2026-09-15T04:43:45.636823Z","steps":["trace[1152414131] 'agreement among raft nodes before linearized reading' (duration: 175.453622ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:45.636625Z","caller":"traceutil/trace.go:171","msg":"trace[139998008] linearizableReadLoop","detail":"{readStateIndex:1517; appliedIndex:1516; }","duration":"175.335177ms","start":"2026-09-15T04:43:45.461267Z","end":"2026-09-15T04:43:45.636602Z","steps":["trace[139998008] 'read index received' (duration: 78.340125ms)","trace[139998008] 'applied index is now lower than readState.Index' (duration: 96.993792ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:45.637538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.399076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:71 size:52699"} {"level":"info","ts":"2026-09-15T04:43:45.637599Z","caller":"traceutil/trace.go:171","msg":"trace[692983879] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:71; response_revision:1454; }","duration":"141.495909ms","start":"2026-09-15T04:43:45.496084Z","end":"2026-09-15T04:43:45.63758Z","steps":["trace[692983879] 'agreement among raft nodes before linearized reading' (duration: 140.859612ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:47.392126Z","caller":"traceutil/trace.go:171","msg":"trace[359914033] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"180.172633ms","start":"2026-09-15T04:43:47.211921Z","end":"2026-09-15T04:43:47.392094Z","steps":["trace[359914033] 'process raft request' (duration: 179.735455ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:47.581655Z","caller":"traceutil/trace.go:171","msg":"trace[838440689] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"109.122706ms","start":"2026-09-15T04:43:47.472495Z","end":"2026-09-15T04:43:47.581617Z","steps":["trace[838440689] 'process raft request' (duration: 45.28103ms)","trace[838440689] 'compare' (duration: 63.658518ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:47.58178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.605041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-15T04:43:47.581906Z","caller":"traceutil/trace.go:171","msg":"trace[1403102527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1457; }","duration":"157.83363ms","start":"2026-09-15T04:43:47.424042Z","end":"2026-09-15T04:43:47.581875Z","steps":["trace[1403102527] 'range keys from in-memory index tree' (duration: 157.36245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:47.834553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.839005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T04:43:47.834842Z","caller":"traceutil/trace.go:171","msg":"trace[285411582] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"183.632777ms","start":"2026-09-15T04:43:47.651065Z","end":"2026-09-15T04:43:47.834698Z","steps":["trace[285411582] 'process raft request' (duration: 39.480948ms)","trace[285411582] 'compare' (duration: 143.46808ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:49.282101Z","caller":"traceutil/trace.go:171","msg":"trace[1478583861] linearizableReadLoop","detail":"{readStateIndex:1527; appliedIndex:1526; }","duration":"279.377805ms","start":"2026-09-15T04:43:49.002699Z","end":"2026-09-15T04:43:49.282076Z","steps":["trace[1478583861] 'read index received' (duration: 279.183737ms)","trace[1478583861] 'applied index is now lower than readState.Index' (duration: 193.158µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:49.282224Z","caller":"traceutil/trace.go:171","msg":"trace[352702141] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"311.517469ms","start":"2026-09-15T04:43:48.970676Z","end":"2026-09-15T04:43:49.282193Z","steps":["trace[352702141] 'process raft request' (duration: 311.264979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:49.282393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:48.970649Z","time spent":"311.643024ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T04:43:49.282437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.713648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-gcttj\" ","response":"range_response_count:1 size:5486"} {"level":"info","ts":"2026-09-15T04:43:49.282534Z","caller":"traceutil/trace.go:171","msg":"trace[2016179714] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-gcttj; range_end:; response_count:1; response_revision:1463; }","duration":"279.866495ms","start":"2026-09-15T04:43:49.002648Z","end":"2026-09-15T04:43:49.282515Z","steps":["trace[2016179714] 'agreement among raft nodes before linearized reading' (duration: 279.559162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:49.282563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.940664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 ","response":"range_response_count:10 size:65234"} {"level":"info","ts":"2026-09-15T04:43:49.282635Z","caller":"traceutil/trace.go:171","msg":"trace[1222749693] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:10; response_revision:1463; }","duration":"270.052199ms","start":"2026-09-15T04:43:49.012564Z","end":"2026-09-15T04:43:49.282616Z","steps":["trace[1222749693] 'agreement among raft nodes before linearized reading' (duration: 269.707645ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:51.492134Z","caller":"traceutil/trace.go:171","msg":"trace[1703302176] linearizableReadLoop","detail":"{readStateIndex:1533; appliedIndex:1532; }","duration":"106.456704ms","start":"2026-09-15T04:43:51.385657Z","end":"2026-09-15T04:43:51.492113Z","steps":["trace[1703302176] 'read index received' (duration: 106.186573ms)","trace[1703302176] 'applied index is now lower than readState.Index' (duration: 269.171µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:51.492271Z","caller":"traceutil/trace.go:171","msg":"trace[1294004094] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"197.603841ms","start":"2026-09-15T04:43:51.294589Z","end":"2026-09-15T04:43:51.492193Z","steps":["trace[1294004094] 'process raft request' (duration: 197.373172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:51.492396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.721944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j2f68\" ","response":"range_response_count:1 size:18521"} {"level":"info","ts":"2026-09-15T04:43:51.49248Z","caller":"traceutil/trace.go:171","msg":"trace[58446110] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j2f68; range_end:; response_count:1; response_revision:1469; }","duration":"106.829238ms","start":"2026-09-15T04:43:51.385618Z","end":"2026-09-15T04:43:51.492448Z","steps":["trace[58446110] 'agreement among raft nodes before linearized reading' (duration: 106.646271ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:52.006068Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15158730019284162680,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-15T04:43:52.526128Z","caller":"traceutil/trace.go:171","msg":"trace[1744114023] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"1.02869626s","start":"2026-09-15T04:43:51.497393Z","end":"2026-09-15T04:43:52.526089Z","steps":["trace[1744114023] 'process raft request' (duration: 967.195598ms)","trace[1744114023] 'compare' (duration: 60.293283ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:52.526289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:51.497375Z","time spent":"1.028836486s","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T04:43:52.526536Z","caller":"traceutil/trace.go:171","msg":"trace[806281663] linearizableReadLoop","detail":"{readStateIndex:1535; appliedIndex:1533; }","duration":"1.020893641s","start":"2026-09-15T04:43:51.505614Z","end":"2026-09-15T04:43:52.526508Z","steps":["trace[806281663] 'read index received' (duration: 959.006493ms)","trace[806281663] 'applied index is now lower than readState.Index' (duration: 61.885688ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:52.526686Z","caller":"traceutil/trace.go:171","msg":"trace[1106604635] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"906.174579ms","start":"2026-09-15T04:43:51.620498Z","end":"2026-09-15T04:43:52.526673Z","steps":["trace[1106604635] 'process raft request' (duration: 905.149457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:52.526752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:51.620477Z","time spent":"906.237881ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T04:43:52.527006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021372281s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j2f68\" ","response":"range_response_count:1 size:18521"} {"level":"warn","ts":"2026-09-15T04:43:52.527079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.145898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T04:43:52.527089Z","caller":"traceutil/trace.go:171","msg":"trace[398204283] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j2f68; range_end:; response_count:1; response_revision:1471; }","duration":"1.021506996s","start":"2026-09-15T04:43:51.505568Z","end":"2026-09-15T04:43:52.527075Z","steps":["trace[398204283] 'agreement among raft nodes before linearized reading' (duration: 1.021170082s)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:52.527111Z","caller":"traceutil/trace.go:171","msg":"trace[948524096] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1471; }","duration":"352.215361ms","start":"2026-09-15T04:43:52.174883Z","end":"2026-09-15T04:43:52.527098Z","steps":["trace[948524096] 'agreement among raft nodes before linearized reading' (duration: 352.174769ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:52.527151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:52.174861Z","time spent":"352.281524ms","remote":"127.0.0.1:58166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-15T04:43:52.527225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:51.505553Z","time spent":"1.021565109s","remote":"127.0.0.1:52290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18544,"request content":"key:\"/registry/pods/kube-system/cilium-j2f68\" "} {"level":"warn","ts":"2026-09-15T04:43:52.527032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.730264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-15T04:43:52.527493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.933601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-15T04:43:52.527479Z","caller":"traceutil/trace.go:171","msg":"trace[267841837] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1471; }","duration":"875.198072ms","start":"2026-09-15T04:43:51.652256Z","end":"2026-09-15T04:43:52.527454Z","steps":["trace[267841837] 'agreement among raft nodes before linearized reading' (duration: 874.730723ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:52.527524Z","caller":"traceutil/trace.go:171","msg":"trace[1063735743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1471; }","duration":"794.983823ms","start":"2026-09-15T04:43:51.732529Z","end":"2026-09-15T04:43:52.527513Z","steps":["trace[1063735743] 'agreement among raft nodes before linearized reading' (duration: 794.91294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:52.527553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:51.732515Z","time spent":"795.031464ms","remote":"127.0.0.1:52382","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":"warn","ts":"2026-09-15T04:43:52.527555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.884446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.76\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-09-15T04:43:52.527571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:51.652233Z","time spent":"875.301947ms","remote":"127.0.0.1:52524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-09-15T04:43:52.527597Z","caller":"traceutil/trace.go:171","msg":"trace[555543700] range","detail":"{range_begin:/registry/masterleases/162.253.55.76; range_end:; response_count:1; response_revision:1471; }","duration":"859.956199ms","start":"2026-09-15T04:43:51.667626Z","end":"2026-09-15T04:43:52.527582Z","steps":["trace[555543700] 'agreement among raft nodes before linearized reading' (duration: 859.842854ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:52.527589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.44164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-15T04:43:52.52742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.530034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-15T04:43:52.527633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:51.667608Z","time spent":"860.013691ms","remote":"127.0.0.1:52130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.76\" "} {"level":"info","ts":"2026-09-15T04:43:52.527656Z","caller":"traceutil/trace.go:171","msg":"trace[440699109] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1471; }","duration":"816.559325ms","start":"2026-09-15T04:43:51.71108Z","end":"2026-09-15T04:43:52.52764Z","steps":["trace[440699109] 'agreement among raft nodes before linearized reading' (duration: 816.43542ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:52.527667Z","caller":"traceutil/trace.go:171","msg":"trace[1386562228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1471; }","duration":"648.810415ms","start":"2026-09-15T04:43:51.878844Z","end":"2026-09-15T04:43:52.527655Z","steps":["trace[1386562228] 'agreement among raft nodes before linearized reading' (duration: 648.473951ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:52.527696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:51.711066Z","time spent":"816.620038ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-09-15T04:43:52.52771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:51.878825Z","time spent":"648.874507ms","remote":"127.0.0.1:52382","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":"info","ts":"2026-09-15T04:43:52.715941Z","caller":"traceutil/trace.go:171","msg":"trace[757187736] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"142.921996ms","start":"2026-09-15T04:43:52.572989Z","end":"2026-09-15T04:43:52.715911Z","steps":["trace[757187736] 'process raft request' (duration: 85.53457ms)","trace[757187736] 'compare' (duration: 57.181877ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:53.221668Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15158730019284162694,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-15T04:43:53.321544Z","caller":"traceutil/trace.go:171","msg":"trace[56299679] linearizableReadLoop","detail":"{readStateIndex:1540; appliedIndex:1539; }","duration":"600.235906ms","start":"2026-09-15T04:43:52.721283Z","end":"2026-09-15T04:43:53.321518Z","steps":["trace[56299679] 'read index received' (duration: 600.015407ms)","trace[56299679] 'applied index is now lower than readState.Index' (duration: 219.649µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:53.321624Z","caller":"traceutil/trace.go:171","msg":"trace[2114739847] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"601.276828ms","start":"2026-09-15T04:43:52.720317Z","end":"2026-09-15T04:43:53.321594Z","steps":["trace[2114739847] 'process raft request' (duration: 601.060599ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:53.321705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.395472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-15T04:43:53.321986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:52.7203Z","time spent":"601.601732ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T04:43:53.322008Z","caller":"traceutil/trace.go:171","msg":"trace[364474150] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1475; }","duration":"600.736016ms","start":"2026-09-15T04:43:52.72126Z","end":"2026-09-15T04:43:53.321996Z","steps":["trace[364474150] 'agreement among raft nodes before linearized reading' (duration: 600.351641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:53.322048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:52.72125Z","time spent":"600.784888ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-09-15T04:43:53.321801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.838996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T04:43:53.322313Z","caller":"traceutil/trace.go:171","msg":"trace[1327127296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1475; }","duration":"362.405199ms","start":"2026-09-15T04:43:52.959891Z","end":"2026-09-15T04:43:53.322296Z","steps":["trace[1327127296] 'agreement among raft nodes before linearized reading' (duration: 361.840846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:53.32236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:52.959872Z","time spent":"362.473432ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-15T04:43:53.321847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.175469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-jgl9h\" ","response":"range_response_count:1 size:4690"} {"level":"info","ts":"2026-09-15T04:43:53.322442Z","caller":"traceutil/trace.go:171","msg":"trace[1033346668] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-jgl9h; range_end:; response_count:1; response_revision:1475; }","duration":"506.827006ms","start":"2026-09-15T04:43:52.815594Z","end":"2026-09-15T04:43:53.322421Z","steps":["trace[1033346668] 'agreement among raft nodes before linearized reading' (duration: 506.093906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:53.32251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:52.815565Z","time spent":"506.92848ms","remote":"127.0.0.1:52290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4713,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-jgl9h\" "} {"level":"warn","ts":"2026-09-15T04:43:53.58104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.795713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-jgl9h\" ","response":"range_response_count:1 size:4690"} {"level":"info","ts":"2026-09-15T04:43:53.581129Z","caller":"traceutil/trace.go:171","msg":"trace[1826509754] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-jgl9h; range_end:; response_count:1; response_revision:1475; }","duration":"147.921158ms","start":"2026-09-15T04:43:53.433183Z","end":"2026-09-15T04:43:53.581105Z","steps":["trace[1826509754] 'range keys from in-memory index tree' (duration: 147.609075ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:53.716896Z","caller":"traceutil/trace.go:171","msg":"trace[1579767266] linearizableReadLoop","detail":"{readStateIndex:1541; appliedIndex:1540; }","duration":"126.563963ms","start":"2026-09-15T04:43:53.590309Z","end":"2026-09-15T04:43:53.716873Z","steps":["trace[1579767266] 'read index received' (duration: 126.333904ms)","trace[1579767266] 'applied index is now lower than readState.Index' (duration: 229.319µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:53.717215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.885577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-jgl9h\" ","response":"range_response_count:1 size:4690"} {"level":"info","ts":"2026-09-15T04:43:53.71726Z","caller":"traceutil/trace.go:171","msg":"trace[180082821] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-jgl9h; range_end:; response_count:1; response_revision:1476; }","duration":"126.96704ms","start":"2026-09-15T04:43:53.590279Z","end":"2026-09-15T04:43:53.717246Z","steps":["trace[180082821] 'agreement among raft nodes before linearized reading' (duration: 126.688859ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:53.717339Z","caller":"traceutil/trace.go:171","msg":"trace[1980602387] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"131.591577ms","start":"2026-09-15T04:43:53.585692Z","end":"2026-09-15T04:43:53.717284Z","steps":["trace[1980602387] 'process raft request' (duration: 131.029834ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:54.695306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.394385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T04:43:54.695425Z","caller":"traceutil/trace.go:171","msg":"trace[1407087313] linearizableReadLoop","detail":"{readStateIndex:1543; appliedIndex:1542; }","duration":"130.931644ms","start":"2026-09-15T04:43:54.564472Z","end":"2026-09-15T04:43:54.695404Z","steps":["trace[1407087313] 'read index received' (duration: 21.272978ms)","trace[1407087313] 'applied index is now lower than readState.Index' (duration: 109.656986ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:54.695514Z","caller":"traceutil/trace.go:171","msg":"trace[329314336] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"159.235783ms","start":"2026-09-15T04:43:54.536261Z","end":"2026-09-15T04:43:54.695497Z","steps":["trace[329314336] 'process raft request' (duration: 49.540065ms)","trace[329314336] 'compare' (duration: 109.273381ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:54.695615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.132782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-k95jp\" ","response":"range_response_count:1 size:4907"} {"level":"info","ts":"2026-09-15T04:43:54.695658Z","caller":"traceutil/trace.go:171","msg":"trace[79399328] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-k95jp; range_end:; response_count:1; response_revision:1478; }","duration":"131.200655ms","start":"2026-09-15T04:43:54.564445Z","end":"2026-09-15T04:43:54.695646Z","steps":["trace[79399328] 'agreement among raft nodes before linearized reading' (duration: 131.055619ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:54.717036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.7969ms","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-09-15T04:43:54.717114Z","caller":"traceutil/trace.go:171","msg":"trace[349079761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1479; }","duration":"104.914516ms","start":"2026-09-15T04:43:54.612183Z","end":"2026-09-15T04:43:54.717098Z","steps":["trace[349079761] 'agreement among raft nodes before linearized reading' (duration: 104.743909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:54.717196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.859333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-15T04:43:54.717275Z","caller":"traceutil/trace.go:171","msg":"trace[1231124066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1479; }","duration":"105.053301ms","start":"2026-09-15T04:43:54.612209Z","end":"2026-09-15T04:43:54.717252Z","steps":["trace[1231124066] 'agreement among raft nodes before linearized reading' (duration: 104.804241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:54.955413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.459347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T04:43:54.955604Z","caller":"traceutil/trace.go:171","msg":"trace[902237373] linearizableReadLoop","detail":"{readStateIndex:1546; appliedIndex:1544; }","duration":"141.612383ms","start":"2026-09-15T04:43:54.813972Z","end":"2026-09-15T04:43:54.955584Z","steps":["trace[902237373] 'read index received' (duration: 96.387252ms)","trace[902237373] 'applied index is now lower than readState.Index' (duration: 45.224031ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:54.955803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.821341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-k95jp\" ","response":"range_response_count:1 size:4907"} {"level":"info","ts":"2026-09-15T04:43:54.955824Z","caller":"traceutil/trace.go:171","msg":"trace[1349742703] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"233.955372ms","start":"2026-09-15T04:43:54.72185Z","end":"2026-09-15T04:43:54.955805Z","steps":["trace[1349742703] 'process raft request' (duration: 233.66172ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T04:43:54.955813Z","caller":"traceutil/trace.go:171","msg":"trace[2054310720] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"234.232263ms","start":"2026-09-15T04:43:54.721556Z","end":"2026-09-15T04:43:54.955789Z","steps":["trace[2054310720] 'process raft request' (duration: 92.312278ms)","trace[2054310720] 'compare' (duration: 141.255459ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:54.955877Z","caller":"traceutil/trace.go:171","msg":"trace[11669768] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-k95jp; range_end:; response_count:1; response_revision:1481; }","duration":"141.924406ms","start":"2026-09-15T04:43:54.813939Z","end":"2026-09-15T04:43:54.955863Z","steps":["trace[11669768] 'agreement among raft nodes before linearized reading' (duration: 141.704857ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:55.169811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.258928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5154"} {"level":"info","ts":"2026-09-15T04:43:55.169936Z","caller":"traceutil/trace.go:171","msg":"trace[787550428] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1481; }","duration":"105.434244ms","start":"2026-09-15T04:43:55.06448Z","end":"2026-09-15T04:43:55.169914Z","steps":["trace[787550428] 'range keys from in-memory index tree' (duration: 105.130702ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:55.86554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15158730019284162737,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-15T04:43:56.075787Z","caller":"traceutil/trace.go:171","msg":"trace[1006211291] linearizableReadLoop","detail":"{readStateIndex:1547; appliedIndex:1546; }","duration":"710.88911ms","start":"2026-09-15T04:43:55.364876Z","end":"2026-09-15T04:43:56.075765Z","steps":["trace[1006211291] 'read index received' (duration: 710.706573ms)","trace[1006211291] 'applied index is now lower than readState.Index' (duration: 181.717µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:56.076382Z","caller":"traceutil/trace.go:171","msg":"trace[488521170] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"745.391062ms","start":"2026-09-15T04:43:55.330979Z","end":"2026-09-15T04:43:56.07637Z","steps":["trace[488521170] 'process raft request' (duration: 744.663223ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:56.076389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.474119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-15T04:43:56.07657Z","caller":"traceutil/trace.go:171","msg":"trace[760672023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1482; }","duration":"354.735769ms","start":"2026-09-15T04:43:55.721815Z","end":"2026-09-15T04:43:56.076551Z","steps":["trace[760672023] 'agreement among raft nodes before linearized reading' (duration: 354.25638ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:56.076478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:55.330961Z","time spent":"745.455395ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T04:43:56.076714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:55.721798Z","time spent":"354.857573ms","remote":"127.0.0.1:52382","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":"warn","ts":"2026-09-15T04:43:56.076802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.822646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5154"} {"level":"info","ts":"2026-09-15T04:43:56.077099Z","caller":"traceutil/trace.go:171","msg":"trace[645067634] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1482; }","duration":"712.208282ms","start":"2026-09-15T04:43:55.3648Z","end":"2026-09-15T04:43:56.077009Z","steps":["trace[645067634] 'agreement among raft nodes before linearized reading' (duration: 711.607148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:56.077265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:55.364782Z","time spent":"712.426311ms","remote":"127.0.0.1:52290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5177,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-09-15T04:43:56.495913Z","caller":"traceutil/trace.go:171","msg":"trace[363605956] linearizableReadLoop","detail":"{readStateIndex:1548; appliedIndex:1547; }","duration":"410.019995ms","start":"2026-09-15T04:43:56.085869Z","end":"2026-09-15T04:43:56.495889Z","steps":["trace[363605956] 'read index received' (duration: 343.219252ms)","trace[363605956] 'applied index is now lower than readState.Index' (duration: 66.799613ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T04:43:56.495932Z","caller":"traceutil/trace.go:171","msg":"trace[597219099] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"413.748994ms","start":"2026-09-15T04:43:56.082165Z","end":"2026-09-15T04:43:56.495913Z","steps":["trace[597219099] 'process raft request' (duration: 346.989373ms)","trace[597219099] 'compare' (duration: 66.565444ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T04:43:56.496095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.238584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5154"} {"level":"warn","ts":"2026-09-15T04:43:56.496099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:56.082144Z","time spent":"413.853889ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T04:43:56.496139Z","caller":"traceutil/trace.go:171","msg":"trace[1823465125] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1483; }","duration":"410.319158ms","start":"2026-09-15T04:43:56.085806Z","end":"2026-09-15T04:43:56.496125Z","steps":["trace[1823465125] 'agreement among raft nodes before linearized reading' (duration: 410.169312ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T04:43:56.496182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T04:43:56.085792Z","time spent":"410.38099ms","remote":"127.0.0.1:52290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5177,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "}