{"level":"warn","ts":"2026-04-13T17:09:46.914353Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T17:09:46.914442Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.57:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.57:2380","--initial-cluster=instance=https://199.19.213.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.57: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-04-13T17:09:46.91452Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T17:09:46.91453Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.57:2380"]} {"level":"info","ts":"2026-04-13T17:09:46.914551Z","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-04-13T17:09:46.915348Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"]} {"level":"info","ts":"2026-04-13T17:09:46.915436Z","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://199.19.213.57:2380"],"listen-peer-urls":["https://199.19.213.57:2380"],"advertise-client-urls":["https://199.19.213.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.57: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-04-13T17:09:46.921866Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.11052ms"} {"level":"info","ts":"2026-04-13T17:09:46.929187Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ef5ef2a9544a39ef","cluster-id":"58d35553a3d286c0"} {"level":"info","ts":"2026-04-13T17:09:46.929259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T17:09:46.929278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became follower at term 0"} {"level":"info","ts":"2026-04-13T17:09:46.92929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ef5ef2a9544a39ef [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T17:09:46.929311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became follower at term 1"} {"level":"info","ts":"2026-04-13T17:09:46.929351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=(17248490431953123823)"} {"level":"warn","ts":"2026-04-13T17:09:46.943258Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T17:09:46.947171Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T17:09:46.949598Z","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-04-13T17:09:46.952286Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ef5ef2a9544a39ef","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T17:09:46.952573Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ef5ef2a9544a39ef","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T17:09:46.952727Z","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-04-13T17:09:46.952785Z","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-04-13T17:09:46.952801Z","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-04-13T17:09:46.955351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=(17248490431953123823)"} {"level":"info","ts":"2026-04-13T17:09:46.956141Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"58d35553a3d286c0","local-member-id":"ef5ef2a9544a39ef","added-peer-id":"ef5ef2a9544a39ef","added-peer-peer-urls":["https://199.19.213.57:2380"]} {"level":"info","ts":"2026-04-13T17:09:46.957884Z","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-04-13T17:09:46.958048Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.57:2380"} {"level":"info","ts":"2026-04-13T17:09:46.958115Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.57:2380"} {"level":"info","ts":"2026-04-13T17:09:46.958148Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ef5ef2a9544a39ef","initial-advertise-peer-urls":["https://199.19.213.57:2380"],"listen-peer-urls":["https://199.19.213.57:2380"],"advertise-client-urls":["https://199.19.213.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T17:09:46.958208Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T17:09:47.029828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T17:09:47.029902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T17:09:47.029974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef received MsgPreVoteResp from ef5ef2a9544a39ef at term 1"} {"level":"info","ts":"2026-04-13T17:09:47.030045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became candidate at term 2"} {"level":"info","ts":"2026-04-13T17:09:47.03006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef received MsgVoteResp from ef5ef2a9544a39ef at term 2"} {"level":"info","ts":"2026-04-13T17:09:47.030087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became leader at term 2"} {"level":"info","ts":"2026-04-13T17:09:47.030125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ef5ef2a9544a39ef elected leader ef5ef2a9544a39ef at term 2"} {"level":"info","ts":"2026-04-13T17:09:47.031089Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:09:47.031883Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ef5ef2a9544a39ef","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.57:2379]}","request-path":"/0/members/ef5ef2a9544a39ef/attributes","cluster-id":"58d35553a3d286c0","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T17:09:47.031894Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:09:47.031918Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:09:47.033553Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T17:09:47.033586Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T17:09:47.033705Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"58d35553a3d286c0","local-member-id":"ef5ef2a9544a39ef","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:09:47.033797Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:09:47.033847Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:09:47.03575Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T17:09:47.036382Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.57:2379"} {"level":"info","ts":"2026-04-13T17:10:17.200112Z","caller":"traceutil/trace.go:171","msg":"trace[609289289] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"503.904374ms","start":"2026-04-13T17:10:16.69618Z","end":"2026-04-13T17:10:17.200085Z","steps":["trace[609289289] 'process raft request' (duration: 503.719831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:17.200623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:16.696161Z","time spent":"504.039837ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:10:17.278898Z","caller":"traceutil/trace.go:171","msg":"trace[1180345680] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"574.772257ms","start":"2026-04-13T17:10:16.704086Z","end":"2026-04-13T17:10:17.278858Z","steps":["trace[1180345680] 'process raft request' (duration: 563.117058ms)","trace[1180345680] 'compare' (duration: 11.390074ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:10:17.278974Z","caller":"traceutil/trace.go:171","msg":"trace[1442227431] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"567.857758ms","start":"2026-04-13T17:10:16.711077Z","end":"2026-04-13T17:10:17.278935Z","steps":["trace[1442227431] 'process raft request' (duration: 567.770116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:17.27906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:16.704068Z","time spent":"574.928011ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:10:17.27909Z","caller":"traceutil/trace.go:171","msg":"trace[623803094] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"562.466876ms","start":"2026-04-13T17:10:16.7166Z","end":"2026-04-13T17:10:17.279067Z","steps":["trace[623803094] 'process raft request' (duration: 562.277463ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.279151Z","caller":"traceutil/trace.go:171","msg":"trace[1500618044] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"551.909168ms","start":"2026-04-13T17:10:16.7272Z","end":"2026-04-13T17:10:17.279109Z","steps":["trace[1500618044] 'process raft request' (duration: 551.707804ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.279226Z","caller":"traceutil/trace.go:171","msg":"trace[286699977] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"551.218744ms","start":"2026-04-13T17:10:16.727993Z","end":"2026-04-13T17:10:17.279212Z","steps":["trace[286699977] 'process raft request' (duration: 550.99253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:17.279285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:16.72798Z","time spent":"551.275015ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:10:17.279236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:16.711067Z","time spent":"568.076731ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:10:17.279324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:16.727174Z","time spent":"552.07362ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:10:17.279345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:16.716585Z","time spent":"562.621899ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:10:17.279487Z","caller":"traceutil/trace.go:171","msg":"trace[994500686] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"547.574456ms","start":"2026-04-13T17:10:16.731901Z","end":"2026-04-13T17:10:17.279475Z","steps":["trace[994500686] 'process raft request' (duration: 547.184168ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.279476Z","caller":"traceutil/trace.go:171","msg":"trace[1559439680] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"550.92337ms","start":"2026-04-13T17:10:16.728527Z","end":"2026-04-13T17:10:17.279451Z","steps":["trace[1559439680] 'process raft request' (duration: 550.482481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:17.279556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:16.731888Z","time spent":"547.641547ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:10:17.27958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:16.728484Z","time spent":"551.051603ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:10:17.279569Z","caller":"traceutil/trace.go:171","msg":"trace[42382396] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"547.582526ms","start":"2026-04-13T17:10:16.731972Z","end":"2026-04-13T17:10:17.279554Z","steps":["trace[42382396] 'process raft request' (duration: 547.199828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:17.279671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:16.731963Z","time spent":"547.686209ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:10:17.27971Z","caller":"traceutil/trace.go:171","msg":"trace[1090952457] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"570.839844ms","start":"2026-04-13T17:10:16.708075Z","end":"2026-04-13T17:10:17.278914Z","steps":["trace[1090952457] 'process raft request' (duration: 570.672921ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.279817Z","caller":"traceutil/trace.go:171","msg":"trace[2004771626] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"536.620099ms","start":"2026-04-13T17:10:16.743188Z","end":"2026-04-13T17:10:17.279808Z","steps":["trace[2004771626] 'process raft request' (duration: 536.117499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:17.279941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:16.743175Z","time spent":"536.704331ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:10:17.279708Z","caller":"traceutil/trace.go:171","msg":"trace[2071102084] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"397.152015ms","start":"2026-04-13T17:10:16.882538Z","end":"2026-04-13T17:10:17.27969Z","steps":["trace[2071102084] 'process raft request' (duration: 397.029983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:17.279961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:16.708065Z","time spent":"571.755633ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:10:17.280006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:16.882504Z","time spent":"397.476642ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:10:17.326706Z","caller":"traceutil/trace.go:171","msg":"trace[2117179548] transaction","detail":"{read_only:false; number_of_response:1; response_revision:483; }","duration":"119.665762ms","start":"2026-04-13T17:10:17.207016Z","end":"2026-04-13T17:10:17.326682Z","steps":["trace[2117179548] 'process raft request' (duration: 119.5483ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.326703Z","caller":"traceutil/trace.go:171","msg":"trace[1120431352] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"120.418647ms","start":"2026-04-13T17:10:17.206223Z","end":"2026-04-13T17:10:17.326641Z","steps":["trace[1120431352] 'process raft request' (duration: 120.157312ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.326783Z","caller":"traceutil/trace.go:171","msg":"trace[1444347234] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"119.848216ms","start":"2026-04-13T17:10:17.206899Z","end":"2026-04-13T17:10:17.326748Z","steps":["trace[1444347234] 'process raft request' (duration: 119.595601ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.468193Z","caller":"traceutil/trace.go:171","msg":"trace[1208734891] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"183.774379ms","start":"2026-04-13T17:10:17.284374Z","end":"2026-04-13T17:10:17.468148Z","steps":["trace[1208734891] 'process raft request' (duration: 180.895425ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.468228Z","caller":"traceutil/trace.go:171","msg":"trace[730652491] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"172.075268ms","start":"2026-04-13T17:10:17.296098Z","end":"2026-04-13T17:10:17.468174Z","steps":["trace[730652491] 'process raft request' (duration: 171.902215ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.468356Z","caller":"traceutil/trace.go:171","msg":"trace[548709571] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"169.652612ms","start":"2026-04-13T17:10:17.298687Z","end":"2026-04-13T17:10:17.46834Z","steps":["trace[548709571] 'process raft request' (duration: 169.413248ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.468488Z","caller":"traceutil/trace.go:171","msg":"trace[1928355033] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"172.14308ms","start":"2026-04-13T17:10:17.296308Z","end":"2026-04-13T17:10:17.468451Z","steps":["trace[1928355033] 'process raft request' (duration: 171.750302ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.468651Z","caller":"traceutil/trace.go:171","msg":"trace[447717648] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"169.089812ms","start":"2026-04-13T17:10:17.299526Z","end":"2026-04-13T17:10:17.468616Z","steps":["trace[447717648] 'process raft request' (duration: 168.700234ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.468519Z","caller":"traceutil/trace.go:171","msg":"trace[537884279] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"167.110916ms","start":"2026-04-13T17:10:17.301381Z","end":"2026-04-13T17:10:17.468492Z","steps":["trace[537884279] 'process raft request' (duration: 166.917091ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.468989Z","caller":"traceutil/trace.go:171","msg":"trace[1119367156] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"166.221398ms","start":"2026-04-13T17:10:17.302724Z","end":"2026-04-13T17:10:17.468945Z","steps":["trace[1119367156] 'process raft request' (duration: 166.052555ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.468996Z","caller":"traceutil/trace.go:171","msg":"trace[907569946] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"166.521654ms","start":"2026-04-13T17:10:17.302457Z","end":"2026-04-13T17:10:17.468978Z","steps":["trace[907569946] 'process raft request' (duration: 165.935223ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.472694Z","caller":"traceutil/trace.go:171","msg":"trace[1818451473] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:504; }","duration":"122.921404ms","start":"2026-04-13T17:10:17.349756Z","end":"2026-04-13T17:10:17.472678Z","steps":["trace[1818451473] 'read index received' (duration: 115.611306ms)","trace[1818451473] 'applied index is now lower than readState.Index' (duration: 7.309138ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:10:17.472888Z","caller":"traceutil/trace.go:171","msg":"trace[1718537985] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"130.540138ms","start":"2026-04-13T17:10:17.342235Z","end":"2026-04-13T17:10:17.472775Z","steps":["trace[1718537985] 'process raft request' (duration: 130.338844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:17.472907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.144408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T17:10:17.472972Z","caller":"traceutil/trace.go:171","msg":"trace[323632323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"123.25217ms","start":"2026-04-13T17:10:17.34971Z","end":"2026-04-13T17:10:17.472962Z","steps":["trace[323632323] 'agreement among raft nodes before linearized reading' (duration: 123.087997ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.473045Z","caller":"traceutil/trace.go:171","msg":"trace[126179770] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"130.757431ms","start":"2026-04-13T17:10:17.342269Z","end":"2026-04-13T17:10:17.473026Z","steps":["trace[126179770] 'process raft request' (duration: 130.350314ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:17.472716Z","caller":"traceutil/trace.go:171","msg":"trace[2146568497] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"137.756353ms","start":"2026-04-13T17:10:17.334943Z","end":"2026-04-13T17:10:17.472699Z","steps":["trace[2146568497] 'process raft request' (duration: 137.542858ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:22.649705Z","caller":"traceutil/trace.go:171","msg":"trace[1662008096] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"102.0114ms","start":"2026-04-13T17:10:22.547662Z","end":"2026-04-13T17:10:22.649673Z","steps":["trace[1662008096] 'read index received' (duration: 78.399015ms)","trace[1662008096] 'applied index is now lower than readState.Index' (duration: 23.611085ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:10:22.649856Z","caller":"traceutil/trace.go:171","msg":"trace[2099965310] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"285.782148ms","start":"2026-04-13T17:10:22.364035Z","end":"2026-04-13T17:10:22.649817Z","steps":["trace[2099965310] 'process raft request' (duration: 262.004141ms)","trace[2099965310] 'compare' (duration: 23.417641ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:10:22.649968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.267034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:10:22.650017Z","caller":"traceutil/trace.go:171","msg":"trace[1337782164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"102.390187ms","start":"2026-04-13T17:10:22.547612Z","end":"2026-04-13T17:10:22.650002Z","steps":["trace[1337782164] 'agreement among raft nodes before linearized reading' (duration: 102.212104ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:23.878998Z","caller":"traceutil/trace.go:171","msg":"trace[184744078] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"270.687294ms","start":"2026-04-13T17:10:23.608273Z","end":"2026-04-13T17:10:23.87896Z","steps":["trace[184744078] 'read index received' (duration: 270.121074ms)","trace[184744078] 'applied index is now lower than readState.Index' (duration: 565.09µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:10:23.879023Z","caller":"traceutil/trace.go:171","msg":"trace[112197396] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"274.859413ms","start":"2026-04-13T17:10:23.604131Z","end":"2026-04-13T17:10:23.87899Z","steps":["trace[112197396] 'process raft request' (duration: 274.357584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:23.879307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.021711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T17:10:23.879367Z","caller":"traceutil/trace.go:171","msg":"trace[344955555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"271.094182ms","start":"2026-04-13T17:10:23.608237Z","end":"2026-04-13T17:10:23.879331Z","steps":["trace[344955555] 'agreement among raft nodes before linearized reading' (duration: 270.867438ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:24.372219Z","caller":"traceutil/trace.go:171","msg":"trace[634369576] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"488.229528ms","start":"2026-04-13T17:10:23.883959Z","end":"2026-04-13T17:10:24.372188Z","steps":["trace[634369576] 'process raft request' (duration: 487.953783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:24.372758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:23.883938Z","time spent":"488.458873ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:10:24.400264Z","caller":"traceutil/trace.go:171","msg":"trace[1566402780] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"515.162686ms","start":"2026-04-13T17:10:23.885071Z","end":"2026-04-13T17:10:24.400234Z","steps":["trace[1566402780] 'process raft request' (duration: 514.998883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:24.400466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:23.885052Z","time spent":"515.31976ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:10:24.400617Z","caller":"traceutil/trace.go:171","msg":"trace[1417643892] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:601; }","duration":"372.190375ms","start":"2026-04-13T17:10:24.028415Z","end":"2026-04-13T17:10:24.400605Z","steps":["trace[1417643892] 'read index received' (duration: 343.461234ms)","trace[1417643892] 'applied index is now lower than readState.Index' (duration: 28.727961ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:10:24.400624Z","caller":"traceutil/trace.go:171","msg":"trace[59370645] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"459.009179ms","start":"2026-04-13T17:10:23.941597Z","end":"2026-04-13T17:10:24.400606Z","steps":["trace[59370645] 'process raft request' (duration: 458.852486ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:24.400618Z","caller":"traceutil/trace.go:171","msg":"trace[1982802384] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"515.132376ms","start":"2026-04-13T17:10:23.885461Z","end":"2026-04-13T17:10:24.400594Z","steps":["trace[1982802384] 'process raft request' (duration: 514.735767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:24.400726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:23.941573Z","time spent":"459.102711ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:10:24.400747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:23.885447Z","time spent":"515.236837ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:10:24.400789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.370598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9c6np\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-13T17:10:24.400823Z","caller":"traceutil/trace.go:171","msg":"trace[542781682] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9c6np; range_end:; response_count:1; response_revision:582; }","duration":"372.44232ms","start":"2026-04-13T17:10:24.02837Z","end":"2026-04-13T17:10:24.400812Z","steps":["trace[542781682] 'agreement among raft nodes before linearized reading' (duration: 372.272256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:24.400861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:24.028352Z","time spent":"372.50289ms","remote":"127.0.0.1:43854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-9c6np\" "} {"level":"warn","ts":"2026-04-13T17:10:25.049484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174728586266883547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:10:25.93983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:25.002376Z","time spent":"937.449656ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-13T17:10:25.940391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.295537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:10:25.940502Z","caller":"traceutil/trace.go:171","msg":"trace[289479060] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.522262892s","start":"2026-04-13T17:10:24.418221Z","end":"2026-04-13T17:10:25.940483Z","steps":["trace[289479060] 'process raft request' (duration: 852.356633ms)","trace[289479060] 'compare' (duration: 669.045333ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:10:25.940707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:24.418199Z","time spent":"1.522454926s","remote":"127.0.0.1:43854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:10:26.006594Z","caller":"traceutil/trace.go:171","msg":"trace[467950287] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"1.457268219s","start":"2026-04-13T17:10:24.549278Z","end":"2026-04-13T17:10:26.006546Z","steps":["trace[467950287] 'read index received' (duration: 721.236945ms)","trace[467950287] 'applied index is now lower than readState.Index' (duration: 736.030354ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:10:26.006679Z","caller":"traceutil/trace.go:171","msg":"trace[1406491282] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.220726317s","start":"2026-04-13T17:10:24.785926Z","end":"2026-04-13T17:10:26.006653Z","steps":["trace[1406491282] 'process raft request' (duration: 1.21989204s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:26.006734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457448001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:10:26.006776Z","caller":"traceutil/trace.go:171","msg":"trace[1404879561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"1.457542363s","start":"2026-04-13T17:10:24.54922Z","end":"2026-04-13T17:10:26.006762Z","steps":["trace[1404879561] 'agreement among raft nodes before linearized reading' (duration: 1.457444612s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:26.006842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:24.549172Z","time spent":"1.457627305s","remote":"127.0.0.1:43708","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-04-13T17:10:26.006837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:24.785906Z","time spent":"1.220850538s","remote":"127.0.0.1:43770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:10:26.060663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.127187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:10:26.060698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.332123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:10:26.06074Z","caller":"traceutil/trace.go:171","msg":"trace[908577527] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:584; }","duration":"200.224519ms","start":"2026-04-13T17:10:25.860498Z","end":"2026-04-13T17:10:26.060722Z","steps":["trace[908577527] 'agreement among raft nodes before linearized reading' (duration: 200.116387ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:26.060779Z","caller":"traceutil/trace.go:171","msg":"trace[1900051925] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:584; }","duration":"173.470035ms","start":"2026-04-13T17:10:25.887292Z","end":"2026-04-13T17:10:26.060762Z","steps":["trace[1900051925] 'agreement among raft nodes before linearized reading' (duration: 173.293072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:26.061056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.554055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:10:26.061111Z","caller":"traceutil/trace.go:171","msg":"trace[1420344623] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:584; }","duration":"200.630817ms","start":"2026-04-13T17:10:25.860467Z","end":"2026-04-13T17:10:26.061097Z","steps":["trace[1420344623] 'agreement among raft nodes before linearized reading' (duration: 200.547275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:26.06129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.561798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:10:26.061312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.0095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:10:26.061341Z","caller":"traceutil/trace.go:171","msg":"trace[1978290812] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:584; }","duration":"104.62645ms","start":"2026-04-13T17:10:25.956706Z","end":"2026-04-13T17:10:26.061332Z","steps":["trace[1978290812] 'agreement among raft nodes before linearized reading' (duration: 104.563659ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:26.061349Z","caller":"traceutil/trace.go:171","msg":"trace[978459954] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:584; }","duration":"238.071051ms","start":"2026-04-13T17:10:25.823266Z","end":"2026-04-13T17:10:26.061338Z","steps":["trace[978459954] 'agreement among raft nodes before linearized reading' (duration: 238.01136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:26.061448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.051097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:10:26.061493Z","caller":"traceutil/trace.go:171","msg":"trace[1868137863] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:584; }","duration":"105.099468ms","start":"2026-04-13T17:10:25.956387Z","end":"2026-04-13T17:10:26.061486Z","steps":["trace[1868137863] 'agreement among raft nodes before linearized reading' (duration: 105.041957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:26.061598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.289101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:10:26.061642Z","caller":"traceutil/trace.go:171","msg":"trace[907195486] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:584; }","duration":"105.360572ms","start":"2026-04-13T17:10:25.956276Z","end":"2026-04-13T17:10:26.061637Z","steps":["trace[907195486] 'agreement among raft nodes before linearized reading' (duration: 105.304881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:26.06175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.958005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:10:26.06174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.648941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:10:26.061816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.034416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:10:26.061815Z","caller":"traceutil/trace.go:171","msg":"trace[658138036] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:584; }","duration":"238.756163ms","start":"2026-04-13T17:10:25.82304Z","end":"2026-04-13T17:10:26.061796Z","steps":["trace[658138036] 'agreement among raft nodes before linearized reading' (duration: 238.59672ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:26.06184Z","caller":"traceutil/trace.go:171","msg":"trace[1682325249] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:584; }","duration":"148.073407ms","start":"2026-04-13T17:10:25.913761Z","end":"2026-04-13T17:10:26.061835Z","steps":["trace[1682325249] 'agreement among raft nodes before linearized reading' (duration: 148.026636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:26.061931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.175259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:10:26.061998Z","caller":"traceutil/trace.go:171","msg":"trace[1899350545] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:584; }","duration":"148.246551ms","start":"2026-04-13T17:10:25.913736Z","end":"2026-04-13T17:10:26.061982Z","steps":["trace[1899350545] 'agreement among raft nodes before linearized reading' (duration: 148.149599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:26.062098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.097719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:10:26.062141Z","caller":"traceutil/trace.go:171","msg":"trace[2022677366] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:584; }","duration":"239.170631ms","start":"2026-04-13T17:10:25.822959Z","end":"2026-04-13T17:10:26.062129Z","steps":["trace[2022677366] 'agreement among raft nodes before linearized reading' (duration: 239.09114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:26.062316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.176218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:10:26.062387Z","caller":"traceutil/trace.go:171","msg":"trace[787442025] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:584; }","duration":"174.25232ms","start":"2026-04-13T17:10:25.888116Z","end":"2026-04-13T17:10:26.062368Z","steps":["trace[787442025] 'agreement among raft nodes before linearized reading' (duration: 174.163118ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:26.061775Z","caller":"traceutil/trace.go:171","msg":"trace[2117403567] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:584; }","duration":"148.021476ms","start":"2026-04-13T17:10:25.913747Z","end":"2026-04-13T17:10:26.061769Z","steps":["trace[2117403567] 'agreement among raft nodes before linearized reading' (duration: 147.983895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:26.062591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.132586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:10:26.06263Z","caller":"traceutil/trace.go:171","msg":"trace[66241401] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:584; }","duration":"175.206328ms","start":"2026-04-13T17:10:25.887414Z","end":"2026-04-13T17:10:26.06262Z","steps":["trace[66241401] 'agreement among raft nodes before linearized reading' (duration: 175.145937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:26.062375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.293663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T17:10:26.062843Z","caller":"traceutil/trace.go:171","msg":"trace[526956455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"503.795893ms","start":"2026-04-13T17:10:25.559037Z","end":"2026-04-13T17:10:26.062833Z","steps":["trace[526956455] 'agreement among raft nodes before linearized reading' (duration: 503.230762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:26.062891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:25.559018Z","time spent":"503.861054ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T17:10:41.400256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.123475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-csck8\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-13T17:10:41.40037Z","caller":"traceutil/trace.go:171","msg":"trace[216360399] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-csck8; range_end:; response_count:1; response_revision:742; }","duration":"269.323268ms","start":"2026-04-13T17:10:41.131023Z","end":"2026-04-13T17:10:41.400346Z","steps":["trace[216360399] 'range keys from in-memory index tree' (duration: 268.958472ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:41.928013Z","caller":"traceutil/trace.go:171","msg":"trace[927276197] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"377.847752ms","start":"2026-04-13T17:10:41.550134Z","end":"2026-04-13T17:10:41.927982Z","steps":["trace[927276197] 'read index received' (duration: 377.75555ms)","trace[927276197] 'applied index is now lower than readState.Index' (duration: 90.122µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:10:41.928228Z","caller":"traceutil/trace.go:171","msg":"trace[729095790] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"521.158179ms","start":"2026-04-13T17:10:41.407038Z","end":"2026-04-13T17:10:41.928196Z","steps":["trace[729095790] 'process raft request' (duration: 520.766622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:41.928937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:41.407016Z","time spent":"521.834332ms","remote":"127.0.0.1:43854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:10:41.92834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.187719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:10:41.929447Z","caller":"traceutil/trace.go:171","msg":"trace[2039905288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"379.34663ms","start":"2026-04-13T17:10:41.550072Z","end":"2026-04-13T17:10:41.929419Z","steps":["trace[2039905288] 'agreement among raft nodes before linearized reading' (duration: 378.138877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:41.929723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:41.550049Z","time spent":"379.646746ms","remote":"127.0.0.1:43708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T17:11:17.044847Z","caller":"traceutil/trace.go:171","msg":"trace[1362724512] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"709.98348ms","start":"2026-04-13T17:11:16.334843Z","end":"2026-04-13T17:11:17.044826Z","steps":["trace[1362724512] 'process raft request' (duration: 709.872538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:17.044969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:16.334826Z","time spent":"710.084652ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:11:17.116345Z","caller":"traceutil/trace.go:171","msg":"trace[1297895980] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"568.055494ms","start":"2026-04-13T17:11:16.548263Z","end":"2026-04-13T17:11:17.116319Z","steps":["trace[1297895980] 'read index received' (duration: 496.582811ms)","trace[1297895980] 'applied index is now lower than readState.Index' (duration: 71.471543ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:17.116484Z","caller":"traceutil/trace.go:171","msg":"trace[1550228638] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"754.262344ms","start":"2026-04-13T17:11:16.362207Z","end":"2026-04-13T17:11:17.11647Z","steps":["trace[1550228638] 'process raft request' (duration: 753.957658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:17.11663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:16.362183Z","time spent":"754.344245ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:11:17.116841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.140498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T17:11:17.116915Z","caller":"traceutil/trace.go:171","msg":"trace[104804778] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:965; }","duration":"487.257829ms","start":"2026-04-13T17:11:16.62964Z","end":"2026-04-13T17:11:17.116898Z","steps":["trace[104804778] 'agreement among raft nodes before linearized reading' (duration: 487.069685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:17.116954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:16.629619Z","time spent":"487.327341ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T17:11:17.117086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.586329ms","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-04-13T17:11:17.117124Z","caller":"traceutil/trace.go:171","msg":"trace[1092284551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:965; }","duration":"430.662931ms","start":"2026-04-13T17:11:16.68645Z","end":"2026-04-13T17:11:17.117113Z","steps":["trace[1092284551] 'agreement among raft nodes before linearized reading' (duration: 430.533819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:17.117154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:16.686426Z","time spent":"430.721652ms","remote":"127.0.0.1:43934","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-04-13T17:11:17.117241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.983121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:11:17.117269Z","caller":"traceutil/trace.go:171","msg":"trace[1759229222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"569.030363ms","start":"2026-04-13T17:11:16.548226Z","end":"2026-04-13T17:11:17.117256Z","steps":["trace[1759229222] 'agreement among raft nodes before linearized reading' (duration: 568.995002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:17.1173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:16.548208Z","time spent":"569.084483ms","remote":"127.0.0.1:43714","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-04-13T17:11:17.117483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.722409ms","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-04-13T17:11:17.117563Z","caller":"traceutil/trace.go:171","msg":"trace[276235270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:965; }","duration":"431.828842ms","start":"2026-04-13T17:11:16.685719Z","end":"2026-04-13T17:11:17.117548Z","steps":["trace[276235270] 'agreement among raft nodes before linearized reading' (duration: 431.652079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:17.117652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:16.6857Z","time spent":"431.889783ms","remote":"127.0.0.1:43934","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-04-13T17:11:17.579178Z","caller":"traceutil/trace.go:171","msg":"trace[872209198] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"457.447962ms","start":"2026-04-13T17:11:17.121699Z","end":"2026-04-13T17:11:17.579147Z","steps":["trace[872209198] 'process raft request' (duration: 405.511079ms)","trace[872209198] 'compare' (duration: 51.825001ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:17.579338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:17.121686Z","time spent":"457.562904ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:11:17.764031Z","caller":"traceutil/trace.go:171","msg":"trace[1209207415] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"642.192776ms","start":"2026-04-13T17:11:17.121813Z","end":"2026-04-13T17:11:17.764006Z","steps":["trace[1209207415] 'process raft request' (duration: 640.664028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:17.764201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:17.121786Z","time spent":"642.319038ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:11:17.764359Z","caller":"traceutil/trace.go:171","msg":"trace[2107987456] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1005; }","duration":"214.795225ms","start":"2026-04-13T17:11:17.549537Z","end":"2026-04-13T17:11:17.764332Z","steps":["trace[2107987456] 'read index received' (duration: 212.937071ms)","trace[2107987456] 'applied index is now lower than readState.Index' (duration: 1.856944ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:17.764587Z","caller":"traceutil/trace.go:171","msg":"trace[1148832560] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"641.90719ms","start":"2026-04-13T17:11:17.122667Z","end":"2026-04-13T17:11:17.764574Z","steps":["trace[1148832560] 'process raft request' (duration: 641.297949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:17.764664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:17.122649Z","time spent":"641.980561ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:11:17.764703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.148352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:11:17.764761Z","caller":"traceutil/trace.go:171","msg":"trace[120217718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"215.256793ms","start":"2026-04-13T17:11:17.549493Z","end":"2026-04-13T17:11:17.764749Z","steps":["trace[120217718] 'agreement among raft nodes before linearized reading' (duration: 214.936007ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:40.077821Z","caller":"traceutil/trace.go:171","msg":"trace[331707738] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"359.561885ms","start":"2026-04-13T17:11:39.718223Z","end":"2026-04-13T17:11:40.077785Z","steps":["trace[331707738] 'read index received' (duration: 359.382631ms)","trace[331707738] 'applied index is now lower than readState.Index' (duration: 178.393µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:40.078051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.797399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:11:40.078161Z","caller":"traceutil/trace.go:171","msg":"trace[1773084719] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1038; }","duration":"359.948921ms","start":"2026-04-13T17:11:39.718196Z","end":"2026-04-13T17:11:40.078145Z","steps":["trace[1773084719] 'agreement among raft nodes before linearized reading' (duration: 359.783428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:40.078294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:39.718184Z","time spent":"360.086364ms","remote":"127.0.0.1:37588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:11:40.078159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.744574ms","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-04-13T17:11:40.078596Z","caller":"traceutil/trace.go:171","msg":"trace[911548985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1038; }","duration":"215.218043ms","start":"2026-04-13T17:11:39.86336Z","end":"2026-04-13T17:11:40.078578Z","steps":["trace[911548985] 'agreement among raft nodes before linearized reading' (duration: 214.671714ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:40.078618Z","caller":"traceutil/trace.go:171","msg":"trace[69265529] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"389.236799ms","start":"2026-04-13T17:11:39.68936Z","end":"2026-04-13T17:11:40.078597Z","steps":["trace[69265529] 'process raft request' (duration: 388.299262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:40.078729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:39.689339Z","time spent":"389.32104ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:11:40.078201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.626422ms","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-04-13T17:11:40.078885Z","caller":"traceutil/trace.go:171","msg":"trace[1130563084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1038; }","duration":"215.321575ms","start":"2026-04-13T17:11:39.863546Z","end":"2026-04-13T17:11:40.078867Z","steps":["trace[1130563084] 'agreement among raft nodes before linearized reading' (duration: 214.570431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:41.000658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.825732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:11:41.000872Z","caller":"traceutil/trace.go:171","msg":"trace[1953620620] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1081; }","duration":"681.190022ms","start":"2026-04-13T17:11:40.319661Z","end":"2026-04-13T17:11:41.000851Z","steps":["trace[1953620620] 'read index received' (duration: 381.691831ms)","trace[1953620620] 'applied index is now lower than readState.Index' (duration: 299.496601ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:41.000975Z","caller":"traceutil/trace.go:171","msg":"trace[29571121] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"918.222935ms","start":"2026-04-13T17:11:40.082739Z","end":"2026-04-13T17:11:41.000962Z","steps":["trace[29571121] 'process raft request' (duration: 137.001396ms)","trace[29571121] 'compare' (duration: 780.69926ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:41.001049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:40.082717Z","time spent":"918.291726ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:11:41.001162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.502148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-13T17:11:41.001176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.566913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:11:41.001219Z","caller":"traceutil/trace.go:171","msg":"trace[106665507] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1040; }","duration":"681.59666ms","start":"2026-04-13T17:11:40.319608Z","end":"2026-04-13T17:11:41.001204Z","steps":["trace[106665507] 'agreement among raft nodes before linearized reading' (duration: 681.405116ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:41.001142Z","caller":"traceutil/trace.go:171","msg":"trace[1117478635] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"918.250496ms","start":"2026-04-13T17:11:40.082825Z","end":"2026-04-13T17:11:41.001076Z","steps":["trace[1117478635] 'process raft request' (duration: 917.945521ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:41.001267Z","caller":"traceutil/trace.go:171","msg":"trace[1114110007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1040; }","duration":"451.704786ms","start":"2026-04-13T17:11:40.549526Z","end":"2026-04-13T17:11:41.001231Z","steps":["trace[1114110007] 'agreement among raft nodes before linearized reading' (duration: 451.561563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:41.001285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.369644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T17:11:41.00132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:40.549496Z","time spent":"451.816008ms","remote":"127.0.0.1:43714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T17:11:41.001333Z","caller":"traceutil/trace.go:171","msg":"trace[688092400] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1040; }","duration":"112.462406ms","start":"2026-04-13T17:11:40.888858Z","end":"2026-04-13T17:11:41.001321Z","steps":["trace[688092400] 'agreement among raft nodes before linearized reading' (duration: 112.363844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:41.001452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:40.082789Z","time spent":"918.551381ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:11:41.00153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.964308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:11:41.001257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:40.319582Z","time spent":"681.66763ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T17:11:41.001567Z","caller":"traceutil/trace.go:171","msg":"trace[1698970175] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1040; }","duration":"258.038969ms","start":"2026-04-13T17:11:40.743519Z","end":"2026-04-13T17:11:41.001558Z","steps":["trace[1698970175] 'agreement among raft nodes before linearized reading' (duration: 257.969658ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:41.422663Z","caller":"traceutil/trace.go:171","msg":"trace[1239942428] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"186.21835ms","start":"2026-04-13T17:11:41.236413Z","end":"2026-04-13T17:11:41.422631Z","steps":["trace[1239942428] 'process raft request' (duration: 185.979846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:42.717764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.18902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:11:42.717849Z","caller":"traceutil/trace.go:171","msg":"trace[194008378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1046; }","duration":"167.322262ms","start":"2026-04-13T17:11:42.550509Z","end":"2026-04-13T17:11:42.717831Z","steps":["trace[194008378] 'range keys from in-memory index tree' (duration: 167.109169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:42.71785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.489936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:11:42.718151Z","caller":"traceutil/trace.go:171","msg":"trace[1939350207] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1046; }","duration":"345.839372ms","start":"2026-04-13T17:11:42.372275Z","end":"2026-04-13T17:11:42.718114Z","steps":["trace[1939350207] 'count revisions from in-memory index tree' (duration: 345.404504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:42.71826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:42.372256Z","time spent":"345.959004ms","remote":"127.0.0.1:33328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-04-13T17:11:43.433533Z","caller":"traceutil/trace.go:171","msg":"trace[656986802] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1090; }","duration":"384.716185ms","start":"2026-04-13T17:11:43.048796Z","end":"2026-04-13T17:11:43.433513Z","steps":["trace[656986802] 'read index received' (duration: 384.621664ms)","trace[656986802] 'applied index is now lower than readState.Index' (duration: 93.351µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:43.433572Z","caller":"traceutil/trace.go:171","msg":"trace[1816321480] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"423.903355ms","start":"2026-04-13T17:11:43.009645Z","end":"2026-04-13T17:11:43.433548Z","steps":["trace[1816321480] 'process raft request' (duration: 423.657921ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:43.433571Z","caller":"traceutil/trace.go:171","msg":"trace[1142772340] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"423.905735ms","start":"2026-04-13T17:11:43.009644Z","end":"2026-04-13T17:11:43.43355Z","steps":["trace[1142772340] 'process raft request' (duration: 423.830454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:43.433703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.893439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-13T17:11:43.433698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:43.009627Z","time spent":"423.999016ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:11:43.433728Z","caller":"traceutil/trace.go:171","msg":"trace[1838397620] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1048; }","duration":"384.970561ms","start":"2026-04-13T17:11:43.04875Z","end":"2026-04-13T17:11:43.433721Z","steps":["trace[1838397620] 'agreement among raft nodes before linearized reading' (duration: 384.853838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:43.43373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:43.009617Z","time spent":"424.081309ms","remote":"127.0.0.1:43934","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-04-13T17:11:43.433756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:43.048725Z","time spent":"385.024102ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T17:11:43.738892Z","caller":"traceutil/trace.go:171","msg":"trace[276118279] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"308.500665ms","start":"2026-04-13T17:11:43.430369Z","end":"2026-04-13T17:11:43.738869Z","steps":["trace[276118279] 'process raft request' (duration: 308.414344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:43.739004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:43.43032Z","time spent":"308.620999ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:11:43.739422Z","caller":"traceutil/trace.go:171","msg":"trace[256088615] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1093; }","duration":"305.830507ms","start":"2026-04-13T17:11:43.433584Z","end":"2026-04-13T17:11:43.739414Z","steps":["trace[256088615] 'read index received' (duration: 305.827067ms)","trace[256088615] 'applied index is now lower than readState.Index' (duration: 2.73µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:43.739516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.888788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-13T17:11:43.739535Z","caller":"traceutil/trace.go:171","msg":"trace[1771315422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1049; }","duration":"532.940529ms","start":"2026-04-13T17:11:43.20659Z","end":"2026-04-13T17:11:43.73953Z","steps":["trace[1771315422] 'agreement among raft nodes before linearized reading' (duration: 532.858128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:43.739555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:43.206572Z","time spent":"532.977859ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T17:11:43.756495Z","caller":"traceutil/trace.go:171","msg":"trace[1604683531] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"315.687378ms","start":"2026-04-13T17:11:43.44078Z","end":"2026-04-13T17:11:43.756467Z","steps":["trace[1604683531] 'process raft request' (duration: 315.528826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:43.756682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:43.440762Z","time spent":"315.82311ms","remote":"127.0.0.1:43934","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-04-13T17:11:45.566747Z","caller":"traceutil/trace.go:171","msg":"trace[519040550] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"129.631262ms","start":"2026-04-13T17:11:45.437091Z","end":"2026-04-13T17:11:45.566723Z","steps":["trace[519040550] 'read index received' (duration: 129.54405ms)","trace[519040550] 'applied index is now lower than readState.Index' (duration: 85.942µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:45.566866Z","caller":"traceutil/trace.go:171","msg":"trace[1752765158] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"272.174129ms","start":"2026-04-13T17:11:45.294681Z","end":"2026-04-13T17:11:45.566855Z","steps":["trace[1752765158] 'process raft request' (duration: 271.892064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:45.567151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.522529ms","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-04-13T17:11:45.567298Z","caller":"traceutil/trace.go:171","msg":"trace[2100966993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1053; }","duration":"129.701422ms","start":"2026-04-13T17:11:45.43755Z","end":"2026-04-13T17:11:45.567252Z","steps":["trace[2100966993] 'agreement among raft nodes before linearized reading' (duration: 129.457218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:45.567983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.879725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-13T17:11:45.568052Z","caller":"traceutil/trace.go:171","msg":"trace[1373928492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1053; }","duration":"131.007677ms","start":"2026-04-13T17:11:45.437028Z","end":"2026-04-13T17:11:45.568036Z","steps":["trace[1373928492] 'agreement among raft nodes before linearized reading' (duration: 130.831824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:46.421995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.482745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:11:46.422104Z","caller":"traceutil/trace.go:171","msg":"trace[1811895317] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"849.30155ms","start":"2026-04-13T17:11:45.572784Z","end":"2026-04-13T17:11:46.422086Z","steps":["trace[1811895317] 'process raft request' (duration: 631.620131ms)","trace[1811895317] 'compare' (duration: 217.341833ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:46.422497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:45.572761Z","time spent":"849.608576ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:11:46.497769Z","caller":"traceutil/trace.go:171","msg":"trace[1916714692] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"752.053783ms","start":"2026-04-13T17:11:45.745689Z","end":"2026-04-13T17:11:46.497743Z","steps":["trace[1916714692] 'process raft request' (duration: 751.992312ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:46.497811Z","caller":"traceutil/trace.go:171","msg":"trace[996144431] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"924.702485ms","start":"2026-04-13T17:11:45.573068Z","end":"2026-04-13T17:11:46.49777Z","steps":["trace[996144431] 'process raft request' (duration: 924.480191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:46.497953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:45.745657Z","time spent":"752.194095ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:11:46.498125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:45.573043Z","time spent":"924.943089ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:11:46.538779Z","caller":"traceutil/trace.go:171","msg":"trace[155942946] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1097; }","duration":"778.399827ms","start":"2026-04-13T17:11:45.760342Z","end":"2026-04-13T17:11:46.538742Z","steps":["trace[155942946] 'read index received' (duration: 444.077056ms)","trace[155942946] 'applied index is now lower than readState.Index' (duration: 334.321061ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:46.539036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.691173ms","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":"warn","ts":"2026-04-13T17:11:46.539065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.313274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-13T17:11:46.539069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.10003ms","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-04-13T17:11:46.539746Z","caller":"traceutil/trace.go:171","msg":"trace[1473914051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1056; }","duration":"429.805854ms","start":"2026-04-13T17:11:46.109925Z","end":"2026-04-13T17:11:46.539731Z","steps":["trace[1473914051] 'agreement among raft nodes before linearized reading' (duration: 429.04509ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:46.539087Z","caller":"traceutil/trace.go:171","msg":"trace[339069316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1056; }","duration":"778.796024ms","start":"2026-04-13T17:11:45.760279Z","end":"2026-04-13T17:11:46.539075Z","steps":["trace[339069316] 'agreement among raft nodes before linearized reading' (duration: 778.56901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:46.539975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:46.109909Z","time spent":"430.048938ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T17:11:46.54001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:45.760255Z","time spent":"779.738522ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T17:11:46.5391Z","caller":"traceutil/trace.go:171","msg":"trace[75213062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1056; }","duration":"748.403905ms","start":"2026-04-13T17:11:45.790686Z","end":"2026-04-13T17:11:46.53909Z","steps":["trace[75213062] 'agreement among raft nodes before linearized reading' (duration: 748.213562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:46.540198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:45.790668Z","time spent":"749.481346ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T17:11:46.539154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.854884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:11:46.540333Z","caller":"traceutil/trace.go:171","msg":"trace[208587619] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1056; }","duration":"707.155418ms","start":"2026-04-13T17:11:45.833133Z","end":"2026-04-13T17:11:46.540288Z","steps":["trace[208587619] 'agreement among raft nodes before linearized reading' (duration: 705.866244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:46.54044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:45.8331Z","time spent":"707.302431ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:11:56.877791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.281366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:11:56.877916Z","caller":"traceutil/trace.go:171","msg":"trace[809296097] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1188; }","duration":"328.457473ms","start":"2026-04-13T17:11:56.549436Z","end":"2026-04-13T17:11:56.877894Z","steps":["trace[809296097] 'read index received' (duration: 113.968282ms)","trace[809296097] 'applied index is now lower than readState.Index' (duration: 214.487111ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:56.877977Z","caller":"traceutil/trace.go:171","msg":"trace[1576298013] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"336.715825ms","start":"2026-04-13T17:11:56.541221Z","end":"2026-04-13T17:11:56.877937Z","steps":["trace[1576298013] 'process raft request' (duration: 122.187544ms)","trace[1576298013] 'compare' (duration: 214.146073ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:56.878032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.579095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:11:56.87808Z","caller":"traceutil/trace.go:171","msg":"trace[886426401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1140; }","duration":"328.667287ms","start":"2026-04-13T17:11:56.549402Z","end":"2026-04-13T17:11:56.878069Z","steps":["trace[886426401] 'agreement among raft nodes before linearized reading' (duration: 328.584366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:56.878104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:56.549383Z","time spent":"328.717398ms","remote":"127.0.0.1:43714","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-04-13T17:11:56.878149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.983584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-13T17:11:56.878154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:56.541198Z","time spent":"336.865158ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:11:56.878203Z","caller":"traceutil/trace.go:171","msg":"trace[1725853959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1140; }","duration":"328.050406ms","start":"2026-04-13T17:11:56.550141Z","end":"2026-04-13T17:11:56.878191Z","steps":["trace[1725853959] 'agreement among raft nodes before linearized reading' (duration: 327.888233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:56.878244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:56.550136Z","time spent":"328.098567ms","remote":"127.0.0.1:43934","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-04-13T17:11:56.878336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.558417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T17:11:56.87836Z","caller":"traceutil/trace.go:171","msg":"trace[2102919206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1140; }","duration":"278.801261ms","start":"2026-04-13T17:11:56.599554Z","end":"2026-04-13T17:11:56.878355Z","steps":["trace[2102919206] 'agreement among raft nodes before linearized reading' (duration: 278.71972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:56.878417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.849291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-13T17:11:56.878444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.043667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T17:11:56.878452Z","caller":"traceutil/trace.go:171","msg":"trace[795197613] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1140; }","duration":"278.913633ms","start":"2026-04-13T17:11:56.599529Z","end":"2026-04-13T17:11:56.878443Z","steps":["trace[795197613] 'agreement among raft nodes before linearized reading' (duration: 278.827271ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:56.87846Z","caller":"traceutil/trace.go:171","msg":"trace[575302701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1140; }","duration":"290.105998ms","start":"2026-04-13T17:11:56.588348Z","end":"2026-04-13T17:11:56.878454Z","steps":["trace[575302701] 'agreement among raft nodes before linearized reading' (duration: 290.064337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:59.051334Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174728586266884806,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:11:59.551977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174728586266884806,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:11:59.63046Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.096277124s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T17:11:59.630769Z","caller":"traceutil/trace.go:171","msg":"trace[1225614955] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"1.096647271s","start":"2026-04-13T17:11:58.534098Z","end":"2026-04-13T17:11:59.630745Z","steps":["trace[1225614955] 'process raft request' (duration: 1.096522329s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:59.630893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:58.53408Z","time spent":"1.096744483s","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:11:59.766113Z","caller":"traceutil/trace.go:171","msg":"trace[139187171] linearizableReadLoop","detail":"{readStateIndex:1195; appliedIndex:1193; }","duration":"1.215021083s","start":"2026-04-13T17:11:58.551062Z","end":"2026-04-13T17:11:59.766083Z","steps":["trace[139187171] 'read index received' (duration: 1.079581637s)","trace[139187171] 'applied index is now lower than readState.Index' (duration: 135.437886ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:59.76621Z","caller":"traceutil/trace.go:171","msg":"trace[726589654] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"882.105259ms","start":"2026-04-13T17:11:58.884076Z","end":"2026-04-13T17:11:59.766181Z","steps":["trace[726589654] 'process raft request' (duration: 837.391709ms)","trace[726589654] 'compare' (duration: 44.501896ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:59.766264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215190196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:11:59.766303Z","caller":"traceutil/trace.go:171","msg":"trace[2099733104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1146; }","duration":"1.215268478s","start":"2026-04-13T17:11:58.551023Z","end":"2026-04-13T17:11:59.766292Z","steps":["trace[2099733104] 'agreement among raft nodes before linearized reading' (duration: 1.215196046s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:59.766305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.294809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T17:11:59.766341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:58.551004Z","time spent":"1.2153264s","remote":"127.0.0.1:43714","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-04-13T17:11:59.766349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:58.884052Z","time spent":"882.223552ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:11:59.766405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.203806ms","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-04-13T17:11:59.766463Z","caller":"traceutil/trace.go:171","msg":"trace[527222554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1146; }","duration":"830.279297ms","start":"2026-04-13T17:11:58.936171Z","end":"2026-04-13T17:11:59.76645Z","steps":["trace[527222554] 'agreement among raft nodes before linearized reading' (duration: 830.126064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:59.766488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.336688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-13T17:11:59.766501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:58.936164Z","time spent":"830.330468ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T17:11:59.766525Z","caller":"traceutil/trace.go:171","msg":"trace[1417206992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1146; }","duration":"830.397899ms","start":"2026-04-13T17:11:58.936119Z","end":"2026-04-13T17:11:59.766517Z","steps":["trace[1417206992] 'agreement among raft nodes before linearized reading' (duration: 830.267267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:59.766563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:58.936103Z","time spent":"830.45084ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T17:11:59.766597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.160484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:11:59.766633Z","caller":"traceutil/trace.go:171","msg":"trace[1130739919] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1146; }","duration":"108.217166ms","start":"2026-04-13T17:11:59.658403Z","end":"2026-04-13T17:11:59.766621Z","steps":["trace[1130739919] 'agreement among raft nodes before linearized reading' (duration: 108.166385ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:59.76636Z","caller":"traceutil/trace.go:171","msg":"trace[1638727509] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1146; }","duration":"915.38072ms","start":"2026-04-13T17:11:58.850966Z","end":"2026-04-13T17:11:59.766346Z","steps":["trace[1638727509] 'agreement among raft nodes before linearized reading' (duration: 915.292958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:59.766666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.44655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T17:11:59.766691Z","caller":"traceutil/trace.go:171","msg":"trace[1554608148] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1146; }","duration":"830.484851ms","start":"2026-04-13T17:11:58.936198Z","end":"2026-04-13T17:11:59.766683Z","steps":["trace[1554608148] 'agreement among raft nodes before linearized reading' (duration: 830.41101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:59.766687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:58.850952Z","time spent":"915.716056ms","remote":"127.0.0.1:33500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:11:59.766708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:58.936193Z","time spent":"830.508881ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T17:11:59.76673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.166642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T17:11:59.766767Z","caller":"traceutil/trace.go:171","msg":"trace[2143276789] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1146; }","duration":"839.237422ms","start":"2026-04-13T17:11:58.927519Z","end":"2026-04-13T17:11:59.766756Z","steps":["trace[2143276789] 'agreement among raft nodes before linearized reading' (duration: 839.13505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:59.766796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:58.9275Z","time spent":"839.289904ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T17:12:11.81694Z","caller":"traceutil/trace.go:171","msg":"trace[503806290] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"116.452649ms","start":"2026-04-13T17:12:11.700469Z","end":"2026-04-13T17:12:11.816922Z","steps":["trace[503806290] 'process raft request' (duration: 116.355857ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:15.967364Z","caller":"traceutil/trace.go:171","msg":"trace[1933116857] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"104.498045ms","start":"2026-04-13T17:12:15.862822Z","end":"2026-04-13T17:12:15.96732Z","steps":["trace[1933116857] 'process raft request' (duration: 104.363781ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:15.967518Z","caller":"traceutil/trace.go:171","msg":"trace[814961655] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"103.944045ms","start":"2026-04-13T17:12:15.863566Z","end":"2026-04-13T17:12:15.96751Z","steps":["trace[814961655] 'process raft request' (duration: 103.727491ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:15.967798Z","caller":"traceutil/trace.go:171","msg":"trace[136565704] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"104.146348ms","start":"2026-04-13T17:12:15.863611Z","end":"2026-04-13T17:12:15.967758Z","steps":["trace[136565704] 'process raft request' (duration: 103.875543ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:15.967964Z","caller":"traceutil/trace.go:171","msg":"trace[1235968266] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"103.913733ms","start":"2026-04-13T17:12:15.864038Z","end":"2026-04-13T17:12:15.967952Z","steps":["trace[1235968266] 'process raft request' (duration: 103.649478ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:15.968122Z","caller":"traceutil/trace.go:171","msg":"trace[1427126600] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"103.371383ms","start":"2026-04-13T17:12:15.864742Z","end":"2026-04-13T17:12:15.968113Z","steps":["trace[1427126600] 'process raft request' (duration: 103.178309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:16.629408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.791781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:12:16.629544Z","caller":"traceutil/trace.go:171","msg":"trace[2065626254] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"266.599033ms","start":"2026-04-13T17:12:16.362924Z","end":"2026-04-13T17:12:16.629523Z","steps":["trace[2065626254] 'read index received' (duration: 32.69µs)","trace[2065626254] 'applied index is now lower than readState.Index' (duration: 266.564443ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:12:16.629656Z","caller":"traceutil/trace.go:171","msg":"trace[849478568] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"335.939406ms","start":"2026-04-13T17:12:16.2937Z","end":"2026-04-13T17:12:16.62964Z","steps":["trace[849478568] 'process raft request' (duration: 14.787377ms)","trace[849478568] 'compare' (duration: 320.630608ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:12:16.62974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:16.293682Z","time spent":"336.019187ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:12:16.629848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.904118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:12:16.630075Z","caller":"traceutil/trace.go:171","msg":"trace[509925979] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1226; }","duration":"267.164613ms","start":"2026-04-13T17:12:16.362884Z","end":"2026-04-13T17:12:16.630048Z","steps":["trace[509925979] 'agreement among raft nodes before linearized reading' (duration: 266.871468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:17.369972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.351412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:12:17.370133Z","caller":"traceutil/trace.go:171","msg":"trace[824007830] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"732.265407ms","start":"2026-04-13T17:12:16.637815Z","end":"2026-04-13T17:12:17.37008Z","steps":["trace[824007830] 'process raft request' (duration: 603.729551ms)","trace[824007830] 'compare' (duration: 128.20508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:12:17.370211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:16.637795Z","time spent":"732.38245ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:12:17.400567Z","caller":"traceutil/trace.go:171","msg":"trace[304462219] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"724.47119ms","start":"2026-04-13T17:12:16.676076Z","end":"2026-04-13T17:12:17.400548Z","steps":["trace[304462219] 'process raft request' (duration: 724.382838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:17.400696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:16.676052Z","time spent":"724.587372ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:12:17.624761Z","caller":"traceutil/trace.go:171","msg":"trace[842669602] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"248.700064ms","start":"2026-04-13T17:12:17.376034Z","end":"2026-04-13T17:12:17.624734Z","steps":["trace[842669602] 'process raft request' (duration: 196.3244ms)","trace[842669602] 'compare' (duration: 52.047358ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:12:17.65707Z","caller":"traceutil/trace.go:171","msg":"trace[1614935471] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"248.076914ms","start":"2026-04-13T17:12:17.408965Z","end":"2026-04-13T17:12:17.657042Z","steps":["trace[1614935471] 'process raft request' (duration: 247.89106ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:17.657207Z","caller":"traceutil/trace.go:171","msg":"trace[812211871] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"248.173246ms","start":"2026-04-13T17:12:17.409008Z","end":"2026-04-13T17:12:17.657182Z","steps":["trace[812211871] 'process raft request' (duration: 247.989412ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:18.360113Z","caller":"traceutil/trace.go:171","msg":"trace[944480288] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"381.635005ms","start":"2026-04-13T17:12:17.978451Z","end":"2026-04-13T17:12:18.360086Z","steps":["trace[944480288] 'process raft request' (duration: 381.564724ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:18.360214Z","caller":"traceutil/trace.go:171","msg":"trace[1994799419] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"381.674596ms","start":"2026-04-13T17:12:17.978532Z","end":"2026-04-13T17:12:18.360207Z","steps":["trace[1994799419] 'process raft request' (duration: 381.514483ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:18.360189Z","caller":"traceutil/trace.go:171","msg":"trace[1572735533] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"383.005831ms","start":"2026-04-13T17:12:17.977146Z","end":"2026-04-13T17:12:18.360152Z","steps":["trace[1572735533] 'process raft request' (duration: 382.834107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:18.360285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:17.978431Z","time spent":"381.766607ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:12:18.360123Z","caller":"traceutil/trace.go:171","msg":"trace[98350100] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"383.074332ms","start":"2026-04-13T17:12:17.977015Z","end":"2026-04-13T17:12:18.360089Z","steps":["trace[98350100] 'process raft request' (duration: 382.822727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:18.360322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:17.978516Z","time spent":"381.732807ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:12:18.360461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:17.977005Z","time spent":"383.349547ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:12:18.3608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:17.977112Z","time spent":"383.166994ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:12:20.119166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.828852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T17:12:20.119244Z","caller":"traceutil/trace.go:171","msg":"trace[1726240551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"112.982554ms","start":"2026-04-13T17:12:20.006243Z","end":"2026-04-13T17:12:20.119226Z","steps":["trace[1726240551] 'range keys from in-memory index tree' (duration: 112.609817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:28.785443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.689207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-13T17:12:28.785451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.097125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-13T17:12:28.785487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.613194ms","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-04-13T17:12:28.785504Z","caller":"traceutil/trace.go:171","msg":"trace[915741783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1306; }","duration":"380.782038ms","start":"2026-04-13T17:12:28.404707Z","end":"2026-04-13T17:12:28.785489Z","steps":["trace[915741783] 'range keys from in-memory index tree' (duration: 380.519632ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:28.785508Z","caller":"traceutil/trace.go:171","msg":"trace[1945315255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1306; }","duration":"381.182596ms","start":"2026-04-13T17:12:28.404313Z","end":"2026-04-13T17:12:28.785496Z","steps":["trace[1945315255] 'range keys from in-memory index tree' (duration: 381.005762ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:28.785538Z","caller":"traceutil/trace.go:171","msg":"trace[546883281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1306; }","duration":"382.721645ms","start":"2026-04-13T17:12:28.402804Z","end":"2026-04-13T17:12:28.785526Z","steps":["trace[546883281] 'range keys from in-memory index tree' (duration: 382.431739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:28.785547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:28.404699Z","time spent":"380.837869ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T17:12:28.785562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:28.402781Z","time spent":"382.776647ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T17:12:28.78556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:28.404302Z","time spent":"381.242567ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T17:12:28.785569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.851314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:12:28.785618Z","caller":"traceutil/trace.go:171","msg":"trace[1330989165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1306; }","duration":"235.902675ms","start":"2026-04-13T17:12:28.549703Z","end":"2026-04-13T17:12:28.785606Z","steps":["trace[1330989165] 'range keys from in-memory index tree' (duration: 235.777943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:28.78567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.062487ms","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-04-13T17:12:28.785764Z","caller":"traceutil/trace.go:171","msg":"trace[1909430784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1306; }","duration":"379.207859ms","start":"2026-04-13T17:12:28.406534Z","end":"2026-04-13T17:12:28.785742Z","steps":["trace[1909430784] 'range keys from in-memory index tree' (duration: 378.875063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:28.785812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:28.406514Z","time spent":"379.288421ms","remote":"127.0.0.1:43934","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-04-13T17:12:29.516857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174728586266885131,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:12:29.897248Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.107762147s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T17:12:29.897645Z","caller":"traceutil/trace.go:171","msg":"trace[1299468412] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"1.108354857s","start":"2026-04-13T17:12:28.789258Z","end":"2026-04-13T17:12:29.897612Z","steps":["trace[1299468412] 'process raft request' (duration: 1.108191494s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:29.897809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:28.789247Z","time spent":"1.10846265s","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:12:29.954168Z","caller":"traceutil/trace.go:171","msg":"trace[1873836274] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1361; }","duration":"938.32802ms","start":"2026-04-13T17:12:29.015813Z","end":"2026-04-13T17:12:29.954141Z","steps":["trace[1873836274] 'read index received' (duration: 881.728737ms)","trace[1873836274] 'applied index is now lower than readState.Index' (duration: 56.598373ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:12:29.954637Z","caller":"traceutil/trace.go:171","msg":"trace[1087101147] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"1.164283059s","start":"2026-04-13T17:12:28.790339Z","end":"2026-04-13T17:12:29.954622Z","steps":["trace[1087101147] 'process raft request' (duration: 1.163767169s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:29.954814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.988272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-13T17:12:29.954847Z","caller":"traceutil/trace.go:171","msg":"trace[2049938848] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"1.165387639s","start":"2026-04-13T17:12:28.789422Z","end":"2026-04-13T17:12:29.95481Z","steps":["trace[2049938848] 'process raft request' (duration: 1.164490122s)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:29.954982Z","caller":"traceutil/trace.go:171","msg":"trace[1860496851] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1310; }","duration":"939.174926ms","start":"2026-04-13T17:12:29.015716Z","end":"2026-04-13T17:12:29.954891Z","steps":["trace[1860496851] 'agreement among raft nodes before linearized reading' (duration: 938.915971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:29.955011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:28.789416Z","time spent":"1.165515532s","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:12:29.955038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:29.015688Z","time spent":"939.335649ms","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":547,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-13T17:12:29.955056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:28.790328Z","time spent":"1.16435382s","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:12:29.956034Z","caller":"traceutil/trace.go:171","msg":"trace[1924123199] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"1.165478591s","start":"2026-04-13T17:12:28.789788Z","end":"2026-04-13T17:12:29.955266Z","steps":["trace[1924123199] 'process raft request' (duration: 1.164279498s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:29.956115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:28.789765Z","time spent":"1.166319936s","remote":"127.0.0.1:43934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:12:29.956579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.711015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:12:29.95662Z","caller":"traceutil/trace.go:171","msg":"trace[230663761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"406.778966ms","start":"2026-04-13T17:12:29.54983Z","end":"2026-04-13T17:12:29.956609Z","steps":["trace[230663761] 'agreement among raft nodes before linearized reading' (duration: 406.686184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:29.956644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:29.549814Z","time spent":"406.825437ms","remote":"127.0.0.1:43714","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-04-13T17:12:29.956957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.384323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:12:29.956993Z","caller":"traceutil/trace.go:171","msg":"trace[2130810304] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1311; }","duration":"253.448973ms","start":"2026-04-13T17:12:29.703531Z","end":"2026-04-13T17:12:29.95698Z","steps":["trace[2130810304] 'agreement among raft nodes before linearized reading' (duration: 253.403443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:10.338655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.137791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-13T17:13:10.338703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.484188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-13T17:13:10.338778Z","caller":"traceutil/trace.go:171","msg":"trace[90731234] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1495; }","duration":"109.58778ms","start":"2026-04-13T17:13:10.229171Z","end":"2026-04-13T17:13:10.338759Z","steps":["trace[90731234] 'range keys from in-memory index tree' (duration: 109.317315ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:10.338758Z","caller":"traceutil/trace.go:171","msg":"trace[588970423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1495; }","duration":"110.319114ms","start":"2026-04-13T17:13:10.228413Z","end":"2026-04-13T17:13:10.338732Z","steps":["trace[588970423] 'range keys from in-memory index tree' (duration: 109.982947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:28.396675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.15558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:13:28.397074Z","caller":"traceutil/trace.go:171","msg":"trace[1226025304] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"156.520952ms","start":"2026-04-13T17:13:28.240469Z","end":"2026-04-13T17:13:28.39699Z","steps":["trace[1226025304] 'process raft request' (duration: 45.917033ms)","trace[1226025304] 'compare' (duration: 109.957647ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:13:28.611497Z","caller":"traceutil/trace.go:171","msg":"trace[667661253] linearizableReadLoop","detail":"{readStateIndex:1647; appliedIndex:1646; }","duration":"122.288028ms","start":"2026-04-13T17:13:28.489176Z","end":"2026-04-13T17:13:28.611464Z","steps":["trace[667661253] 'read index received' (duration: 74.943309ms)","trace[667661253] 'applied index is now lower than readState.Index' (duration: 47.341819ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:13:28.611558Z","caller":"traceutil/trace.go:171","msg":"trace[731659630] transaction","detail":"{read_only:false; response_revision:1580; number_of_response:1; }","duration":"137.435833ms","start":"2026-04-13T17:13:28.474088Z","end":"2026-04-13T17:13:28.611523Z","steps":["trace[731659630] 'process raft request' (duration: 90.054384ms)","trace[731659630] 'compare' (duration: 47.188406ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:13:28.61181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.586374ms","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-04-13T17:13:28.611883Z","caller":"traceutil/trace.go:171","msg":"trace[2106168576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1580; }","duration":"122.723517ms","start":"2026-04-13T17:13:28.489142Z","end":"2026-04-13T17:13:28.611865Z","steps":["trace[2106168576] 'agreement among raft nodes before linearized reading' (duration: 122.467192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:28.612583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.156514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-13T17:13:28.612647Z","caller":"traceutil/trace.go:171","msg":"trace[1026951241] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1580; }","duration":"115.252625ms","start":"2026-04-13T17:13:28.497376Z","end":"2026-04-13T17:13:28.612629Z","steps":["trace[1026951241] 'agreement among raft nodes before linearized reading' (duration: 114.524812ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:28.78019Z","caller":"traceutil/trace.go:171","msg":"trace[2013028586] transaction","detail":"{read_only:false; response_revision:1581; number_of_response:1; }","duration":"164.226906ms","start":"2026-04-13T17:13:28.615935Z","end":"2026-04-13T17:13:28.780162Z","steps":["trace[2013028586] 'process raft request' (duration: 161.560445ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:28.809093Z","caller":"traceutil/trace.go:171","msg":"trace[1295402346] linearizableReadLoop","detail":"{readStateIndex:1649; appliedIndex:1647; }","duration":"181.612102ms","start":"2026-04-13T17:13:28.627451Z","end":"2026-04-13T17:13:28.809063Z","steps":["trace[1295402346] 'read index received' (duration: 150.107731ms)","trace[1295402346] 'applied index is now lower than readState.Index' (duration: 31.503301ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:13:28.809232Z","caller":"traceutil/trace.go:171","msg":"trace[1463088131] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"192.654431ms","start":"2026-04-13T17:13:28.616543Z","end":"2026-04-13T17:13:28.809197Z","steps":["trace[1463088131] 'process raft request' (duration: 192.386055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:28.809433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.962129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T17:13:28.809501Z","caller":"traceutil/trace.go:171","msg":"trace[642629783] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1582; }","duration":"182.062502ms","start":"2026-04-13T17:13:28.627426Z","end":"2026-04-13T17:13:28.809489Z","steps":["trace[642629783] 'agreement among raft nodes before linearized reading' (duration: 181.737506ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:43.065225Z","caller":"traceutil/trace.go:171","msg":"trace[697080687] transaction","detail":"{read_only:false; response_revision:1646; number_of_response:1; }","duration":"110.385653ms","start":"2026-04-13T17:13:42.954807Z","end":"2026-04-13T17:13:43.065192Z","steps":["trace[697080687] 'process raft request' (duration: 41.409768ms)","trace[697080687] 'compare' (duration: 68.342454ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:15:25.757841Z","caller":"traceutil/trace.go:171","msg":"trace[698914364] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"130.972457ms","start":"2026-04-13T17:15:25.626839Z","end":"2026-04-13T17:15:25.757811Z","steps":["trace[698914364] 'process raft request' (duration: 130.58208ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:48.339074Z","caller":"traceutil/trace.go:171","msg":"trace[592940218] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"135.394661ms","start":"2026-04-13T17:15:48.203654Z","end":"2026-04-13T17:15:48.339048Z","steps":["trace[592940218] 'process raft request' (duration: 135.245538ms)"],"step_count":1}