{"level":"warn","ts":"2026-06-15T20:01:36.811077Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T20:01:36.81118Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.18: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.18:2380","--initial-cluster=instance=https://199.19.213.18:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.18:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.18: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-06-15T20:01:36.811256Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T20:01:36.811273Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.18:2380"]} {"level":"info","ts":"2026-06-15T20:01:36.811293Z","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-06-15T20:01:36.812183Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"]} {"level":"info","ts":"2026-06-15T20:01:36.812285Z","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.18:2380"],"listen-peer-urls":["https://199.19.213.18:2380"],"advertise-client-urls":["https://199.19.213.18:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.18: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-06-15T20:01:36.81667Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.061891ms"} {"level":"info","ts":"2026-06-15T20:01:36.829871Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6d0358acb671c233","cluster-id":"ba212af698de913f"} {"level":"info","ts":"2026-06-15T20:01:36.830001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=()"} {"level":"info","ts":"2026-06-15T20:01:36.830106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became follower at term 0"} {"level":"info","ts":"2026-06-15T20:01:36.830136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6d0358acb671c233 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-15T20:01:36.830272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became follower at term 1"} {"level":"info","ts":"2026-06-15T20:01:36.830371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=(7855219673882804787)"} {"level":"warn","ts":"2026-06-15T20:01:36.84052Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-15T20:01:36.842007Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-15T20:01:36.843295Z","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-06-15T20:01:36.846481Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6d0358acb671c233","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-15T20:01:36.846842Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6d0358acb671c233","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-15T20:01:36.84688Z","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-06-15T20:01:36.848301Z","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-06-15T20:01:36.848323Z","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-06-15T20:01:36.851501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=(7855219673882804787)"} {"level":"info","ts":"2026-06-15T20:01:36.851611Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba212af698de913f","local-member-id":"6d0358acb671c233","added-peer-id":"6d0358acb671c233","added-peer-peer-urls":["https://199.19.213.18:2380"]} {"level":"info","ts":"2026-06-15T20:01:36.855016Z","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-06-15T20:01:36.855183Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.18:2380"} {"level":"info","ts":"2026-06-15T20:01:36.855255Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.18:2380"} {"level":"info","ts":"2026-06-15T20:01:36.855375Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6d0358acb671c233","initial-advertise-peer-urls":["https://199.19.213.18:2380"],"listen-peer-urls":["https://199.19.213.18:2380"],"advertise-client-urls":["https://199.19.213.18:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-15T20:01:36.855418Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-15T20:01:37.731537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 is starting a new election at term 1"} {"level":"info","ts":"2026-06-15T20:01:37.731596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-15T20:01:37.73162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 received MsgPreVoteResp from 6d0358acb671c233 at term 1"} {"level":"info","ts":"2026-06-15T20:01:37.73164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became candidate at term 2"} {"level":"info","ts":"2026-06-15T20:01:37.731651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 received MsgVoteResp from 6d0358acb671c233 at term 2"} {"level":"info","ts":"2026-06-15T20:01:37.731667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became leader at term 2"} {"level":"info","ts":"2026-06-15T20:01:37.73169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6d0358acb671c233 elected leader 6d0358acb671c233 at term 2"} {"level":"info","ts":"2026-06-15T20:01:37.733116Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6d0358acb671c233","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.18:2379]}","request-path":"/0/members/6d0358acb671c233/attributes","cluster-id":"ba212af698de913f","publish-timeout":"7s"} {"level":"info","ts":"2026-06-15T20:01:37.733173Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:01:37.73331Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T20:01:37.733234Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T20:01:37.733903Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-15T20:01:37.733966Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-15T20:01:37.734549Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba212af698de913f","local-member-id":"6d0358acb671c233","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:01:37.734673Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:01:37.734712Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:01:37.737393Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.18:2379"} {"level":"info","ts":"2026-06-15T20:01:37.737854Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-15T20:01:46.068517Z","caller":"traceutil/trace.go:171","msg":"trace[319336002] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:355; }","duration":"132.309858ms","start":"2026-06-15T20:01:45.936187Z","end":"2026-06-15T20:01:46.068497Z","steps":["trace[319336002] 'read index received' (duration: 38.925491ms)","trace[319336002] 'applied index is now lower than readState.Index' (duration: 93.383337ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:01:46.06865Z","caller":"traceutil/trace.go:171","msg":"trace[554196866] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"171.947465ms","start":"2026-06-15T20:01:45.896691Z","end":"2026-06-15T20:01:46.068638Z","steps":["trace[554196866] 'process raft request' (duration: 171.71684ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:46.068619Z","caller":"traceutil/trace.go:171","msg":"trace[276731209] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"172.025897ms","start":"2026-06-15T20:01:45.896555Z","end":"2026-06-15T20:01:46.068581Z","steps":["trace[276731209] 'process raft request' (duration: 78.62762ms)","trace[276731209] 'compare' (duration: 93.0893ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:01:46.068825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.604314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-06-15T20:01:46.068885Z","caller":"traceutil/trace.go:171","msg":"trace[2056260659] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:345; }","duration":"132.702357ms","start":"2026-06-15T20:01:45.936172Z","end":"2026-06-15T20:01:46.068874Z","steps":["trace[2056260659] 'agreement among raft nodes before linearized reading' (duration: 132.577284ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:46.269826Z","caller":"traceutil/trace.go:171","msg":"trace[1173505830] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"195.063264ms","start":"2026-06-15T20:01:46.074736Z","end":"2026-06-15T20:01:46.269799Z","steps":["trace[1173505830] 'process raft request' (duration: 130.278042ms)","trace[1173505830] 'compare' (duration: 64.609158ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:01:46.270268Z","caller":"traceutil/trace.go:171","msg":"trace[93167786] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"177.41494ms","start":"2026-06-15T20:01:46.09282Z","end":"2026-06-15T20:01:46.270235Z","steps":["trace[93167786] 'process raft request' (duration: 177.292347ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:46.270277Z","caller":"traceutil/trace.go:171","msg":"trace[1458085324] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"193.148641ms","start":"2026-06-15T20:01:46.077073Z","end":"2026-06-15T20:01:46.270221Z","steps":["trace[1458085324] 'process raft request' (duration: 192.65991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:46.270412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.189163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-06-15T20:01:46.270283Z","caller":"traceutil/trace.go:171","msg":"trace[589419148] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:358; }","duration":"183.051429ms","start":"2026-06-15T20:01:46.087206Z","end":"2026-06-15T20:01:46.270258Z","steps":["trace[589419148] 'read index received' (duration: 117.881427ms)","trace[589419148] 'applied index is now lower than readState.Index' (duration: 65.167602ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:01:46.270489Z","caller":"traceutil/trace.go:171","msg":"trace[904562907] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:349; }","duration":"183.303195ms","start":"2026-06-15T20:01:46.087169Z","end":"2026-06-15T20:01:46.270472Z","steps":["trace[904562907] 'agreement among raft nodes before linearized reading' (duration: 183.162661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:46.270603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.953123ms","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-06-15T20:01:46.27067Z","caller":"traceutil/trace.go:171","msg":"trace[166153799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:349; }","duration":"147.058276ms","start":"2026-06-15T20:01:46.123593Z","end":"2026-06-15T20:01:46.270652Z","steps":["trace[166153799] 'agreement among raft nodes before linearized reading' (duration: 146.951023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:46.270928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.894384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-06-15T20:01:46.27103Z","caller":"traceutil/trace.go:171","msg":"trace[825457184] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:349; }","duration":"134.025398ms","start":"2026-06-15T20:01:46.136988Z","end":"2026-06-15T20:01:46.271013Z","steps":["trace[825457184] 'agreement among raft nodes before linearized reading' (duration: 133.862584ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:46.636528Z","caller":"traceutil/trace.go:171","msg":"trace[2049566655] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:375; }","duration":"149.522482ms","start":"2026-06-15T20:01:46.486976Z","end":"2026-06-15T20:01:46.636499Z","steps":["trace[2049566655] 'read index received' (duration: 18.576024ms)","trace[2049566655] 'applied index is now lower than readState.Index' (duration: 130.944558ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:01:46.636712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.704076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-15T20:01:46.636757Z","caller":"traceutil/trace.go:171","msg":"trace[1102233900] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:366; }","duration":"149.799278ms","start":"2026-06-15T20:01:46.486946Z","end":"2026-06-15T20:01:46.636746Z","steps":["trace[1102233900] 'agreement among raft nodes before linearized reading' (duration: 149.667375ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:46.636662Z","caller":"traceutil/trace.go:171","msg":"trace[93632328] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"160.306258ms","start":"2026-06-15T20:01:46.476313Z","end":"2026-06-15T20:01:46.636619Z","steps":["trace[93632328] 'process raft request' (duration: 151.63874ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:12.447239Z","caller":"traceutil/trace.go:171","msg":"trace[1678598025] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"165.668172ms","start":"2026-06-15T20:02:12.281555Z","end":"2026-06-15T20:02:12.447224Z","steps":["trace[1678598025] 'process raft request' (duration: 165.2172ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:12.604123Z","caller":"traceutil/trace.go:171","msg":"trace[1420139645] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:506; }","duration":"101.146805ms","start":"2026-06-15T20:02:12.502945Z","end":"2026-06-15T20:02:12.604092Z","steps":["trace[1420139645] 'read index received' (duration: 48.101721ms)","trace[1420139645] 'applied index is now lower than readState.Index' (duration: 53.043924ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:12.604237Z","caller":"traceutil/trace.go:171","msg":"trace[1500039716] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"129.872283ms","start":"2026-06-15T20:02:12.474317Z","end":"2026-06-15T20:02:12.604189Z","steps":["trace[1500039716] 'process raft request' (duration: 76.732537ms)","trace[1500039716] 'compare' (duration: 52.85069ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:12.60427Z","caller":"traceutil/trace.go:171","msg":"trace[1602747759] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"129.35128ms","start":"2026-06-15T20:02:12.474888Z","end":"2026-06-15T20:02:12.60424Z","steps":["trace[1602747759] 'process raft request' (duration: 129.167126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:12.604425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.445092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:12.604473Z","caller":"traceutil/trace.go:171","msg":"trace[2090562489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:489; }","duration":"101.545864ms","start":"2026-06-15T20:02:12.502916Z","end":"2026-06-15T20:02:12.604461Z","steps":["trace[2090562489] 'agreement among raft nodes before linearized reading' (duration: 101.284138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:13.303982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.961907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:02:13.304082Z","caller":"traceutil/trace.go:171","msg":"trace[33739187] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"659.039763ms","start":"2026-06-15T20:02:12.645028Z","end":"2026-06-15T20:02:13.304067Z","steps":["trace[33739187] 'read index received' (duration: 312.495072ms)","trace[33739187] 'applied index is now lower than readState.Index' (duration: 346.543351ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:13.304169Z","caller":"traceutil/trace.go:171","msg":"trace[694859858] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"693.726417ms","start":"2026-06-15T20:02:12.610433Z","end":"2026-06-15T20:02:13.304159Z","steps":["trace[694859858] 'process raft request' (duration: 347.115034ms)","trace[694859858] 'compare' (duration: 345.841475ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:13.304223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:12.610418Z","time spent":"693.782079ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T20:02:13.304488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.464353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:13.304526Z","caller":"traceutil/trace.go:171","msg":"trace[1286107554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"659.539135ms","start":"2026-06-15T20:02:12.644978Z","end":"2026-06-15T20:02:13.304517Z","steps":["trace[1286107554] 'agreement among raft nodes before linearized reading' (duration: 659.450403ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:13.304553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:12.644959Z","time spent":"659.588486ms","remote":"127.0.0.1:47930","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-06-15T20:02:13.304762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.410445ms","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-06-15T20:02:13.304806Z","caller":"traceutil/trace.go:171","msg":"trace[1557256118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:490; }","duration":"175.491087ms","start":"2026-06-15T20:02:13.129304Z","end":"2026-06-15T20:02:13.304795Z","steps":["trace[1557256118] 'agreement among raft nodes before linearized reading' (duration: 175.416876ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:16.165716Z","caller":"traceutil/trace.go:171","msg":"trace[929232403] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"225.719076ms","start":"2026-06-15T20:02:15.939971Z","end":"2026-06-15T20:02:16.165691Z","steps":["trace[929232403] 'read index received' (duration: 225.580873ms)","trace[929232403] 'applied index is now lower than readState.Index' (duration: 137.343µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:16.165767Z","caller":"traceutil/trace.go:171","msg":"trace[1579415125] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"235.321606ms","start":"2026-06-15T20:02:15.930424Z","end":"2026-06-15T20:02:16.165746Z","steps":["trace[1579415125] 'process raft request' (duration: 235.02667ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:16.165946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.948412ms","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-06-15T20:02:16.166006Z","caller":"traceutil/trace.go:171","msg":"trace[620328733] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:616; }","duration":"226.057174ms","start":"2026-06-15T20:02:15.939932Z","end":"2026-06-15T20:02:16.165989Z","steps":["trace[620328733] 'agreement among raft nodes before linearized reading' (duration: 225.90271ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:16.279174Z","caller":"traceutil/trace.go:171","msg":"trace[378409803] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:641; }","duration":"113.337863ms","start":"2026-06-15T20:02:16.165809Z","end":"2026-06-15T20:02:16.279147Z","steps":["trace[378409803] 'read index received' (duration: 111.341268ms)","trace[378409803] 'applied index is now lower than readState.Index' (duration: 1.995535ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:16.279291Z","caller":"traceutil/trace.go:171","msg":"trace[696839153] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"245.11139ms","start":"2026-06-15T20:02:16.034167Z","end":"2026-06-15T20:02:16.279278Z","steps":["trace[696839153] 'process raft request' (duration: 242.888519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:16.27958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.631388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:16.279628Z","caller":"traceutil/trace.go:171","msg":"trace[1511418910] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:618; }","duration":"223.73735ms","start":"2026-06-15T20:02:16.055879Z","end":"2026-06-15T20:02:16.279617Z","steps":["trace[1511418910] 'agreement among raft nodes before linearized reading' (duration: 223.638208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:16.279653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.86023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:16.279696Z","caller":"traceutil/trace.go:171","msg":"trace[1295092323] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:618; }","duration":"243.934973ms","start":"2026-06-15T20:02:16.035747Z","end":"2026-06-15T20:02:16.279682Z","steps":["trace[1295092323] 'agreement among raft nodes before linearized reading' (duration: 243.847641ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:16.279774Z","caller":"traceutil/trace.go:171","msg":"trace[1386821098] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"244.006344ms","start":"2026-06-15T20:02:16.035759Z","end":"2026-06-15T20:02:16.279765Z","steps":["trace[1386821098] 'process raft request' (duration: 243.302827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:16.279888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.13111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:16.279904Z","caller":"traceutil/trace.go:171","msg":"trace[792042656] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:618; }","duration":"127.182051ms","start":"2026-06-15T20:02:16.152717Z","end":"2026-06-15T20:02:16.279899Z","steps":["trace[792042656] 'agreement among raft nodes before linearized reading' (duration: 127.125539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:16.279897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.246489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:16.279932Z","caller":"traceutil/trace.go:171","msg":"trace[607973824] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:618; }","duration":"244.28385ms","start":"2026-06-15T20:02:16.035639Z","end":"2026-06-15T20:02:16.279923Z","steps":["trace[607973824] 'agreement among raft nodes before linearized reading' (duration: 244.207008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:16.279981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.310733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:16.279998Z","caller":"traceutil/trace.go:171","msg":"trace[712334990] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:618; }","duration":"127.328734ms","start":"2026-06-15T20:02:16.152665Z","end":"2026-06-15T20:02:16.279993Z","steps":["trace[712334990] 'agreement among raft nodes before linearized reading' (duration: 127.303373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:16.280062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.566569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:16.280077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.624138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:16.280075Z","caller":"traceutil/trace.go:171","msg":"trace[1430963388] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:618; }","duration":"127.57977ms","start":"2026-06-15T20:02:16.152491Z","end":"2026-06-15T20:02:16.28007Z","steps":["trace[1430963388] 'agreement among raft nodes before linearized reading' (duration: 127.557289ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:16.280116Z","caller":"traceutil/trace.go:171","msg":"trace[1254053388] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:618; }","duration":"244.67693ms","start":"2026-06-15T20:02:16.03543Z","end":"2026-06-15T20:02:16.280107Z","steps":["trace[1254053388] 'agreement among raft nodes before linearized reading' (duration: 244.620909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:16.280207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.643927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:16.280222Z","caller":"traceutil/trace.go:171","msg":"trace[1366185364] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:618; }","duration":"181.683478ms","start":"2026-06-15T20:02:16.098535Z","end":"2026-06-15T20:02:16.280218Z","steps":["trace[1366185364] 'agreement among raft nodes before linearized reading' (duration: 181.656497ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:16.280257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.895173ms","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":"warn","ts":"2026-06-15T20:02:16.280284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.80155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:16.280299Z","caller":"traceutil/trace.go:171","msg":"trace[1379488738] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:618; }","duration":"181.830802ms","start":"2026-06-15T20:02:16.098464Z","end":"2026-06-15T20:02:16.280295Z","steps":["trace[1379488738] 'agreement among raft nodes before linearized reading' (duration: 181.808791ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:16.280304Z","caller":"traceutil/trace.go:171","msg":"trace[796358746] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:618; }","duration":"337.970885ms","start":"2026-06-15T20:02:15.942319Z","end":"2026-06-15T20:02:16.28029Z","steps":["trace[796358746] 'agreement among raft nodes before linearized reading' (duration: 337.879733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:16.280341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:15.942317Z","time spent":"338.013576ms","remote":"127.0.0.1:57150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:02:16.280359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.034515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:16.28046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.144139ms","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":"info","ts":"2026-06-15T20:02:16.280483Z","caller":"traceutil/trace.go:171","msg":"trace[1674999668] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:618; }","duration":"338.18482ms","start":"2026-06-15T20:02:15.942289Z","end":"2026-06-15T20:02:16.280474Z","steps":["trace[1674999668] 'agreement among raft nodes before linearized reading' (duration: 338.143829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:16.280499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:15.942281Z","time spent":"338.21374ms","remote":"127.0.0.1:57150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"warn","ts":"2026-06-15T20:02:16.280493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.539899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:16.280525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.791459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:16.28055Z","caller":"traceutil/trace.go:171","msg":"trace[635434886] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:618; }","duration":"224.6077ms","start":"2026-06-15T20:02:16.055928Z","end":"2026-06-15T20:02:16.280536Z","steps":["trace[635434886] 'agreement among raft nodes before linearized reading' (duration: 224.503528ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:16.280561Z","caller":"traceutil/trace.go:171","msg":"trace[1985339021] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:618; }","duration":"260.828509ms","start":"2026-06-15T20:02:16.019723Z","end":"2026-06-15T20:02:16.280551Z","steps":["trace[1985339021] 'agreement among raft nodes before linearized reading' (duration: 260.772748ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:16.28071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.579146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:16.280736Z","caller":"traceutil/trace.go:171","msg":"trace[1246621541] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:618; }","duration":"261.615896ms","start":"2026-06-15T20:02:16.019113Z","end":"2026-06-15T20:02:16.280729Z","steps":["trace[1246621541] 'agreement among raft nodes before linearized reading' (duration: 261.573135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:16.280851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.73411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:16.280877Z","caller":"traceutil/trace.go:171","msg":"trace[909857480] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:618; }","duration":"261.788371ms","start":"2026-06-15T20:02:16.019081Z","end":"2026-06-15T20:02:16.280869Z","steps":["trace[909857480] 'agreement among raft nodes before linearized reading' (duration: 261.742559ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:16.281037Z","caller":"traceutil/trace.go:171","msg":"trace[774079224] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:618; }","duration":"223.068015ms","start":"2026-06-15T20:02:16.057299Z","end":"2026-06-15T20:02:16.280367Z","steps":["trace[774079224] 'agreement among raft nodes before linearized reading' (duration: 223.047205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.029548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993703069939942195,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T20:02:17.109327Z","caller":"traceutil/trace.go:171","msg":"trace[1878451470] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"583.418202ms","start":"2026-06-15T20:02:16.525885Z","end":"2026-06-15T20:02:17.109303Z","steps":["trace[1878451470] 'process raft request' (duration: 583.168986ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.109727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.525875Z","time spent":"583.526625ms","remote":"127.0.0.1:47988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:02:17.112731Z","caller":"traceutil/trace.go:171","msg":"trace[578494827] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"583.961454ms","start":"2026-06-15T20:02:16.52874Z","end":"2026-06-15T20:02:17.112702Z","steps":["trace[578494827] 'process raft request' (duration: 583.443513ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:17.112824Z","caller":"traceutil/trace.go:171","msg":"trace[1336120478] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:652; }","duration":"584.059916ms","start":"2026-06-15T20:02:16.528733Z","end":"2026-06-15T20:02:17.112793Z","steps":["trace[1336120478] 'read index received' (duration: 580.337201ms)","trace[1336120478] 'applied index is now lower than readState.Index' (duration: 3.721385ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:17.112985Z","caller":"traceutil/trace.go:171","msg":"trace[1178447759] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"533.366146ms","start":"2026-06-15T20:02:16.5796Z","end":"2026-06-15T20:02:17.112966Z","steps":["trace[1178447759] 'process raft request' (duration: 532.880275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.113083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.579572Z","time spent":"533.461159ms","remote":"127.0.0.1:47988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:02:17.113117Z","caller":"traceutil/trace.go:171","msg":"trace[1127184831] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"474.725024ms","start":"2026-06-15T20:02:16.63837Z","end":"2026-06-15T20:02:17.113095Z","steps":["trace[1127184831] 'process raft request' (duration: 474.315265ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.113391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.638352Z","time spent":"474.871677ms","remote":"127.0.0.1:47988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T20:02:17.112894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.528724Z","time spent":"584.082537ms","remote":"127.0.0.1:48216","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-06-15T20:02:17.113618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.829739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:17.113672Z","caller":"traceutil/trace.go:171","msg":"trace[816164503] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:631; }","duration":"537.921741ms","start":"2026-06-15T20:02:16.575736Z","end":"2026-06-15T20:02:17.113658Z","steps":["trace[816164503] 'agreement among raft nodes before linearized reading' (duration: 537.843859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.113708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.575714Z","time spent":"537.983893ms","remote":"127.0.0.1:57242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-06-15T20:02:17.113948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.191122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-06-15T20:02:17.114023Z","caller":"traceutil/trace.go:171","msg":"trace[1972015109] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:631; }","duration":"585.293436ms","start":"2026-06-15T20:02:16.528711Z","end":"2026-06-15T20:02:17.114005Z","steps":["trace[1972015109] 'agreement among raft nodes before linearized reading' (duration: 585.033079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.114105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.528701Z","time spent":"585.357297ms","remote":"127.0.0.1:47988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151259,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-06-15T20:02:17.114237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.898622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:17.114285Z","caller":"traceutil/trace.go:171","msg":"trace[9249298] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:631; }","duration":"371.951854ms","start":"2026-06-15T20:02:16.742318Z","end":"2026-06-15T20:02:17.11427Z","steps":["trace[9249298] 'agreement among raft nodes before linearized reading' (duration: 371.8145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.114318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.742312Z","time spent":"371.997254ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-06-15T20:02:17.114487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.69478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:17.114525Z","caller":"traceutil/trace.go:171","msg":"trace[10951070] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:631; }","duration":"372.746291ms","start":"2026-06-15T20:02:16.741769Z","end":"2026-06-15T20:02:17.114515Z","steps":["trace[10951070] 'agreement among raft nodes before linearized reading' (duration: 372.69058ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.114554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.741765Z","time spent":"372.781162ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:02:17.114059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.583152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:17.114684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.73112ms","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-06-15T20:02:17.114722Z","caller":"traceutil/trace.go:171","msg":"trace[170196912] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:631; }","duration":"448.796181ms","start":"2026-06-15T20:02:16.665916Z","end":"2026-06-15T20:02:17.114712Z","steps":["trace[170196912] 'agreement among raft nodes before linearized reading' (duration: 448.741529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.114753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.665903Z","time spent":"448.841322ms","remote":"127.0.0.1:57274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:02:17.114983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.040163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:17.115028Z","caller":"traceutil/trace.go:171","msg":"trace[1465161427] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:631; }","duration":"493.091955ms","start":"2026-06-15T20:02:16.621924Z","end":"2026-06-15T20:02:17.115016Z","steps":["trace[1465161427] 'agreement among raft nodes before linearized reading' (duration: 493.025524ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:17.114917Z","caller":"traceutil/trace.go:171","msg":"trace[476134480] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"284.450271ms","start":"2026-06-15T20:02:16.830443Z","end":"2026-06-15T20:02:17.114893Z","steps":["trace[476134480] 'agreement among raft nodes before linearized reading' (duration: 283.586121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.115067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.621922Z","time spent":"493.132856ms","remote":"127.0.0.1:57262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:02:17.115238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.442096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:17.115299Z","caller":"traceutil/trace.go:171","msg":"trace[106585508] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:631; }","duration":"539.505597ms","start":"2026-06-15T20:02:16.575776Z","end":"2026-06-15T20:02:17.115282Z","steps":["trace[106585508] 'agreement among raft nodes before linearized reading' (duration: 539.418655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.115399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.575729Z","time spent":"539.655621ms","remote":"127.0.0.1:57242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:02:17.115191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.013176ms","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":"warn","ts":"2026-06-15T20:02:17.115541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.638787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:17.115652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.349351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:17.115664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.038581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:17.115699Z","caller":"traceutil/trace.go:171","msg":"trace[1627670478] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:631; }","duration":"393.417034ms","start":"2026-06-15T20:02:16.72227Z","end":"2026-06-15T20:02:17.115687Z","steps":["trace[1627670478] 'agreement among raft nodes before linearized reading' (duration: 393.334132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.115704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.412308ms","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:5"} {"level":"info","ts":"2026-06-15T20:02:17.115716Z","caller":"traceutil/trace.go:171","msg":"trace[229859299] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:631; }","duration":"527.111924ms","start":"2026-06-15T20:02:16.58859Z","end":"2026-06-15T20:02:17.115702Z","steps":["trace[229859299] 'agreement among raft nodes before linearized reading' (duration: 526.996051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.115733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.722267Z","time spent":"393.456914ms","remote":"127.0.0.1:57292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"info","ts":"2026-06-15T20:02:17.115748Z","caller":"traceutil/trace.go:171","msg":"trace[911485512] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:631; }","duration":"429.481309ms","start":"2026-06-15T20:02:16.686257Z","end":"2026-06-15T20:02:17.115738Z","steps":["trace[911485512] 'agreement among raft nodes before linearized reading' (duration: 429.394687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.115757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.588584Z","time spent":"527.162675ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:02:17.115782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.686244Z","time spent":"429.531049ms","remote":"127.0.0.1:57282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:02:17.115918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.320281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:17.115925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.159983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:17.115942Z","caller":"traceutil/trace.go:171","msg":"trace[736108369] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:631; }","duration":"410.383241ms","start":"2026-06-15T20:02:16.705552Z","end":"2026-06-15T20:02:17.115936Z","steps":["trace[736108369] 'agreement among raft nodes before linearized reading' (duration: 410.34523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.115963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.70553Z","time spent":"410.428193ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-06-15T20:02:17.115995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.816337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:17.116011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.558926ms","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:5"} {"level":"info","ts":"2026-06-15T20:02:17.116049Z","caller":"traceutil/trace.go:171","msg":"trace[919020788] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:631; }","duration":"526.914759ms","start":"2026-06-15T20:02:16.589122Z","end":"2026-06-15T20:02:17.116037Z","steps":["trace[919020788] 'agreement among raft nodes before linearized reading' (duration: 526.825667ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:17.116051Z","caller":"traceutil/trace.go:171","msg":"trace[1264165271] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:631; }","duration":"410.610106ms","start":"2026-06-15T20:02:16.705431Z","end":"2026-06-15T20:02:17.116041Z","steps":["trace[1264165271] 'agreement among raft nodes before linearized reading' (duration: 410.551895ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.116092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.705425Z","time spent":"410.658827ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:02:17.116097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.589103Z","time spent":"526.98003ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-06-15T20:02:17.116131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.933469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:17.11615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.318944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:17.116159Z","caller":"traceutil/trace.go:171","msg":"trace[933400707] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:631; }","duration":"429.97508ms","start":"2026-06-15T20:02:16.686178Z","end":"2026-06-15T20:02:17.116153Z","steps":["trace[933400707] 'agreement among raft nodes before linearized reading' (duration: 429.934409ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:17.116176Z","caller":"traceutil/trace.go:171","msg":"trace[931452475] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:631; }","duration":"374.436839ms","start":"2026-06-15T20:02:16.741726Z","end":"2026-06-15T20:02:17.116163Z","steps":["trace[931452475] 'agreement among raft nodes before linearized reading' (duration: 374.162913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.11619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.686173Z","time spent":"430.011491ms","remote":"127.0.0.1:57282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-06-15T20:02:17.116192Z","caller":"traceutil/trace.go:171","msg":"trace[478014935] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:631; }","duration":"394.383365ms","start":"2026-06-15T20:02:16.721797Z","end":"2026-06-15T20:02:17.11618Z","steps":["trace[478014935] 'agreement among raft nodes before linearized reading' (duration: 394.320303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.116228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.721783Z","time spent":"394.434946ms","remote":"127.0.0.1:57292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:02:17.116319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.743087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:17.116353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.65796ms","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":"info","ts":"2026-06-15T20:02:17.116361Z","caller":"traceutil/trace.go:171","msg":"trace[1398758178] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:631; }","duration":"527.808909ms","start":"2026-06-15T20:02:16.588543Z","end":"2026-06-15T20:02:17.116352Z","steps":["trace[1398758178] 'agreement among raft nodes before linearized reading' (duration: 527.747088ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.115986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.099509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:17.115505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.577136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:17.116434Z","caller":"traceutil/trace.go:171","msg":"trace[732054466] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:631; }","duration":"394.554889ms","start":"2026-06-15T20:02:16.721866Z","end":"2026-06-15T20:02:17.116421Z","steps":["trace[732054466] 'agreement among raft nodes before linearized reading' (duration: 394.084958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.11644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.741711Z","time spent":"374.50119ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-06-15T20:02:17.116393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.588533Z","time spent":"527.8515ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-06-15T20:02:17.116476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.721863Z","time spent":"394.60077ms","remote":"127.0.0.1:57292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"info","ts":"2026-06-15T20:02:17.11647Z","caller":"traceutil/trace.go:171","msg":"trace[298365720] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:631; }","duration":"494.540638ms","start":"2026-06-15T20:02:16.621918Z","end":"2026-06-15T20:02:17.116459Z","steps":["trace[298365720] 'agreement among raft nodes before linearized reading' (duration: 493.559256ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.115721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.745997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:17.116528Z","caller":"traceutil/trace.go:171","msg":"trace[1328695278] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:631; }","duration":"410.566325ms","start":"2026-06-15T20:02:16.70595Z","end":"2026-06-15T20:02:17.116516Z","steps":["trace[1328695278] 'agreement among raft nodes before linearized reading' (duration: 409.726566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.11653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.621906Z","time spent":"494.605229ms","remote":"127.0.0.1:57262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-06-15T20:02:17.116572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.705945Z","time spent":"410.614187ms","remote":"127.0.0.1:57282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"info","ts":"2026-06-15T20:02:17.11655Z","caller":"traceutil/trace.go:171","msg":"trace[2088966643] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:631; }","duration":"449.611229ms","start":"2026-06-15T20:02:16.66594Z","end":"2026-06-15T20:02:17.115551Z","steps":["trace[2088966643] 'agreement among raft nodes before linearized reading' (duration: 448.525115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.116692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.665923Z","time spent":"450.753365ms","remote":"127.0.0.1:57274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"info","ts":"2026-06-15T20:02:17.115578Z","caller":"traceutil/trace.go:171","msg":"trace[402086081] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:631; }","duration":"493.692759ms","start":"2026-06-15T20:02:16.621875Z","end":"2026-06-15T20:02:17.115568Z","steps":["trace[402086081] 'agreement among raft nodes before linearized reading' (duration: 493.649148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.116799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.621859Z","time spent":"494.923767ms","remote":"127.0.0.1:57262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"info","ts":"2026-06-15T20:02:17.116375Z","caller":"traceutil/trace.go:171","msg":"trace[699816379] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:631; }","duration":"449.693641ms","start":"2026-06-15T20:02:16.666676Z","end":"2026-06-15T20:02:17.116369Z","steps":["trace[699816379] 'agreement among raft nodes before linearized reading' (duration: 449.65402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:17.121207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:16.666671Z","time spent":"454.519422ms","remote":"127.0.0.1:57274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"info","ts":"2026-06-15T20:02:22.87789Z","caller":"traceutil/trace.go:171","msg":"trace[615624477] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"151.658211ms","start":"2026-06-15T20:02:22.726201Z","end":"2026-06-15T20:02:22.877859Z","steps":["trace[615624477] 'process raft request' (duration: 114.845909ms)","trace[615624477] 'compare' (duration: 36.654659ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:23.030266Z","caller":"traceutil/trace.go:171","msg":"trace[1787695931] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"128.022499ms","start":"2026-06-15T20:02:22.902215Z","end":"2026-06-15T20:02:23.030237Z","steps":["trace[1787695931] 'process raft request' (duration: 85.620799ms)","trace[1787695931] 'compare' (duration: 42.276798ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:23.059745Z","caller":"traceutil/trace.go:171","msg":"trace[182197257] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"156.507732ms","start":"2026-06-15T20:02:22.903205Z","end":"2026-06-15T20:02:23.059713Z","steps":["trace[182197257] 'process raft request' (duration: 156.300488ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:23.059784Z","caller":"traceutil/trace.go:171","msg":"trace[883274575] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"156.110593ms","start":"2026-06-15T20:02:22.903645Z","end":"2026-06-15T20:02:23.059755Z","steps":["trace[883274575] 'process raft request' (duration: 156.059832ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:23.059819Z","caller":"traceutil/trace.go:171","msg":"trace[363018370] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"156.40927ms","start":"2026-06-15T20:02:22.903383Z","end":"2026-06-15T20:02:23.059793Z","steps":["trace[363018370] 'process raft request' (duration: 156.246047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:24.67831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.791921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:02:24.678454Z","caller":"traceutil/trace.go:171","msg":"trace[1010289921] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:722; }","duration":"896.661471ms","start":"2026-06-15T20:02:23.781762Z","end":"2026-06-15T20:02:24.678423Z","steps":["trace[1010289921] 'read index received' (duration: 41.001µs)","trace[1010289921] 'applied index is now lower than readState.Index' (duration: 896.61903ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:24.678523Z","caller":"traceutil/trace.go:171","msg":"trace[2114410369] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"1.054562116s","start":"2026-06-15T20:02:23.623915Z","end":"2026-06-15T20:02:24.678477Z","steps":["trace[2114410369] 'process raft request' (duration: 148.432038ms)","trace[2114410369] 'compare' (duration: 905.484773ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:24.67861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.845645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l75vh\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-15T20:02:24.678642Z","caller":"traceutil/trace.go:171","msg":"trace[1326023649] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l75vh; range_end:; response_count:1; response_revision:697; }","duration":"896.910166ms","start":"2026-06-15T20:02:23.781721Z","end":"2026-06-15T20:02:24.678632Z","steps":["trace[1326023649] 'agreement among raft nodes before linearized reading' (duration: 896.811484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:24.678673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:23.7817Z","time spent":"896.966298ms","remote":"127.0.0.1:48144","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-l75vh\" "} {"level":"warn","ts":"2026-06-15T20:02:24.678721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:23.623896Z","time spent":"1.054716189s","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:02:24.678726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.403369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T20:02:24.678802Z","caller":"traceutil/trace.go:171","msg":"trace[567765917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:697; }","duration":"308.532472ms","start":"2026-06-15T20:02:24.370252Z","end":"2026-06-15T20:02:24.678784Z","steps":["trace[567765917] 'agreement among raft nodes before linearized reading' (duration: 308.328197ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:24.678854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:24.370232Z","time spent":"308.613303ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:02:24.678935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.015103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:24.679115Z","caller":"traceutil/trace.go:171","msg":"trace[327952049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"174.229418ms","start":"2026-06-15T20:02:24.504864Z","end":"2026-06-15T20:02:24.679093Z","steps":["trace[327952049] 'agreement among raft nodes before linearized reading' (duration: 173.937151ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:25.501903Z","caller":"traceutil/trace.go:171","msg":"trace[530248865] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"329.969952ms","start":"2026-06-15T20:02:25.171904Z","end":"2026-06-15T20:02:25.501874Z","steps":["trace[530248865] 'process raft request' (duration: 329.820069ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:25.502162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:25.171818Z","time spent":"330.174157ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:02:25.503309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.544571ms","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-06-15T20:02:25.503429Z","caller":"traceutil/trace.go:171","msg":"trace[1453429696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:700; }","duration":"100.705915ms","start":"2026-06-15T20:02:25.402705Z","end":"2026-06-15T20:02:25.503411Z","steps":["trace[1453429696] 'agreement among raft nodes before linearized reading' (duration: 99.685672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:26.516201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.949891ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:26.516344Z","caller":"traceutil/trace.go:171","msg":"trace[230499560] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:703; }","duration":"264.101184ms","start":"2026-06-15T20:02:26.252218Z","end":"2026-06-15T20:02:26.51632Z","steps":["trace[230499560] 'range keys from in-memory index tree' (duration: 263.93531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:26.632092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.250096ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-15T20:02:26.632224Z","caller":"traceutil/trace.go:171","msg":"trace[1742803487] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"126.887223ms","start":"2026-06-15T20:02:26.505315Z","end":"2026-06-15T20:02:26.632202Z","steps":["trace[1742803487] 'read index received' (duration: 53.551µs)","trace[1742803487] 'applied index is now lower than readState.Index' (duration: 126.831842ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:26.632324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.003396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:26.632372Z","caller":"traceutil/trace.go:171","msg":"trace[1428562201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"127.091318ms","start":"2026-06-15T20:02:26.505266Z","end":"2026-06-15T20:02:26.632357Z","steps":["trace[1428562201] 'agreement among raft nodes before linearized reading' (duration: 126.995376ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:26.841606Z","caller":"traceutil/trace.go:171","msg":"trace[1637280581] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"142.326578ms","start":"2026-06-15T20:02:26.699263Z","end":"2026-06-15T20:02:26.841589Z","steps":["trace[1637280581] 'process raft request' (duration: 142.220576ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:40.106402Z","caller":"traceutil/trace.go:171","msg":"trace[1098504197] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"214.377526ms","start":"2026-06-15T20:02:39.892Z","end":"2026-06-15T20:02:40.106378Z","steps":["trace[1098504197] 'process raft request' (duration: 214.228202ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:43.215649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.117192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T20:02:43.215734Z","caller":"traceutil/trace.go:171","msg":"trace[618830669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:816; }","duration":"301.245715ms","start":"2026-06-15T20:02:42.914468Z","end":"2026-06-15T20:02:43.215714Z","steps":["trace[618830669] 'range keys from in-memory index tree' (duration: 300.956329ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:43.215775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:42.914457Z","time spent":"301.308257ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:02:43.215664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.704096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:43.215784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.821189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v25nf\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-06-15T20:02:43.215828Z","caller":"traceutil/trace.go:171","msg":"trace[681203499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"568.918051ms","start":"2026-06-15T20:02:42.646894Z","end":"2026-06-15T20:02:43.215812Z","steps":["trace[681203499] 'range keys from in-memory index tree' (duration: 568.532521ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:43.215869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:42.646823Z","time spent":"569.037443ms","remote":"127.0.0.1:47930","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-06-15T20:02:43.215872Z","caller":"traceutil/trace.go:171","msg":"trace[29022372] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v25nf; range_end:; response_count:1; response_revision:816; }","duration":"319.944853ms","start":"2026-06-15T20:02:42.895907Z","end":"2026-06-15T20:02:43.215852Z","steps":["trace[29022372] 'range keys from in-memory index tree' (duration: 319.666956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:43.215916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:42.895892Z","time spent":"320.014934ms","remote":"127.0.0.1:48144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v25nf\" "} {"level":"info","ts":"2026-06-15T20:02:43.410863Z","caller":"traceutil/trace.go:171","msg":"trace[8926125] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"189.413775ms","start":"2026-06-15T20:02:43.221431Z","end":"2026-06-15T20:02:43.410844Z","steps":["trace[8926125] 'process raft request' (duration: 189.297123ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:43.435386Z","caller":"traceutil/trace.go:171","msg":"trace[1300913248] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"210.849005ms","start":"2026-06-15T20:02:43.224528Z","end":"2026-06-15T20:02:43.435377Z","steps":["trace[1300913248] 'process raft request' (duration: 210.787724ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:49.741398Z","caller":"traceutil/trace.go:171","msg":"trace[1937759660] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"107.235651ms","start":"2026-06-15T20:02:49.634137Z","end":"2026-06-15T20:02:49.741373Z","steps":["trace[1937759660] 'process raft request' (duration: 107.097958ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:49.742772Z","caller":"traceutil/trace.go:171","msg":"trace[2061473573] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"108.538623ms","start":"2026-06-15T20:02:49.634201Z","end":"2026-06-15T20:02:49.74274Z","steps":["trace[2061473573] 'process raft request' (duration: 108.264816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:18.48033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993703069939943070,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T20:03:18.507534Z","caller":"traceutil/trace.go:171","msg":"trace[736947075] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"592.309682ms","start":"2026-06-15T20:03:17.915203Z","end":"2026-06-15T20:03:18.507512Z","steps":["trace[736947075] 'process raft request' (duration: 592.154708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:18.507695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:17.91517Z","time spent":"592.436005ms","remote":"127.0.0.1:48216","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-06-15T20:03:18.597583Z","caller":"traceutil/trace.go:171","msg":"trace[774678060] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1067; }","duration":"617.744287ms","start":"2026-06-15T20:03:17.979815Z","end":"2026-06-15T20:03:18.597559Z","steps":["trace[774678060] 'read index received' (duration: 527.529865ms)","trace[774678060] 'applied index is now lower than readState.Index' (duration: 90.213472ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:18.597792Z","caller":"traceutil/trace.go:171","msg":"trace[1216864504] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"377.02292ms","start":"2026-06-15T20:03:18.220756Z","end":"2026-06-15T20:03:18.597779Z","steps":["trace[1216864504] 'process raft request' (duration: 332.398504ms)","trace[1216864504] 'compare' (duration: 44.207946ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:18.597929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:18.220738Z","time spent":"377.097681ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:18.598066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.434746ms","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-06-15T20:03:18.598174Z","caller":"traceutil/trace.go:171","msg":"trace[1520270428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1029; }","duration":"449.583089ms","start":"2026-06-15T20:03:18.148572Z","end":"2026-06-15T20:03:18.598155Z","steps":["trace[1520270428] 'agreement among raft nodes before linearized reading' (duration: 449.344704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:18.59822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:18.14855Z","time spent":"449.65682ms","remote":"127.0.0.1:48216","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-06-15T20:03:18.598239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.412703ms","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-06-15T20:03:18.598299Z","caller":"traceutil/trace.go:171","msg":"trace[140195206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1029; }","duration":"618.512215ms","start":"2026-06-15T20:03:17.979769Z","end":"2026-06-15T20:03:18.598282Z","steps":["trace[140195206] 'agreement among raft nodes before linearized reading' (duration: 618.29479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:18.598302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.271924ms","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-06-15T20:03:18.598329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:17.979753Z","time spent":"618.570546ms","remote":"127.0.0.1:48216","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-06-15T20:03:18.598337Z","caller":"traceutil/trace.go:171","msg":"trace[1376201448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1029; }","duration":"447.330696ms","start":"2026-06-15T20:03:18.150997Z","end":"2026-06-15T20:03:18.598327Z","steps":["trace[1376201448] 'agreement among raft nodes before linearized reading' (duration: 447.235934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:18.598374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:18.150985Z","time spent":"447.380127ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-15T20:03:19.87439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.044887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:03:19.87458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.793766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-15T20:03:19.874647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.810513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:03:19.874671Z","caller":"traceutil/trace.go:171","msg":"trace[1861481033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1032; }","duration":"160.901028ms","start":"2026-06-15T20:03:19.713745Z","end":"2026-06-15T20:03:19.874646Z","steps":["trace[1861481033] 'range keys from in-memory index tree' (duration: 160.55664ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:19.874731Z","caller":"traceutil/trace.go:171","msg":"trace[907651546] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1032; }","duration":"349.993927ms","start":"2026-06-15T20:03:19.524716Z","end":"2026-06-15T20:03:19.87471Z","steps":["trace[907651546] 'count revisions from in-memory index tree' (duration: 349.746962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:19.874768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:19.524705Z","time spent":"350.055998ms","remote":"127.0.0.1:48376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-06-15T20:03:19.874697Z","caller":"traceutil/trace.go:171","msg":"trace[294919635] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1032; }","duration":"221.338964ms","start":"2026-06-15T20:03:19.653271Z","end":"2026-06-15T20:03:19.87461Z","steps":["trace[294919635] 'count revisions from in-memory index tree' (duration: 220.976026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:19.874295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.288249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:03:19.87596Z","caller":"traceutil/trace.go:171","msg":"trace[816990299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"370.997148ms","start":"2026-06-15T20:03:19.504928Z","end":"2026-06-15T20:03:19.875925Z","steps":["trace[816990299] 'range keys from in-memory index tree' (duration: 369.197287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:19.87611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:19.50491Z","time spent":"371.158762ms","remote":"127.0.0.1:47930","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-06-15T20:03:20.521391Z","caller":"traceutil/trace.go:171","msg":"trace[502635056] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"451.420832ms","start":"2026-06-15T20:03:20.069952Z","end":"2026-06-15T20:03:20.521373Z","steps":["trace[502635056] 'read index received' (duration: 451.266259ms)","trace[502635056] 'applied index is now lower than readState.Index' (duration: 153.973µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:20.521506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.535305ms","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-06-15T20:03:20.521564Z","caller":"traceutil/trace.go:171","msg":"trace[244241500] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1033; }","duration":"451.636797ms","start":"2026-06-15T20:03:20.069914Z","end":"2026-06-15T20:03:20.521551Z","steps":["trace[244241500] 'agreement among raft nodes before linearized reading' (duration: 451.532595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:20.521596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:20.069892Z","time spent":"451.696278ms","remote":"127.0.0.1:57150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-06-15T20:03:20.522303Z","caller":"traceutil/trace.go:171","msg":"trace[1598974698] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"637.720055ms","start":"2026-06-15T20:03:19.884551Z","end":"2026-06-15T20:03:20.522271Z","steps":["trace[1598974698] 'process raft request' (duration: 636.709151ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:20.522592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:19.884526Z","time spent":"637.93243ms","remote":"127.0.0.1:48216","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-06-15T20:03:20.890033Z","caller":"traceutil/trace.go:171","msg":"trace[2013173634] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"364.847955ms","start":"2026-06-15T20:03:20.525103Z","end":"2026-06-15T20:03:20.889951Z","steps":["trace[2013173634] 'process raft request' (duration: 354.80411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:20.89018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:20.525081Z","time spent":"365.03135ms","remote":"127.0.0.1:48216","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-06-15T20:03:20.891646Z","caller":"traceutil/trace.go:171","msg":"trace[450890158] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1073; }","duration":"268.056838ms","start":"2026-06-15T20:03:20.623567Z","end":"2026-06-15T20:03:20.891624Z","steps":["trace[450890158] 'read index received' (duration: 256.313743ms)","trace[450890158] 'applied index is now lower than readState.Index' (duration: 11.742135ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:20.891728Z","caller":"traceutil/trace.go:171","msg":"trace[1311057291] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"288.948657ms","start":"2026-06-15T20:03:20.602739Z","end":"2026-06-15T20:03:20.891688Z","steps":["trace[1311057291] 'process raft request' (duration: 288.769863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:20.89184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.256873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-15T20:03:20.891873Z","caller":"traceutil/trace.go:171","msg":"trace[597604718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1035; }","duration":"268.358945ms","start":"2026-06-15T20:03:20.623505Z","end":"2026-06-15T20:03:20.891864Z","steps":["trace[597604718] 'agreement among raft nodes before linearized reading' (duration: 268.220942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:20.891978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.443664ms","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-06-15T20:03:20.892061Z","caller":"traceutil/trace.go:171","msg":"trace[1346691624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1035; }","duration":"232.571997ms","start":"2026-06-15T20:03:20.659472Z","end":"2026-06-15T20:03:20.892044Z","steps":["trace[1346691624] 'agreement among raft nodes before linearized reading' (duration: 232.369282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:20.892009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.396273ms","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-06-15T20:03:20.892175Z","caller":"traceutil/trace.go:171","msg":"trace[889721663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1035; }","duration":"232.596478ms","start":"2026-06-15T20:03:20.659569Z","end":"2026-06-15T20:03:20.892166Z","steps":["trace[889721663] 'agreement among raft nodes before linearized reading' (duration: 232.400653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:20.891999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.339279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.18\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-15T20:03:20.892262Z","caller":"traceutil/trace.go:171","msg":"trace[1524183932] range","detail":"{range_begin:/registry/masterleases/199.19.213.18; range_end:; response_count:1; response_revision:1035; }","duration":"199.654476ms","start":"2026-06-15T20:03:20.692599Z","end":"2026-06-15T20:03:20.892254Z","steps":["trace[1524183932] 'agreement among raft nodes before linearized reading' (duration: 199.311658ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:43.893266Z","caller":"traceutil/trace.go:171","msg":"trace[214908608] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1155; }","duration":"387.827674ms","start":"2026-06-15T20:03:43.505417Z","end":"2026-06-15T20:03:43.893245Z","steps":["trace[214908608] 'read index received' (duration: 385.726324ms)","trace[214908608] 'applied index is now lower than readState.Index' (duration: 2.100409ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:43.893364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.932336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:03:43.893388Z","caller":"traceutil/trace.go:171","msg":"trace[777307948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1112; }","duration":"388.015208ms","start":"2026-06-15T20:03:43.505365Z","end":"2026-06-15T20:03:43.89338Z","steps":["trace[777307948] 'agreement among raft nodes before linearized reading' (duration: 387.957836ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:43.893381Z","caller":"traceutil/trace.go:171","msg":"trace[675065748] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"609.630007ms","start":"2026-06-15T20:03:43.283726Z","end":"2026-06-15T20:03:43.893356Z","steps":["trace[675065748] 'process raft request' (duration: 609.442863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:43.893412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:43.505343Z","time spent":"388.064128ms","remote":"127.0.0.1:47938","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-06-15T20:03:43.893488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:43.283718Z","time spent":"609.71142ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:03:43.893349Z","caller":"traceutil/trace.go:171","msg":"trace[1391788995] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"611.254425ms","start":"2026-06-15T20:03:43.282064Z","end":"2026-06-15T20:03:43.893318Z","steps":["trace[1391788995] 'process raft request' (duration: 609.172656ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:43.894351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:43.282036Z","time spent":"612.234957ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:45.723298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993703069939943295,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T20:03:45.831107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.991477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T20:03:45.831186Z","caller":"traceutil/trace.go:171","msg":"trace[1680855479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1112; }","duration":"955.116239ms","start":"2026-06-15T20:03:44.876048Z","end":"2026-06-15T20:03:45.831165Z","steps":["trace[1680855479] 'range keys from in-memory index tree' (duration: 954.824142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.831231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:44.876028Z","time spent":"955.194011ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:03:45.831587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327860098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:03:45.83166Z","caller":"traceutil/trace.go:171","msg":"trace[1681425747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1112; }","duration":"1.328033702s","start":"2026-06-15T20:03:44.503608Z","end":"2026-06-15T20:03:45.831641Z","steps":["trace[1681425747] 'range keys from in-memory index tree' (duration: 1.327808117s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.831713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:44.503591Z","time spent":"1.328109764s","remote":"127.0.0.1:47930","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-06-15T20:03:45.832462Z","caller":"traceutil/trace.go:171","msg":"trace[1573702521] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1157; }","duration":"610.142029ms","start":"2026-06-15T20:03:45.222281Z","end":"2026-06-15T20:03:45.832423Z","steps":["trace[1573702521] 'read index received' (duration: 558.825688ms)","trace[1573702521] 'applied index is now lower than readState.Index' (duration: 51.315091ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:45.83268Z","caller":"traceutil/trace.go:171","msg":"trace[1934999507] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"638.32599ms","start":"2026-06-15T20:03:45.194335Z","end":"2026-06-15T20:03:45.832661Z","steps":["trace[1934999507] 'process raft request' (duration: 586.848485ms)","trace[1934999507] 'compare' (duration: 50.216736ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:45.832913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:45.194317Z","time spent":"638.411112ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:45.833184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.15392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-15T20:03:45.833265Z","caller":"traceutil/trace.go:171","msg":"trace[753768748] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1113; }","duration":"499.293883ms","start":"2026-06-15T20:03:45.333953Z","end":"2026-06-15T20:03:45.833247Z","steps":["trace[753768748] 'agreement among raft nodes before linearized reading' (duration: 499.070578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.833262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.964638ms","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-06-15T20:03:45.833312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:45.33393Z","time spent":"499.373025ms","remote":"127.0.0.1:48216","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-06-15T20:03:45.833325Z","caller":"traceutil/trace.go:171","msg":"trace[387794427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1113; }","duration":"611.075191ms","start":"2026-06-15T20:03:45.222231Z","end":"2026-06-15T20:03:45.833306Z","steps":["trace[387794427] 'agreement among raft nodes before linearized reading' (duration: 610.910597ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.833373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:45.222209Z","time spent":"611.152333ms","remote":"127.0.0.1:48216","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-06-15T20:03:46.262832Z","caller":"traceutil/trace.go:171","msg":"trace[1688400139] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"425.046615ms","start":"2026-06-15T20:03:45.837745Z","end":"2026-06-15T20:03:46.262791Z","steps":["trace[1688400139] 'process raft request' (duration: 374.835439ms)","trace[1688400139] 'compare' (duration: 50.034682ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:46.262992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:45.837722Z","time spent":"425.189188ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:03:46.291216Z","caller":"traceutil/trace.go:171","msg":"trace[1125303163] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"453.263206ms","start":"2026-06-15T20:03:45.837922Z","end":"2026-06-15T20:03:46.291186Z","steps":["trace[1125303163] 'process raft request' (duration: 452.898828ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:46.29128Z","caller":"traceutil/trace.go:171","msg":"trace[849244776] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"453.179883ms","start":"2026-06-15T20:03:45.838068Z","end":"2026-06-15T20:03:46.291248Z","steps":["trace[849244776] 'process raft request' (duration: 452.856146ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:46.291448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:45.837907Z","time spent":"453.43349ms","remote":"127.0.0.1:48216","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-06-15T20:03:46.291573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:45.83806Z","time spent":"453.307556ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:46.404291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.277163ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-15T20:03:46.405204Z","caller":"traceutil/trace.go:171","msg":"trace[127616382] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"320.21426ms","start":"2026-06-15T20:03:46.084966Z","end":"2026-06-15T20:03:46.40518Z","steps":["trace[127616382] 'process raft request' (duration: 319.991464ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:46.405283Z","caller":"traceutil/trace.go:171","msg":"trace[1468658296] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1158; }","duration":"563.858627ms","start":"2026-06-15T20:03:45.841394Z","end":"2026-06-15T20:03:46.405253Z","steps":["trace[1468658296] 'read index received' (duration: 371.158993ms)","trace[1468658296] 'applied index is now lower than readState.Index' (duration: 192.697054ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:46.405352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:46.084936Z","time spent":"320.332763ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:46.40551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.432748ms","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-06-15T20:03:46.405544Z","caller":"traceutil/trace.go:171","msg":"trace[1183777046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1117; }","duration":"508.52677ms","start":"2026-06-15T20:03:45.89701Z","end":"2026-06-15T20:03:46.405537Z","steps":["trace[1183777046] 'agreement among raft nodes before linearized reading' (duration: 508.374697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:46.405565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:45.896994Z","time spent":"508.566251ms","remote":"127.0.0.1:48216","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-06-15T20:03:46.405657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.711711ms","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-06-15T20:03:46.405729Z","caller":"traceutil/trace.go:171","msg":"trace[2061758390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1117; }","duration":"507.833764ms","start":"2026-06-15T20:03:45.897879Z","end":"2026-06-15T20:03:46.405713Z","steps":["trace[2061758390] 'agreement among raft nodes before linearized reading' (duration: 507.577428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:46.40579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:45.897868Z","time spent":"507.913766ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T20:03:46.406038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.038161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:03:46.406135Z","caller":"traceutil/trace.go:171","msg":"trace[421799232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1117; }","duration":"564.744006ms","start":"2026-06-15T20:03:45.841373Z","end":"2026-06-15T20:03:46.406117Z","steps":["trace[421799232] 'agreement among raft nodes before linearized reading' (duration: 563.964929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:46.406185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:45.841365Z","time spent":"564.809928ms","remote":"127.0.0.1:47930","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-06-15T20:03:46.633324Z","caller":"traceutil/trace.go:171","msg":"trace[1186818410] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"222.684555ms","start":"2026-06-15T20:03:46.410603Z","end":"2026-06-15T20:03:46.633288Z","steps":["trace[1186818410] 'process raft request' (duration: 204.672973ms)","trace[1186818410] 'compare' (duration: 17.776786ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:46.634267Z","caller":"traceutil/trace.go:171","msg":"trace[1353753266] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"222.798647ms","start":"2026-06-15T20:03:46.411454Z","end":"2026-06-15T20:03:46.634252Z","steps":["trace[1353753266] 'process raft request' (duration: 221.762703ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:48.243908Z","caller":"traceutil/trace.go:171","msg":"trace[370884358] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"404.96543ms","start":"2026-06-15T20:03:47.838918Z","end":"2026-06-15T20:03:48.243884Z","steps":["trace[370884358] 'process raft request' (duration: 404.813176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:48.244065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:47.838892Z","time spent":"405.090753ms","remote":"127.0.0.1:48216","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-06-15T20:03:48.48557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.86776ms","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-06-15T20:03:48.485588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.366754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T20:03:48.485662Z","caller":"traceutil/trace.go:171","msg":"trace[412016970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1120; }","duration":"219.025053ms","start":"2026-06-15T20:03:48.266616Z","end":"2026-06-15T20:03:48.485641Z","steps":["trace[412016970] 'range keys from in-memory index tree' (duration: 218.635665ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:48.485667Z","caller":"traceutil/trace.go:171","msg":"trace[170979613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1120; }","duration":"190.475757ms","start":"2026-06-15T20:03:48.295173Z","end":"2026-06-15T20:03:48.485649Z","steps":["trace[170979613] 'range keys from in-memory index tree' (duration: 190.209441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:48.485538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.581718ms","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-06-15T20:03:48.486059Z","caller":"traceutil/trace.go:171","msg":"trace[1943273458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1120; }","duration":"191.168321ms","start":"2026-06-15T20:03:48.294865Z","end":"2026-06-15T20:03:48.486034Z","steps":["trace[1943273458] 'range keys from in-memory index tree' (duration: 190.334763ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:48.6234Z","caller":"traceutil/trace.go:171","msg":"trace[1864149543] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"133.275157ms","start":"2026-06-15T20:03:48.489856Z","end":"2026-06-15T20:03:48.623131Z","steps":["trace[1864149543] 'process raft request' (duration: 133.114354ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:48.651348Z","caller":"traceutil/trace.go:171","msg":"trace[49379979] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"160.861675ms","start":"2026-06-15T20:03:48.490464Z","end":"2026-06-15T20:03:48.651325Z","steps":["trace[49379979] 'process raft request' (duration: 160.707311ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:48.651627Z","caller":"traceutil/trace.go:171","msg":"trace[1015130022] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"160.926886ms","start":"2026-06-15T20:03:48.490681Z","end":"2026-06-15T20:03:48.651608Z","steps":["trace[1015130022] 'process raft request' (duration: 160.609269ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:48.788108Z","caller":"traceutil/trace.go:171","msg":"trace[1025727454] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"131.976589ms","start":"2026-06-15T20:03:48.656108Z","end":"2026-06-15T20:03:48.788085Z","steps":["trace[1025727454] 'process raft request' (duration: 131.838085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:50.873136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.898686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:03:50.873327Z","caller":"traceutil/trace.go:171","msg":"trace[1715895238] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"211.610559ms","start":"2026-06-15T20:03:50.661696Z","end":"2026-06-15T20:03:50.873307Z","steps":["trace[1715895238] 'process raft request' (duration: 24.450636ms)","trace[1715895238] 'compare' (duration: 186.723522ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:50.87349Z","caller":"traceutil/trace.go:171","msg":"trace[1809262415] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"211.154208ms","start":"2026-06-15T20:03:50.662323Z","end":"2026-06-15T20:03:50.873477Z","steps":["trace[1809262415] 'process raft request' (duration: 210.931364ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:50.873478Z","caller":"traceutil/trace.go:171","msg":"trace[495352152] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1173; }","duration":"179.127813ms","start":"2026-06-15T20:03:50.69432Z","end":"2026-06-15T20:03:50.873448Z","steps":["trace[495352152] 'read index received' (duration: 5.80607ms)","trace[495352152] 'applied index is now lower than readState.Index' (duration: 173.319843ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:50.873663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.344477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.18\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-15T20:03:50.873697Z","caller":"traceutil/trace.go:171","msg":"trace[769691922] range","detail":"{range_begin:/registry/masterleases/199.19.213.18; range_end:; response_count:1; response_revision:1129; }","duration":"179.410129ms","start":"2026-06-15T20:03:50.694278Z","end":"2026-06-15T20:03:50.873688Z","steps":["trace[769691922] 'agreement among raft nodes before linearized reading' (duration: 179.279916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:50.873714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.873486ms","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-06-15T20:03:50.873759Z","caller":"traceutil/trace.go:171","msg":"trace[69299664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1129; }","duration":"131.974058ms","start":"2026-06-15T20:03:50.741773Z","end":"2026-06-15T20:03:50.873747Z","steps":["trace[69299664] 'agreement among raft nodes before linearized reading' (duration: 131.820465ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:51.07879Z","caller":"traceutil/trace.go:171","msg":"trace[1220896110] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"200.018741ms","start":"2026-06-15T20:03:50.878745Z","end":"2026-06-15T20:03:51.078764Z","steps":["trace[1220896110] 'process raft request' (duration: 199.952119ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:51.078952Z","caller":"traceutil/trace.go:171","msg":"trace[792243317] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"201.645406ms","start":"2026-06-15T20:03:50.877266Z","end":"2026-06-15T20:03:51.078912Z","steps":["trace[792243317] 'process raft request' (duration: 201.266177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:07.37114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.370393ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-15T20:04:07.376439Z","caller":"traceutil/trace.go:171","msg":"trace[1002370112] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1280; }","duration":"872.045347ms","start":"2026-06-15T20:04:06.504375Z","end":"2026-06-15T20:04:07.37642Z","steps":["trace[1002370112] 'read index received' (duration: 263.398396ms)","trace[1002370112] 'applied index is now lower than readState.Index' (duration: 608.645831ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:07.37653Z","caller":"traceutil/trace.go:171","msg":"trace[2090025896] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"670.289589ms","start":"2026-06-15T20:04:06.706231Z","end":"2026-06-15T20:04:07.376521Z","steps":["trace[2090025896] 'process raft request' (duration: 670.152576ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:07.376533Z","caller":"traceutil/trace.go:171","msg":"trace[1456544960] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"981.166056ms","start":"2026-06-15T20:04:06.395354Z","end":"2026-06-15T20:04:07.37652Z","steps":["trace[1456544960] 'process raft request' (duration: 980.91448ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:07.376642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:06.706217Z","time spent":"670.34455ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:07.376696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.000485ms","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-06-15T20:04:07.376732Z","caller":"traceutil/trace.go:171","msg":"trace[1797593402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1231; }","duration":"599.040246ms","start":"2026-06-15T20:04:06.777679Z","end":"2026-06-15T20:04:07.376719Z","steps":["trace[1797593402] 'agreement among raft nodes before linearized reading' (duration: 598.892133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:07.376747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:06.395333Z","time spent":"981.230457ms","remote":"127.0.0.1:48216","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-06-15T20:04:07.376761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:06.777641Z","time spent":"599.114578ms","remote":"127.0.0.1:48216","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-06-15T20:04:07.376867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.219668ms","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-06-15T20:04:07.376918Z","caller":"traceutil/trace.go:171","msg":"trace[1196068821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1231; }","duration":"415.29784ms","start":"2026-06-15T20:04:06.961614Z","end":"2026-06-15T20:04:07.376912Z","steps":["trace[1196068821] 'agreement among raft nodes before linearized reading' (duration: 415.212468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:07.376951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:06.961603Z","time spent":"415.343871ms","remote":"127.0.0.1:48216","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-06-15T20:04:07.376959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.588509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:07.376984Z","caller":"traceutil/trace.go:171","msg":"trace[719192134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1231; }","duration":"872.63371ms","start":"2026-06-15T20:04:06.504343Z","end":"2026-06-15T20:04:07.376977Z","steps":["trace[719192134] 'agreement among raft nodes before linearized reading' (duration: 872.599829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:07.377004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:06.50433Z","time spent":"872.669001ms","remote":"127.0.0.1:47930","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-06-15T20:04:07.377052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.960326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T20:04:07.377071Z","caller":"traceutil/trace.go:171","msg":"trace[1592965226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1231; }","duration":"425.020618ms","start":"2026-06-15T20:04:06.952045Z","end":"2026-06-15T20:04:07.377065Z","steps":["trace[1592965226] 'agreement among raft nodes before linearized reading' (duration: 424.969426ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:07.377088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:06.952021Z","time spent":"425.062898ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:04:07.377167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.331283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-15T20:04:07.376926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.995988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:04:07.3772Z","caller":"traceutil/trace.go:171","msg":"trace[203448076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1231; }","duration":"109.418944ms","start":"2026-06-15T20:04:07.267775Z","end":"2026-06-15T20:04:07.377194Z","steps":["trace[203448076] 'agreement among raft nodes before linearized reading' (duration: 109.327062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:07.377203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.503077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:07.377213Z","caller":"traceutil/trace.go:171","msg":"trace[1870392720] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1231; }","duration":"238.332956ms","start":"2026-06-15T20:04:07.138873Z","end":"2026-06-15T20:04:07.377206Z","steps":["trace[1870392720] 'agreement among raft nodes before linearized reading' (duration: 237.924337ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:07.377228Z","caller":"traceutil/trace.go:171","msg":"trace[608216143] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1231; }","duration":"476.528898ms","start":"2026-06-15T20:04:06.90069Z","end":"2026-06-15T20:04:07.377219Z","steps":["trace[608216143] 'agreement among raft nodes before linearized reading' (duration: 476.493587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:07.377253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:06.900653Z","time spent":"476.59121ms","remote":"127.0.0.1:57048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:04:07.377339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.476166ms","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-06-15T20:04:07.377369Z","caller":"traceutil/trace.go:171","msg":"trace[878286144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1231; }","duration":"113.545377ms","start":"2026-06-15T20:04:07.263817Z","end":"2026-06-15T20:04:07.377362Z","steps":["trace[878286144] 'agreement among raft nodes before linearized reading' (duration: 113.478716ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:07.377168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.864369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:07.377494Z","caller":"traceutil/trace.go:171","msg":"trace[1216570982] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1231; }","duration":"576.227136ms","start":"2026-06-15T20:04:06.80126Z","end":"2026-06-15T20:04:07.377487Z","steps":["trace[1216570982] 'agreement among raft nodes before linearized reading' (duration: 575.882138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:07.377522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:06.801242Z","time spent":"576.269187ms","remote":"127.0.0.1:57164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:04:24.04484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993703069939943708,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T20:04:24.235197Z","caller":"traceutil/trace.go:171","msg":"trace[1867410411] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"690.596203ms","start":"2026-06-15T20:04:23.544577Z","end":"2026-06-15T20:04:24.235173Z","steps":["trace[1867410411] 'read index received' (duration: 689.990628ms)","trace[1867410411] 'applied index is now lower than readState.Index' (duration: 604.655µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:24.235513Z","caller":"traceutil/trace.go:171","msg":"trace[1269666583] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"728.373626ms","start":"2026-06-15T20:04:23.507117Z","end":"2026-06-15T20:04:24.235491Z","steps":["trace[1269666583] 'process raft request' (duration: 727.687041ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:24.235585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.981151ms","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-06-15T20:04:24.235632Z","caller":"traceutil/trace.go:171","msg":"trace[941075323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1292; }","duration":"691.098604ms","start":"2026-06-15T20:04:23.544521Z","end":"2026-06-15T20:04:24.23562Z","steps":["trace[941075323] 'agreement among raft nodes before linearized reading' (duration: 690.886729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:24.235651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:23.507096Z","time spent":"728.469198ms","remote":"127.0.0.1:48216","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-06-15T20:04:24.235672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:23.544499Z","time spent":"691.165944ms","remote":"127.0.0.1:48216","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-06-15T20:04:24.235639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.94756ms","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-06-15T20:04:24.235728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.181051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-15T20:04:24.235805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.495527ms","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-06-15T20:04:24.235819Z","caller":"traceutil/trace.go:171","msg":"trace[1942380950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1292; }","duration":"685.286423ms","start":"2026-06-15T20:04:23.550507Z","end":"2026-06-15T20:04:24.235794Z","steps":["trace[1942380950] 'agreement among raft nodes before linearized reading' (duration: 685.062778ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:24.235849Z","caller":"traceutil/trace.go:171","msg":"trace[2106695041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1292; }","duration":"689.546128ms","start":"2026-06-15T20:04:23.546289Z","end":"2026-06-15T20:04:24.235835Z","steps":["trace[2106695041] 'agreement among raft nodes before linearized reading' (duration: 689.467487ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:24.235778Z","caller":"traceutil/trace.go:171","msg":"trace[720526982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1292; }","duration":"691.195536ms","start":"2026-06-15T20:04:23.544564Z","end":"2026-06-15T20:04:24.235759Z","steps":["trace[720526982] 'agreement among raft nodes before linearized reading' (duration: 690.890589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:24.235889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:23.550502Z","time spent":"685.366146ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:04:24.235708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.299778ms","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-06-15T20:04:24.235961Z","caller":"traceutil/trace.go:171","msg":"trace[938557168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1292; }","duration":"687.593105ms","start":"2026-06-15T20:04:23.548362Z","end":"2026-06-15T20:04:24.235955Z","steps":["trace[938557168] 'agreement among raft nodes before linearized reading' (duration: 687.148875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:24.235985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:23.548357Z","time spent":"687.622936ms","remote":"127.0.0.1:48216","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-06-15T20:04:24.236015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:23.544548Z","time spent":"691.39541ms","remote":"127.0.0.1:48216","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-06-15T20:04:24.235883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:23.546284Z","time spent":"689.592749ms","remote":"127.0.0.1:48216","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-06-15T20:04:24.438274Z","caller":"traceutil/trace.go:171","msg":"trace[443199315] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1349; }","duration":"110.256313ms","start":"2026-06-15T20:04:24.327999Z","end":"2026-06-15T20:04:24.438255Z","steps":["trace[443199315] 'read index received' (duration: 109.999637ms)","trace[443199315] 'applied index is now lower than readState.Index' (duration: 256.136µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:24.438316Z","caller":"traceutil/trace.go:171","msg":"trace[678175644] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"197.780179ms","start":"2026-06-15T20:04:24.2405Z","end":"2026-06-15T20:04:24.43828Z","steps":["trace[678175644] 'process raft request' (duration: 197.705587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:24.438397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.373396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:04:24.438419Z","caller":"traceutil/trace.go:171","msg":"trace[455903355] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1297; }","duration":"110.472449ms","start":"2026-06-15T20:04:24.32794Z","end":"2026-06-15T20:04:24.438413Z","steps":["trace[455903355] 'agreement among raft nodes before linearized reading' (duration: 110.394587ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:24.438472Z","caller":"traceutil/trace.go:171","msg":"trace[744057823] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"197.156705ms","start":"2026-06-15T20:04:24.241275Z","end":"2026-06-15T20:04:24.438431Z","steps":["trace[744057823] 'process raft request' (duration: 196.956781ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:24.438571Z","caller":"traceutil/trace.go:171","msg":"trace[1058799874] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"198.297441ms","start":"2026-06-15T20:04:24.240265Z","end":"2026-06-15T20:04:24.438562Z","steps":["trace[1058799874] 'process raft request' (duration: 197.78803ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:26.090473Z","caller":"traceutil/trace.go:171","msg":"trace[1264142585] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"459.507167ms","start":"2026-06-15T20:04:25.630936Z","end":"2026-06-15T20:04:26.090443Z","steps":["trace[1264142585] 'read index received' (duration: 459.397725ms)","trace[1264142585] 'applied index is now lower than readState.Index' (duration: 108.162µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:26.090494Z","caller":"traceutil/trace.go:171","msg":"trace[258814692] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"497.108588ms","start":"2026-06-15T20:04:25.593356Z","end":"2026-06-15T20:04:26.090464Z","steps":["trace[258814692] 'process raft request' (duration: 496.888833ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:26.090644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.683711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:26.090696Z","caller":"traceutil/trace.go:171","msg":"trace[538373556] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1299; }","duration":"459.782293ms","start":"2026-06-15T20:04:25.630901Z","end":"2026-06-15T20:04:26.090683Z","steps":["trace[538373556] 'agreement among raft nodes before linearized reading' (duration: 459.651991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:26.090689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:25.593337Z","time spent":"497.274011ms","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T20:04:26.090738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:25.630883Z","time spent":"459.845124ms","remote":"127.0.0.1:59416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:04:26.517226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.164589ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-15T20:04:26.517423Z","caller":"traceutil/trace.go:171","msg":"trace[329570971] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"426.847595ms","start":"2026-06-15T20:04:26.09056Z","end":"2026-06-15T20:04:26.517407Z","steps":["trace[329570971] 'read index received' (duration: 13.4219ms)","trace[329570971] 'applied index is now lower than readState.Index' (duration: 413.424765ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:26.517941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.461906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:26.517984Z","caller":"traceutil/trace.go:171","msg":"trace[986893848] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1299; }","duration":"643.528017ms","start":"2026-06-15T20:04:25.874446Z","end":"2026-06-15T20:04:26.517974Z","steps":["trace[986893848] 'agreement among raft nodes before linearized reading' (duration: 643.450816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:26.518021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:25.874441Z","time spent":"643.570158ms","remote":"127.0.0.1:59372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:04:26.518341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.744699ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:04:26.518369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.559601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-06-15T20:04:26.518418Z","caller":"traceutil/trace.go:171","msg":"trace[43584026] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1299; }","duration":"266.827791ms","start":"2026-06-15T20:04:26.251581Z","end":"2026-06-15T20:04:26.518408Z","steps":["trace[43584026] 'range keys from in-memory index tree' (duration: 266.733819ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:26.518432Z","caller":"traceutil/trace.go:171","msg":"trace[245327436] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1299; }","duration":"674.647413ms","start":"2026-06-15T20:04:25.843769Z","end":"2026-06-15T20:04:26.518416Z","steps":["trace[245327436] 'agreement among raft nodes before linearized reading' (duration: 674.454829ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:26.518442Z","caller":"traceutil/trace.go:171","msg":"trace[332041076] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"407.135055ms","start":"2026-06-15T20:04:26.111282Z","end":"2026-06-15T20:04:26.518417Z","steps":["trace[332041076] 'process raft request' (duration: 406.0424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:26.518508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:25.843756Z","time spent":"674.741655ms","remote":"127.0.0.1:48142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5353,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-15T20:04:26.518615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:26.111265Z","time spent":"407.281908ms","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T20:04:27.364648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.219365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:04:27.36479Z","caller":"traceutil/trace.go:171","msg":"trace[1067301651] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1356; }","duration":"847.311834ms","start":"2026-06-15T20:04:26.517457Z","end":"2026-06-15T20:04:27.364769Z","steps":["trace[1067301651] 'read index received' (duration: 2.907165ms)","trace[1067301651] 'applied index is now lower than readState.Index' (duration: 844.403249ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:27.365091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086677691s","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-06-15T20:04:27.36512Z","caller":"traceutil/trace.go:171","msg":"trace[479740659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1301; }","duration":"1.086735262s","start":"2026-06-15T20:04:26.278375Z","end":"2026-06-15T20:04:27.36511Z","steps":["trace[479740659] 'agreement among raft nodes before linearized reading' (duration: 1.086465056s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:27.365153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:26.27836Z","time spent":"1.086785334s","remote":"127.0.0.1:48216","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-06-15T20:04:27.365172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.084919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:27.365274Z","caller":"traceutil/trace.go:171","msg":"trace[2117187983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"860.247623ms","start":"2026-06-15T20:04:26.505001Z","end":"2026-06-15T20:04:27.365248Z","steps":["trace[2117187983] 'agreement among raft nodes before linearized reading' (duration: 860.029988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:27.365328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:26.504949Z","time spent":"860.367906ms","remote":"127.0.0.1:47930","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-06-15T20:04:27.365432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086313723s","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-06-15T20:04:27.365424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.441991ms","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-06-15T20:04:27.365462Z","caller":"traceutil/trace.go:171","msg":"trace[562257807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1301; }","duration":"1.086379175s","start":"2026-06-15T20:04:26.279075Z","end":"2026-06-15T20:04:27.365454Z","steps":["trace[562257807] 'agreement among raft nodes before linearized reading' (duration: 1.086300213s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:27.365489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:26.279051Z","time spent":"1.086433686s","remote":"127.0.0.1:48216","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":"info","ts":"2026-06-15T20:04:27.3655Z","caller":"traceutil/trace.go:171","msg":"trace[231067952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1301; }","duration":"922.567084ms","start":"2026-06-15T20:04:26.442913Z","end":"2026-06-15T20:04:27.36548Z","steps":["trace[231067952] 'agreement among raft nodes before linearized reading' (duration: 922.339109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:27.365546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:26.442893Z","time spent":"922.644896ms","remote":"127.0.0.1:48216","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-06-15T20:04:27.365677Z","caller":"traceutil/trace.go:171","msg":"trace[616224104] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"1.124673679s","start":"2026-06-15T20:04:26.240984Z","end":"2026-06-15T20:04:27.365658Z","steps":["trace[616224104] 'process raft request' (duration: 279.318189ms)","trace[616224104] 'compare' (duration: 843.251823ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:27.365733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.545104ms","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-06-15T20:04:27.365786Z","caller":"traceutil/trace.go:171","msg":"trace[161925644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1301; }","duration":"922.660207ms","start":"2026-06-15T20:04:26.443113Z","end":"2026-06-15T20:04:27.365773Z","steps":["trace[161925644] 'agreement among raft nodes before linearized reading' (duration: 922.466792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:27.365802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:26.240964Z","time spent":"1.124752141s","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:27.365837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:26.443095Z","time spent":"922.734398ms","remote":"127.0.0.1:48216","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-06-15T20:04:27.366039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.072105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T20:04:27.366092Z","caller":"traceutil/trace.go:171","msg":"trace[61884368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1301; }","duration":"923.109186ms","start":"2026-06-15T20:04:26.442956Z","end":"2026-06-15T20:04:27.366065Z","steps":["trace[61884368] 'agreement among raft nodes before linearized reading' (duration: 923.005994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:27.366115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:26.442893Z","time spent":"923.217009ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:04:27.569287Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.045605034s","expected-duration":"1s"} {"level":"info","ts":"2026-06-15T20:04:27.637494Z","caller":"traceutil/trace.go:171","msg":"trace[1111143774] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"1.1138867s","start":"2026-06-15T20:04:26.523583Z","end":"2026-06-15T20:04:27.637469Z","steps":["trace[1111143774] 'process raft request' (duration: 1.045964072s)","trace[1111143774] 'compare' (duration: 67.773244ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:27.637633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:26.523566Z","time spent":"1.113995672s","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T20:04:28.477735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.962797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:04:28.478023Z","caller":"traceutil/trace.go:171","msg":"trace[330965446] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"1.108303715s","start":"2026-06-15T20:04:27.369691Z","end":"2026-06-15T20:04:28.477995Z","steps":["trace[330965446] 'process raft request' (duration: 1.108132771s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:28.478108Z","caller":"traceutil/trace.go:171","msg":"trace[27330810] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"1.108347306s","start":"2026-06-15T20:04:27.369754Z","end":"2026-06-15T20:04:28.478101Z","steps":["trace[27330810] 'process raft request' (duration: 1.108129671s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:28.478161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:27.369749Z","time spent":"1.108383417s","remote":"127.0.0.1:48216","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-06-15T20:04:28.478189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:27.369677Z","time spent":"1.108436078s","remote":"127.0.0.1:48216","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-06-15T20:04:28.478319Z","caller":"traceutil/trace.go:171","msg":"trace[1216653700] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"1.95070697s","start":"2026-06-15T20:04:26.527599Z","end":"2026-06-15T20:04:28.478306Z","steps":["trace[1216653700] 'process raft request' (duration: 1.448073688s)","trace[1216653700] 'compare' (duration: 501.578468ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:28.478373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:26.52758Z","time spent":"1.9507659s","remote":"127.0.0.1:48142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:28.478396Z","caller":"traceutil/trace.go:171","msg":"trace[1650379544] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"1.107578669s","start":"2026-06-15T20:04:27.370811Z","end":"2026-06-15T20:04:28.478389Z","steps":["trace[1650379544] 'process raft request' (duration: 1.10716261s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:28.478402Z","caller":"traceutil/trace.go:171","msg":"trace[413238422] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1357; }","duration":"1.110603476s","start":"2026-06-15T20:04:27.367789Z","end":"2026-06-15T20:04:28.478393Z","steps":["trace[413238422] 'read index received' (duration: 201.771238ms)","trace[413238422] 'applied index is now lower than readState.Index' (duration: 908.831498ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:28.478436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:27.370806Z","time spent":"1.10760903s","remote":"127.0.0.1:48216","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-06-15T20:04:28.478452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110663138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:28.478468Z","caller":"traceutil/trace.go:171","msg":"trace[1045160341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"1.110734409s","start":"2026-06-15T20:04:27.367729Z","end":"2026-06-15T20:04:28.478464Z","steps":["trace[1045160341] 'agreement among raft nodes before linearized reading' (duration: 1.110699188s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:28.478486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:27.367712Z","time spent":"1.11077164s","remote":"127.0.0.1:47938","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-06-15T20:04:28.478055Z","caller":"traceutil/trace.go:171","msg":"trace[1123943212] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"631.142423ms","start":"2026-06-15T20:04:27.846899Z","end":"2026-06-15T20:04:28.478041Z","steps":["trace[1123943212] 'process raft request' (duration: 631.110862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:28.478539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:27.846873Z","time spent":"631.636704ms","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:28.478045Z","caller":"traceutil/trace.go:171","msg":"trace[215683888] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"1.107347204s","start":"2026-06-15T20:04:27.370682Z","end":"2026-06-15T20:04:28.478029Z","steps":["trace[215683888] 'process raft request' (duration: 1.107232812s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:28.478651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:27.370675Z","time spent":"1.107955348s","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:28.478777Z","caller":"traceutil/trace.go:171","msg":"trace[2109636431] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"1.107983198s","start":"2026-06-15T20:04:27.370778Z","end":"2026-06-15T20:04:28.478761Z","steps":["trace[2109636431] 'process raft request' (duration: 1.107165s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:28.479933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:27.370773Z","time spent":"1.108098592s","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:28.73689Z","caller":"traceutil/trace.go:171","msg":"trace[1423312884] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"252.129104ms","start":"2026-06-15T20:04:28.484733Z","end":"2026-06-15T20:04:28.736863Z","steps":["trace[1423312884] 'read index received' (duration: 157.186912ms)","trace[1423312884] 'applied index is now lower than readState.Index' (duration: 94.941022ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:28.737016Z","caller":"traceutil/trace.go:171","msg":"trace[1337341966] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"252.337779ms","start":"2026-06-15T20:04:28.484656Z","end":"2026-06-15T20:04:28.736994Z","steps":["trace[1337341966] 'process raft request' (duration: 157.332646ms)","trace[1337341966] 'compare' (duration: 94.710547ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:28.737081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.273187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:28.73712Z","caller":"traceutil/trace.go:171","msg":"trace[1879614104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1310; }","duration":"252.400521ms","start":"2026-06-15T20:04:28.484708Z","end":"2026-06-15T20:04:28.737108Z","steps":["trace[1879614104] 'agreement among raft nodes before linearized reading' (duration: 252.256007ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:29.536906Z","caller":"traceutil/trace.go:171","msg":"trace[1719704085] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"165.880486ms","start":"2026-06-15T20:04:29.370991Z","end":"2026-06-15T20:04:29.536872Z","steps":["trace[1719704085] 'process raft request' (duration: 165.567169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:30.042786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.397011ms","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-06-15T20:04:30.042919Z","caller":"traceutil/trace.go:171","msg":"trace[2030419158] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1311; }","duration":"150.575015ms","start":"2026-06-15T20:04:29.892312Z","end":"2026-06-15T20:04:30.042887Z","steps":["trace[2030419158] 'count revisions from in-memory index tree' (duration: 150.294538ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:30.504199Z","caller":"traceutil/trace.go:171","msg":"trace[2062235384] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"428.905695ms","start":"2026-06-15T20:04:30.074834Z","end":"2026-06-15T20:04:30.50374Z","steps":["trace[2062235384] 'process raft request' (duration: 428.760221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:30.504397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:30.074804Z","time spent":"429.546519ms","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T20:04:37.138427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993703069939943841,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T20:04:37.163229Z","caller":"traceutil/trace.go:171","msg":"trace[1269201040] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"526.650549ms","start":"2026-06-15T20:04:36.636544Z","end":"2026-06-15T20:04:37.163194Z","steps":["trace[1269201040] 'process raft request' (duration: 526.393803ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:37.163534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:36.636528Z","time spent":"526.862294ms","remote":"127.0.0.1:48216","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-06-15T20:04:38.130771Z","caller":"traceutil/trace.go:171","msg":"trace[1950157708] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1416; }","duration":"1.493424274s","start":"2026-06-15T20:04:36.637322Z","end":"2026-06-15T20:04:38.130747Z","steps":["trace[1950157708] 'read index received' (duration: 525.711349ms)","trace[1950157708] 'applied index is now lower than readState.Index' (duration: 967.709195ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:38.131185Z","caller":"traceutil/trace.go:171","msg":"trace[503935192] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"1.492016744s","start":"2026-06-15T20:04:36.639122Z","end":"2026-06-15T20:04:38.131139Z","steps":["trace[503935192] 'process raft request' (duration: 1.491457862s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:38.131221Z","caller":"traceutil/trace.go:171","msg":"trace[2100971072] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"1.490739855s","start":"2026-06-15T20:04:36.640444Z","end":"2026-06-15T20:04:38.131184Z","steps":["trace[2100971072] 'process raft request' (duration: 1.490222303s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:38.131249Z","caller":"traceutil/trace.go:171","msg":"trace[1601299719] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"1.492842942s","start":"2026-06-15T20:04:36.638381Z","end":"2026-06-15T20:04:38.131224Z","steps":["trace[1601299719] 'process raft request' (duration: 1.491984503s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:38.131321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:36.639112Z","time spent":"1.492137736s","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:38.131389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:36.640434Z","time spent":"1.490861257s","remote":"127.0.0.1:48216","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-06-15T20:04:38.132013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:36.638348Z","time spent":"1.493604739s","remote":"127.0.0.1:48216","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-06-15T20:04:38.131926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.494577439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T20:04:38.132283Z","caller":"traceutil/trace.go:171","msg":"trace[237059418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1362; }","duration":"1.494984758s","start":"2026-06-15T20:04:36.637275Z","end":"2026-06-15T20:04:38.13226Z","steps":["trace[237059418] 'agreement among raft nodes before linearized reading' (duration: 1.493892385s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:38.132399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:36.637109Z","time spent":"1.495215535s","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:04:38.133357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29455217s","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-06-15T20:04:38.133427Z","caller":"traceutil/trace.go:171","msg":"trace[1024017515] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"565.996909ms","start":"2026-06-15T20:04:37.567408Z","end":"2026-06-15T20:04:38.133405Z","steps":["trace[1024017515] 'process raft request' (duration: 565.710882ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:38.133445Z","caller":"traceutil/trace.go:171","msg":"trace[1260678350] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1363; }","duration":"1.294671383s","start":"2026-06-15T20:04:36.83874Z","end":"2026-06-15T20:04:38.133411Z","steps":["trace[1260678350] 'agreement among raft nodes before linearized reading' (duration: 1.294510639s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:38.133562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:36.838721Z","time spent":"1.294825796s","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:04:38.13353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:37.567382Z","time spent":"566.0881ms","remote":"127.0.0.1:48216","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-06-15T20:04:38.133606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.180596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:38.133675Z","caller":"traceutil/trace.go:171","msg":"trace[1257183310] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1363; }","duration":"290.320709ms","start":"2026-06-15T20:04:37.843323Z","end":"2026-06-15T20:04:38.133644Z","steps":["trace[1257183310] 'agreement among raft nodes before linearized reading' (duration: 290.190646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:38.133684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165782491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-15T20:04:38.133726Z","caller":"traceutil/trace.go:171","msg":"trace[388405152] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1363; }","duration":"1.165881324s","start":"2026-06-15T20:04:36.967832Z","end":"2026-06-15T20:04:38.133713Z","steps":["trace[388405152] 'agreement among raft nodes before linearized reading' (duration: 1.165741881s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:38.133755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:36.967803Z","time spent":"1.165945045s","remote":"127.0.0.1:48216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-15T20:04:39.38132Z","caller":"traceutil/trace.go:171","msg":"trace[286296440] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"150.597754ms","start":"2026-06-15T20:04:39.230681Z","end":"2026-06-15T20:04:39.381279Z","steps":["trace[286296440] 'process raft request' (duration: 147.910724ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:08:23.864092Z","caller":"traceutil/trace.go:171","msg":"trace[1826926353] transaction","detail":"{read_only:false; response_revision:2438; number_of_response:1; }","duration":"254.22636ms","start":"2026-06-15T20:08:23.609839Z","end":"2026-06-15T20:08:23.864065Z","steps":["trace[1826926353] 'process raft request' (duration: 254.016275ms)"],"step_count":1}