{"level":"warn","ts":"2026-09-19T10:29:51.435304Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T10:29:51.435377Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.205:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.205:2380","--initial-cluster=instance=https://162.253.55.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.205:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-19T10:29:51.43543Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T10:29:51.435438Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.205:2380"]} {"level":"info","ts":"2026-09-19T10:29:51.435452Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T10:29:51.436088Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.205:2379"]} {"level":"info","ts":"2026-09-19T10:29:51.436156Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.205:2380"],"listen-peer-urls":["https://162.253.55.205:2380"],"advertise-client-urls":["https://162.253.55.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.205:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-19T10:29:51.44412Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.777604ms"} {"level":"info","ts":"2026-09-19T10:29:51.456118Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f1f73ead33f822e","cluster-id":"eaba2551c55d0fa5"} {"level":"info","ts":"2026-09-19T10:29:51.456245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T10:29:51.456288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became follower at term 0"} {"level":"info","ts":"2026-09-19T10:29:51.456313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f1f73ead33f822e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T10:29:51.45633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became follower at term 1"} {"level":"info","ts":"2026-09-19T10:29:51.456393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e switched to configuration voters=(6854324610284880430)"} {"level":"warn","ts":"2026-09-19T10:29:51.463644Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T10:29:51.467546Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T10:29:51.472351Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-19T10:29:51.475161Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f1f73ead33f822e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T10:29:51.47592Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f1f73ead33f822e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T10:29:51.476038Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T10:29:51.476063Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T10:29:51.476074Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T10:29:51.476631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e switched to configuration voters=(6854324610284880430)"} {"level":"info","ts":"2026-09-19T10:29:51.479862Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"eaba2551c55d0fa5","local-member-id":"5f1f73ead33f822e","added-peer-id":"5f1f73ead33f822e","added-peer-peer-urls":["https://162.253.55.205:2380"]} {"level":"info","ts":"2026-09-19T10:29:51.481726Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T10:29:51.48192Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f1f73ead33f822e","initial-advertise-peer-urls":["https://162.253.55.205:2380"],"listen-peer-urls":["https://162.253.55.205:2380"],"advertise-client-urls":["https://162.253.55.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T10:29:51.481951Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T10:29:51.482412Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.205:2380"} {"level":"info","ts":"2026-09-19T10:29:51.482419Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.205:2380"} {"level":"info","ts":"2026-09-19T10:29:52.057194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T10:29:52.057279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T10:29:52.057322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e received MsgPreVoteResp from 5f1f73ead33f822e at term 1"} {"level":"info","ts":"2026-09-19T10:29:52.057353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became candidate at term 2"} {"level":"info","ts":"2026-09-19T10:29:52.057371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e received MsgVoteResp from 5f1f73ead33f822e at term 2"} {"level":"info","ts":"2026-09-19T10:29:52.057482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became leader at term 2"} {"level":"info","ts":"2026-09-19T10:29:52.057506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f1f73ead33f822e elected leader 5f1f73ead33f822e at term 2"} {"level":"info","ts":"2026-09-19T10:29:52.058789Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:29:52.060423Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f1f73ead33f822e","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.205:2379]}","request-path":"/0/members/5f1f73ead33f822e/attributes","cluster-id":"eaba2551c55d0fa5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T10:29:52.060432Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T10:29:52.060622Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T10:29:52.060797Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T10:29:52.060877Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T10:29:52.063871Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"eaba2551c55d0fa5","local-member-id":"5f1f73ead33f822e","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:29:52.064028Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:29:52.064069Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:29:52.064512Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T10:29:52.065962Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.205:2379"} {"level":"info","ts":"2026-09-19T10:29:53.893494Z","caller":"traceutil/trace.go:171","msg":"trace[1871679599] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"191.585212ms","start":"2026-09-19T10:29:53.701856Z","end":"2026-09-19T10:29:53.893441Z","steps":["trace[1871679599] 'process raft request' (duration: 191.450779ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:53.893627Z","caller":"traceutil/trace.go:171","msg":"trace[596901442] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"191.661574ms","start":"2026-09-19T10:29:53.701952Z","end":"2026-09-19T10:29:53.893614Z","steps":["trace[596901442] 'process raft request' (duration: 191.395748ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:53.893671Z","caller":"traceutil/trace.go:171","msg":"trace[1309141236] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"182.813807ms","start":"2026-09-19T10:29:53.710796Z","end":"2026-09-19T10:29:53.89361Z","steps":["trace[1309141236] 'process raft request' (duration: 182.662154ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:53.8936Z","caller":"traceutil/trace.go:171","msg":"trace[360719678] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"191.713045ms","start":"2026-09-19T10:29:53.701835Z","end":"2026-09-19T10:29:53.893548Z","steps":["trace[360719678] 'process raft request' (duration: 139.01151ms)","trace[360719678] 'compare' (duration: 52.313466ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:29:53.893663Z","caller":"traceutil/trace.go:171","msg":"trace[564142068] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"191.696924ms","start":"2026-09-19T10:29:53.701926Z","end":"2026-09-19T10:29:53.893623Z","steps":["trace[564142068] 'process raft request' (duration: 191.456199ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:53.893553Z","caller":"traceutil/trace.go:171","msg":"trace[1820167077] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"190.310745ms","start":"2026-09-19T10:29:53.703198Z","end":"2026-09-19T10:29:53.893509Z","steps":["trace[1820167077] 'process raft request' (duration: 190.216033ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:53.893569Z","caller":"traceutil/trace.go:171","msg":"trace[93160045] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:8; }","duration":"163.782863ms","start":"2026-09-19T10:29:53.729726Z","end":"2026-09-19T10:29:53.893509Z","steps":["trace[93160045] 'read index received' (duration: 111.135059ms)","trace[93160045] 'applied index is now lower than readState.Index' (duration: 52.646354ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:29:53.893918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.975309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T10:29:53.894127Z","caller":"traceutil/trace.go:171","msg":"trace[1390339610] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"191.347408ms","start":"2026-09-19T10:29:53.702757Z","end":"2026-09-19T10:29:53.894104Z","steps":["trace[1390339610] 'agreement among raft nodes before linearized reading' (duration: 190.867076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:53.894158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.562174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T10:29:53.894281Z","caller":"traceutil/trace.go:171","msg":"trace[158300924] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"170.777429ms","start":"2026-09-19T10:29:53.723488Z","end":"2026-09-19T10:29:53.894265Z","steps":["trace[158300924] 'agreement among raft nodes before linearized reading' (duration: 170.430362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:53.894724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.712941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T10:29:53.894784Z","caller":"traceutil/trace.go:171","msg":"trace[1295029188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12; }","duration":"163.806593ms","start":"2026-09-19T10:29:53.730962Z","end":"2026-09-19T10:29:53.894768Z","steps":["trace[1295029188] 'agreement among raft nodes before linearized reading' (duration: 163.67354ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:53.894897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.355754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T10:29:53.894868Z","caller":"traceutil/trace.go:171","msg":"trace[1295434219] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"161.179015ms","start":"2026-09-19T10:29:53.733593Z","end":"2026-09-19T10:29:53.894772Z","steps":["trace[1295434219] 'process raft request' (duration: 160.817887ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:53.895038Z","caller":"traceutil/trace.go:171","msg":"trace[1451456543] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"120.477387ms","start":"2026-09-19T10:29:53.77449Z","end":"2026-09-19T10:29:53.894968Z","steps":["trace[1451456543] 'agreement among raft nodes before linearized reading' (duration: 120.345664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:53.895114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.200194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T10:29:53.895159Z","caller":"traceutil/trace.go:171","msg":"trace[1631807714] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"143.345478ms","start":"2026-09-19T10:29:53.751803Z","end":"2026-09-19T10:29:53.895148Z","steps":["trace[1631807714] 'agreement among raft nodes before linearized reading' (duration: 143.277807ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:54.103082Z","caller":"traceutil/trace.go:171","msg":"trace[810594020] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"139.310516ms","start":"2026-09-19T10:29:53.963719Z","end":"2026-09-19T10:29:54.10303Z","steps":["trace[810594020] 'process raft request' (duration: 47.806496ms)","trace[810594020] 'compare' (duration: 91.220524ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:29:54.103164Z","caller":"traceutil/trace.go:171","msg":"trace[731938166] linearizableReadLoop","detail":"{readStateIndex:33; appliedIndex:26; }","duration":"123.095315ms","start":"2026-09-19T10:29:53.980046Z","end":"2026-09-19T10:29:54.103141Z","steps":["trace[731938166] 'read index received' (duration: 31.34235ms)","trace[731938166] 'applied index is now lower than readState.Index' (duration: 91.751585ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:29:54.103342Z","caller":"traceutil/trace.go:171","msg":"trace[1491093797] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"138.521039ms","start":"2026-09-19T10:29:53.964794Z","end":"2026-09-19T10:29:54.103315Z","steps":["trace[1491093797] 'process raft request' (duration: 138.163431ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:54.103405Z","caller":"traceutil/trace.go:171","msg":"trace[2002916716] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"138.13596ms","start":"2026-09-19T10:29:53.965248Z","end":"2026-09-19T10:29:54.103384Z","steps":["trace[2002916716] 'process raft request' (duration: 137.804982ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:54.103401Z","caller":"traceutil/trace.go:171","msg":"trace[888294219] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"136.911963ms","start":"2026-09-19T10:29:53.966477Z","end":"2026-09-19T10:29:54.103389Z","steps":["trace[888294219] 'process raft request' (duration: 136.614266ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:54.103623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.551305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T10:29:54.103673Z","caller":"traceutil/trace.go:171","msg":"trace[1911701947] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:29; }","duration":"123.675098ms","start":"2026-09-19T10:29:53.979985Z","end":"2026-09-19T10:29:54.10366Z","steps":["trace[1911701947] 'agreement among raft nodes before linearized reading' (duration: 123.440712ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:54.103638Z","caller":"traceutil/trace.go:171","msg":"trace[1998662366] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"139.376978ms","start":"2026-09-19T10:29:53.964247Z","end":"2026-09-19T10:29:54.103624Z","steps":["trace[1998662366] 'process raft request' (duration: 138.635992ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:54.103647Z","caller":"traceutil/trace.go:171","msg":"trace[1697503118] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"138.676223ms","start":"2026-09-19T10:29:53.964939Z","end":"2026-09-19T10:29:54.103615Z","steps":["trace[1697503118] 'process raft request' (duration: 138.053659ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:54.103989Z","caller":"traceutil/trace.go:171","msg":"trace[574271094] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"139.273356ms","start":"2026-09-19T10:29:53.964702Z","end":"2026-09-19T10:29:54.103976Z","steps":["trace[574271094] 'process raft request' (duration: 138.220593ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:54.431312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.826074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-19T10:29:54.431513Z","caller":"traceutil/trace.go:171","msg":"trace[1937848628] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"240.534915ms","start":"2026-09-19T10:29:54.190956Z","end":"2026-09-19T10:29:54.431491Z","steps":["trace[1937848628] 'process raft request' (duration: 27.970864ms)","trace[1937848628] 'compare' (duration: 211.674141ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:29:54.460417Z","caller":"traceutil/trace.go:171","msg":"trace[2085635992] linearizableReadLoop","detail":"{readStateIndex:46; appliedIndex:43; }","duration":"266.714548ms","start":"2026-09-19T10:29:54.193688Z","end":"2026-09-19T10:29:54.460402Z","steps":["trace[2085635992] 'read index received' (duration: 25.247473ms)","trace[2085635992] 'applied index is now lower than readState.Index' (duration: 241.465905ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:29:54.460449Z","caller":"traceutil/trace.go:171","msg":"trace[238061430] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"267.112986ms","start":"2026-09-19T10:29:54.193303Z","end":"2026-09-19T10:29:54.460416Z","steps":["trace[238061430] 'process raft request' (duration: 266.930092ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:54.460563Z","caller":"traceutil/trace.go:171","msg":"trace[1232935971] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"266.926913ms","start":"2026-09-19T10:29:54.193607Z","end":"2026-09-19T10:29:54.460534Z","steps":["trace[1232935971] 'process raft request' (duration: 266.735749ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:54.460773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.824284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:429"} {"level":"info","ts":"2026-09-19T10:29:54.460882Z","caller":"traceutil/trace.go:171","msg":"trace[611781460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:42; }","duration":"263.972888ms","start":"2026-09-19T10:29:54.19689Z","end":"2026-09-19T10:29:54.460863Z","steps":["trace[611781460] 'agreement among raft nodes before linearized reading' (duration: 263.705681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:54.461098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.266821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-19T10:29:54.461325Z","caller":"traceutil/trace.go:171","msg":"trace[1346361993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:42; }","duration":"267.575258ms","start":"2026-09-19T10:29:54.193658Z","end":"2026-09-19T10:29:54.461233Z","steps":["trace[1346361993] 'agreement among raft nodes before linearized reading' (duration: 266.981614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:54.690607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.418475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-09-19T10:29:54.691491Z","caller":"traceutil/trace.go:171","msg":"trace[2106963013] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"207.986157ms","start":"2026-09-19T10:29:54.483438Z","end":"2026-09-19T10:29:54.691424Z","steps":["trace[2106963013] 'process raft request' (duration: 72.996727ms)","trace[2106963013] 'compare' (duration: 128.200629ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:29:54.691557Z","caller":"traceutil/trace.go:171","msg":"trace[1698462049] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"171.539355ms","start":"2026-09-19T10:29:54.519975Z","end":"2026-09-19T10:29:54.691515Z","steps":["trace[1698462049] 'process raft request' (duration: 171.371322ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:54.780942Z","caller":"traceutil/trace.go:171","msg":"trace[657691187] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"135.263187ms","start":"2026-09-19T10:29:54.645653Z","end":"2026-09-19T10:29:54.780917Z","steps":["trace[657691187] 'process raft request' (duration: 135.028222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:54.781028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.546909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T10:29:54.781114Z","caller":"traceutil/trace.go:171","msg":"trace[492499891] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:50; }","duration":"181.710912ms","start":"2026-09-19T10:29:54.599382Z","end":"2026-09-19T10:29:54.781092Z","steps":["trace[492499891] 'agreement among raft nodes before linearized reading' (duration: 181.489287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:54.781044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.722374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T10:29:54.781258Z","caller":"traceutil/trace.go:171","msg":"trace[1762665282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:50; }","duration":"151.966499ms","start":"2026-09-19T10:29:54.629232Z","end":"2026-09-19T10:29:54.781198Z","steps":["trace[1762665282] 'agreement among raft nodes before linearized reading' (duration: 151.710503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:55.021347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.457073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-19T10:29:55.021602Z","caller":"traceutil/trace.go:171","msg":"trace[855848605] linearizableReadLoop","detail":"{readStateIndex:57; appliedIndex:54; }","duration":"237.551508ms","start":"2026-09-19T10:29:54.78403Z","end":"2026-09-19T10:29:55.021581Z","steps":["trace[855848605] 'read index received' (duration: 109.792629ms)","trace[855848605] 'applied index is now lower than readState.Index' (duration: 127.757459ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:29:55.021679Z","caller":"traceutil/trace.go:171","msg":"trace[917014217] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"326.526523ms","start":"2026-09-19T10:29:54.695126Z","end":"2026-09-19T10:29:55.021652Z","steps":["trace[917014217] 'process raft request' (duration: 198.672012ms)","trace[917014217] 'compare' (duration: 127.304509ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:29:55.021778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:29:54.695105Z","time spent":"326.623055ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:29:55.021868Z","caller":"traceutil/trace.go:171","msg":"trace[789625118] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"325.152342ms","start":"2026-09-19T10:29:54.696658Z","end":"2026-09-19T10:29:55.021811Z","steps":["trace[789625118] 'process raft request' (duration: 324.850316ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:55.021985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.943657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T10:29:55.022043Z","caller":"traceutil/trace.go:171","msg":"trace[1696971381] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:53; }","duration":"238.07447ms","start":"2026-09-19T10:29:54.783956Z","end":"2026-09-19T10:29:55.022031Z","steps":["trace[1696971381] 'agreement among raft nodes before linearized reading' (duration: 237.976768ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:55.022106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:29:54.696635Z","time spent":"325.323166ms","remote":"127.0.0.1:39810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:29:55.022222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.843306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T10:29:55.022252Z","caller":"traceutil/trace.go:171","msg":"trace[1727201260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:53; }","duration":"220.896657ms","start":"2026-09-19T10:29:54.801347Z","end":"2026-09-19T10:29:55.022243Z","steps":["trace[1727201260] 'agreement among raft nodes before linearized reading' (duration: 220.851056ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:55.022303Z","caller":"traceutil/trace.go:171","msg":"trace[1831839797] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"326.891741ms","start":"2026-09-19T10:29:54.695382Z","end":"2026-09-19T10:29:55.022273Z","steps":["trace[1831839797] 'process raft request' (duration: 326.060012ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:55.022362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:29:54.695372Z","time spent":"326.961563ms","remote":"127.0.0.1:39810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":796,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:29:55.393562Z","caller":"traceutil/trace.go:171","msg":"trace[208409929] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"122.754278ms","start":"2026-09-19T10:29:55.270779Z","end":"2026-09-19T10:29:55.393533Z","steps":["trace[208409929] 'process raft request' (duration: 122.503833ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:55.393763Z","caller":"traceutil/trace.go:171","msg":"trace[1357610149] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"122.592484ms","start":"2026-09-19T10:29:55.27114Z","end":"2026-09-19T10:29:55.393732Z","steps":["trace[1357610149] 'process raft request' (duration: 122.317798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:29:55.621525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.083752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-19T10:29:55.621847Z","caller":"traceutil/trace.go:171","msg":"trace[10972741] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"166.614646ms","start":"2026-09-19T10:29:55.455214Z","end":"2026-09-19T10:29:55.621829Z","steps":["trace[10972741] 'process raft request' (duration: 166.567085ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:55.621843Z","caller":"traceutil/trace.go:171","msg":"trace[962945534] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"222.587935ms","start":"2026-09-19T10:29:55.3992Z","end":"2026-09-19T10:29:55.621788Z","steps":["trace[962945534] 'process raft request' (duration: 222.427351ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:55.621979Z","caller":"traceutil/trace.go:171","msg":"trace[1901781374] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"194.067769ms","start":"2026-09-19T10:29:55.427874Z","end":"2026-09-19T10:29:55.621942Z","steps":["trace[1901781374] 'process raft request' (duration: 193.823534ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:29:55.622081Z","caller":"traceutil/trace.go:171","msg":"trace[419488818] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"223.120456ms","start":"2026-09-19T10:29:55.398905Z","end":"2026-09-19T10:29:55.622026Z","steps":["trace[419488818] 'process raft request' (duration: 57.447021ms)","trace[419488818] 'compare' (duration: 164.916968ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:29:55.870614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.368847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T10:29:55.8707Z","caller":"traceutil/trace.go:171","msg":"trace[395730296] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:85; }","duration":"147.50684ms","start":"2026-09-19T10:29:55.723173Z","end":"2026-09-19T10:29:55.870679Z","steps":["trace[395730296] 'range keys from in-memory index tree' (duration: 147.266195ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:11.452347Z","caller":"traceutil/trace.go:171","msg":"trace[730794082] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"247.988631ms","start":"2026-09-19T10:30:11.204313Z","end":"2026-09-19T10:30:11.452302Z","steps":["trace[730794082] 'process raft request' (duration: 247.784766ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:16.775234Z","caller":"traceutil/trace.go:171","msg":"trace[1287191807] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"193.410063ms","start":"2026-09-19T10:30:16.581791Z","end":"2026-09-19T10:30:16.775201Z","steps":["trace[1287191807] 'process raft request' (duration: 193.042445ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:16.776272Z","caller":"traceutil/trace.go:171","msg":"trace[536197004] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"193.716261ms","start":"2026-09-19T10:30:16.582497Z","end":"2026-09-19T10:30:16.776213Z","steps":["trace[536197004] 'process raft request' (duration: 193.567937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:36.447765Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9380611791213225125,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T10:30:36.630156Z","caller":"traceutil/trace.go:171","msg":"trace[971141338] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"683.374342ms","start":"2026-09-19T10:30:35.946758Z","end":"2026-09-19T10:30:36.630132Z","steps":["trace[971141338] 'read index received' (duration: 683.142266ms)","trace[971141338] 'applied index is now lower than readState.Index' (duration: 231.236µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:30:36.630214Z","caller":"traceutil/trace.go:171","msg":"trace[1106171678] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"726.34652ms","start":"2026-09-19T10:30:35.903843Z","end":"2026-09-19T10:30:36.63019Z","steps":["trace[1106171678] 'process raft request' (duration: 726.133275ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:36.630295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.523456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:30:36.630335Z","caller":"traceutil/trace.go:171","msg":"trace[859466980] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:546; }","duration":"683.577847ms","start":"2026-09-19T10:30:35.946745Z","end":"2026-09-19T10:30:36.630323Z","steps":["trace[859466980] 'agreement among raft nodes before linearized reading' (duration: 683.501375ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:36.630341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:35.90383Z","time spent":"726.430722ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:30:36.63037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:35.946689Z","time spent":"683.673329ms","remote":"127.0.0.1:39474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-09-19T10:30:36.63078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.075457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:30:36.631182Z","caller":"traceutil/trace.go:171","msg":"trace[782677534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"141.24556ms","start":"2026-09-19T10:30:36.489628Z","end":"2026-09-19T10:30:36.630873Z","steps":["trace[782677534] 'agreement among raft nodes before linearized reading' (duration: 140.846301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.114437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.677988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-19T10:30:37.114529Z","caller":"traceutil/trace.go:171","msg":"trace[1871482911] range","detail":"{range_begin:/registry/masterleases/162.253.55.205; range_end:; response_count:1; response_revision:546; }","duration":"310.841133ms","start":"2026-09-19T10:30:36.803666Z","end":"2026-09-19T10:30:37.114507Z","steps":["trace[1871482911] 'range keys from in-memory index tree' (duration: 310.537106ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.114577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:36.803646Z","time spent":"310.919905ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.205\" "} {"level":"info","ts":"2026-09-19T10:30:37.136581Z","caller":"traceutil/trace.go:171","msg":"trace[1619983489] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"256.356118ms","start":"2026-09-19T10:30:36.8802Z","end":"2026-09-19T10:30:37.136556Z","steps":["trace[1619983489] 'process raft request' (duration: 256.201075ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.207604Z","caller":"traceutil/trace.go:171","msg":"trace[511845308] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"320.353815ms","start":"2026-09-19T10:30:36.887209Z","end":"2026-09-19T10:30:37.207563Z","steps":["trace[511845308] 'process raft request' (duration: 320.208511ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.207708Z","caller":"traceutil/trace.go:171","msg":"trace[1484035161] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"308.918719ms","start":"2026-09-19T10:30:36.898725Z","end":"2026-09-19T10:30:37.207644Z","steps":["trace[1484035161] 'process raft request' (duration: 308.742446ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.207738Z","caller":"traceutil/trace.go:171","msg":"trace[849751783] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"299.08316ms","start":"2026-09-19T10:30:36.908604Z","end":"2026-09-19T10:30:37.207687Z","steps":["trace[849751783] 'process raft request' (duration: 298.900216ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.20793Z","caller":"traceutil/trace.go:171","msg":"trace[1911520017] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"299.07843ms","start":"2026-09-19T10:30:36.908807Z","end":"2026-09-19T10:30:37.207886Z","steps":["trace[1911520017] 'process raft request' (duration: 298.831654ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.208007Z","caller":"traceutil/trace.go:171","msg":"trace[360509422] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"283.800149ms","start":"2026-09-19T10:30:36.924195Z","end":"2026-09-19T10:30:37.207995Z","steps":["trace[360509422] 'process raft request' (duration: 283.531513ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.208155Z","caller":"traceutil/trace.go:171","msg":"trace[984041136] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"299.377626ms","start":"2026-09-19T10:30:36.908759Z","end":"2026-09-19T10:30:37.208137Z","steps":["trace[984041136] 'process raft request' (duration: 298.836384ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.208202Z","caller":"traceutil/trace.go:171","msg":"trace[1953648884] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"270.898431ms","start":"2026-09-19T10:30:36.937285Z","end":"2026-09-19T10:30:37.208184Z","steps":["trace[1953648884] 'process raft request' (duration: 270.531063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.207946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:36.8987Z","time spent":"309.145815ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:30:37.208252Z","caller":"traceutil/trace.go:171","msg":"trace[2052183574] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"296.8588ms","start":"2026-09-19T10:30:36.911343Z","end":"2026-09-19T10:30:37.208202Z","steps":["trace[2052183574] 'process raft request' (duration: 296.338159ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.208313Z","caller":"traceutil/trace.go:171","msg":"trace[21947185] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:567; }","duration":"264.652182ms","start":"2026-09-19T10:30:36.943644Z","end":"2026-09-19T10:30:37.208296Z","steps":["trace[21947185] 'read index received' (duration: 192.867712ms)","trace[21947185] 'applied index is now lower than readState.Index' (duration: 71.78357ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:30:37.208363Z","caller":"traceutil/trace.go:171","msg":"trace[940036451] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"158.490266ms","start":"2026-09-19T10:30:37.049859Z","end":"2026-09-19T10:30:37.208349Z","steps":["trace[940036451] 'process raft request' (duration: 158.115886ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.208433Z","caller":"traceutil/trace.go:171","msg":"trace[409367101] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"247.006319ms","start":"2026-09-19T10:30:36.961415Z","end":"2026-09-19T10:30:37.208421Z","steps":["trace[409367101] 'process raft request' (duration: 246.458026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.208609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.94054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-19T10:30:37.208653Z","caller":"traceutil/trace.go:171","msg":"trace[152127338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"265.020941ms","start":"2026-09-19T10:30:36.943621Z","end":"2026-09-19T10:30:37.208642Z","steps":["trace[152127338] 'agreement among raft nodes before linearized reading' (duration: 264.837157ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.208718Z","caller":"traceutil/trace.go:171","msg":"trace[1808321978] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"323.308121ms","start":"2026-09-19T10:30:36.88532Z","end":"2026-09-19T10:30:37.208628Z","steps":["trace[1808321978] 'process raft request' (duration: 321.92876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.208796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:36.885285Z","time spent":"323.476305ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T10:30:37.209059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:36.887192Z","time spent":"320.522489ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:30:37.30659Z","caller":"traceutil/trace.go:171","msg":"trace[973583173] transaction","detail":"{read_only:false; number_of_response:1; response_revision:560; }","duration":"159.794014ms","start":"2026-09-19T10:30:37.14677Z","end":"2026-09-19T10:30:37.306564Z","steps":["trace[973583173] 'process raft request' (duration: 159.731943ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.307021Z","caller":"traceutil/trace.go:171","msg":"trace[441882758] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"162.893303ms","start":"2026-09-19T10:30:37.144115Z","end":"2026-09-19T10:30:37.307008Z","steps":["trace[441882758] 'process raft request' (duration: 162.054024ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.30742Z","caller":"traceutil/trace.go:171","msg":"trace[1777886649] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"161.722376ms","start":"2026-09-19T10:30:37.145681Z","end":"2026-09-19T10:30:37.307404Z","steps":["trace[1777886649] 'process raft request' (duration: 160.734275ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.308113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.003447ms","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-09-19T10:30:37.308169Z","caller":"traceutil/trace.go:171","msg":"trace[123238333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"346.086778ms","start":"2026-09-19T10:30:36.962069Z","end":"2026-09-19T10:30:37.308156Z","steps":["trace[123238333] 'agreement among raft nodes before linearized reading' (duration: 345.858154ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.308217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:36.962063Z","time spent":"346.14195ms","remote":"127.0.0.1:39624","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-09-19T10:30:37.696522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.202273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:30:37.696809Z","caller":"traceutil/trace.go:171","msg":"trace[2005157562] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"468.716223ms","start":"2026-09-19T10:30:37.228073Z","end":"2026-09-19T10:30:37.696789Z","steps":["trace[2005157562] 'process raft request' (duration: 468.60926ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.696818Z","caller":"traceutil/trace.go:171","msg":"trace[1003366056] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"474.332868ms","start":"2026-09-19T10:30:37.222454Z","end":"2026-09-19T10:30:37.696787Z","steps":["trace[1003366056] 'process raft request' (duration: 474.175695ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.696859Z","caller":"traceutil/trace.go:171","msg":"trace[2033707822] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"487.286268ms","start":"2026-09-19T10:30:37.209486Z","end":"2026-09-19T10:30:37.696773Z","steps":["trace[2033707822] 'process raft request' (duration: 296.740909ms)","trace[2033707822] 'compare' (duration: 190.058999ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:30:37.69691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.228051Z","time spent":"468.829755ms","remote":"127.0.0.1:39894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T10:30:37.697007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.222426Z","time spent":"474.472611ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:30:37.697101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.209458Z","time spent":"487.490513ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:30:37.69742Z","caller":"traceutil/trace.go:171","msg":"trace[2114328585] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"469.110083ms","start":"2026-09-19T10:30:37.22829Z","end":"2026-09-19T10:30:37.6974Z","steps":["trace[2114328585] 'process raft request' (duration: 468.429937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.6975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.228279Z","time spent":"469.186754ms","remote":"127.0.0.1:39894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:30:37.697544Z","caller":"traceutil/trace.go:171","msg":"trace[1693744119] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"469.205724ms","start":"2026-09-19T10:30:37.228323Z","end":"2026-09-19T10:30:37.697529Z","steps":["trace[1693744119] 'process raft request' (duration: 468.973569ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.697712Z","caller":"traceutil/trace.go:171","msg":"trace[557012753] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"461.402751ms","start":"2026-09-19T10:30:37.236295Z","end":"2026-09-19T10:30:37.697697Z","steps":["trace[557012753] 'process raft request' (duration: 461.122515ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.697794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.236274Z","time spent":"461.479402ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:30:37.697866Z","caller":"traceutil/trace.go:171","msg":"trace[1374548918] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"450.865316ms","start":"2026-09-19T10:30:37.246976Z","end":"2026-09-19T10:30:37.697841Z","steps":["trace[1374548918] 'process raft request' (duration: 450.807935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.698058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.246958Z","time spent":"450.981218ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:30:37.69812Z","caller":"traceutil/trace.go:171","msg":"trace[526299661] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"459.848825ms","start":"2026-09-19T10:30:37.238227Z","end":"2026-09-19T10:30:37.698076Z","steps":["trace[526299661] 'process raft request' (duration: 459.251212ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.698201Z","caller":"traceutil/trace.go:171","msg":"trace[657643665] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"453.72317ms","start":"2026-09-19T10:30:37.244469Z","end":"2026-09-19T10:30:37.698192Z","steps":["trace[657643665] 'process raft request' (duration: 453.25781ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.698236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.238214Z","time spent":"459.983419ms","remote":"127.0.0.1:39894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T10:30:37.69826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.244427Z","time spent":"453.800262ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:30:37.698387Z","caller":"traceutil/trace.go:171","msg":"trace[1478485787] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"454.057937ms","start":"2026-09-19T10:30:37.244284Z","end":"2026-09-19T10:30:37.698342Z","steps":["trace[1478485787] 'process raft request' (duration: 453.323961ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.698913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.244262Z","time spent":"454.515307ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:30:37.703213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.228287Z","time spent":"469.288376ms","remote":"127.0.0.1:39894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:30:37.736796Z","caller":"traceutil/trace.go:171","msg":"trace[764609750] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"420.603591ms","start":"2026-09-19T10:30:37.316169Z","end":"2026-09-19T10:30:37.736773Z","steps":["trace[764609750] 'process raft request' (duration: 420.017038ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.73687Z","caller":"traceutil/trace.go:171","msg":"trace[462214694] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"417.043082ms","start":"2026-09-19T10:30:37.319819Z","end":"2026-09-19T10:30:37.736862Z","steps":["trace[462214694] 'process raft request' (duration: 416.424968ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.736976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.319805Z","time spent":"417.095632ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:30:37.737142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.316141Z","time spent":"420.734943ms","remote":"127.0.0.1:39624","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-09-19T10:30:37.736787Z","caller":"traceutil/trace.go:171","msg":"trace[2080365104] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"428.057007ms","start":"2026-09-19T10:30:37.308671Z","end":"2026-09-19T10:30:37.736728Z","steps":["trace[2080365104] 'process raft request' (duration: 427.413932ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.737354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.308644Z","time spent":"428.66692ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:30:37.737321Z","caller":"traceutil/trace.go:171","msg":"trace[1166194868] transaction","detail":"{read_only:false; number_of_response:1; response_revision:573; }","duration":"412.52235ms","start":"2026-09-19T10:30:37.324758Z","end":"2026-09-19T10:30:37.737281Z","steps":["trace[1166194868] 'process raft request' (duration: 411.531358ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:37.736818Z","caller":"traceutil/trace.go:171","msg":"trace[145768111] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"394.149261ms","start":"2026-09-19T10:30:37.34265Z","end":"2026-09-19T10:30:37.736799Z","steps":["trace[145768111] 'process raft request' (duration: 394.040259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:37.737564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.324726Z","time spent":"412.728994ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:30:37.737661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.342624Z","time spent":"394.894467ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:30:38.234913Z","caller":"traceutil/trace.go:171","msg":"trace[2069373688] transaction","detail":"{read_only:false; number_of_response:0; response_revision:574; }","duration":"530.914411ms","start":"2026-09-19T10:30:37.703972Z","end":"2026-09-19T10:30:38.234886Z","steps":["trace[2069373688] 'process raft request' (duration: 478.258547ms)","trace[2069373688] 'compare' (duration: 52.46931ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:30:38.23505Z","caller":"traceutil/trace.go:171","msg":"trace[1643937011] transaction","detail":"{read_only:false; number_of_response:0; response_revision:574; }","duration":"530.968512ms","start":"2026-09-19T10:30:37.703994Z","end":"2026-09-19T10:30:38.234963Z","steps":["trace[1643937011] 'process raft request' (duration: 530.769748ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:38.23507Z","caller":"traceutil/trace.go:171","msg":"trace[1899668829] transaction","detail":"{read_only:false; number_of_response:1; response_revision:574; }","duration":"521.92069ms","start":"2026-09-19T10:30:37.713097Z","end":"2026-09-19T10:30:38.235018Z","steps":["trace[1899668829] 'process raft request' (duration: 521.700535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:38.235234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.703958Z","time spent":"531.192787ms","remote":"127.0.0.1:39894","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T10:30:38.235241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.713065Z","time spent":"522.081734ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7375,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:30:38.235251Z","caller":"traceutil/trace.go:171","msg":"trace[1373851677] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"505.434473ms","start":"2026-09-19T10:30:37.729804Z","end":"2026-09-19T10:30:38.235239Z","steps":["trace[1373851677] 'process raft request' (duration: 505.105406ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:38.235075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.70395Z","time spent":"531.038454ms","remote":"127.0.0.1:39894","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:30:38.23552Z","caller":"traceutil/trace.go:171","msg":"trace[1122174337] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:598; }","duration":"497.624538ms","start":"2026-09-19T10:30:37.73788Z","end":"2026-09-19T10:30:38.235504Z","steps":["trace[1122174337] 'read index received' (duration: 444.428051ms)","trace[1122174337] 'applied index is now lower than readState.Index' (duration: 53.195407ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:30:38.235531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.72977Z","time spent":"505.531994ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:30:38.235595Z","caller":"traceutil/trace.go:171","msg":"trace[1069982453] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"502.027567ms","start":"2026-09-19T10:30:37.73356Z","end":"2026-09-19T10:30:38.235587Z","steps":["trace[1069982453] 'process raft request' (duration: 501.488675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:38.235708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.830102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-19T10:30:38.23579Z","caller":"traceutil/trace.go:171","msg":"trace[1369877302] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:576; }","duration":"531.942964ms","start":"2026-09-19T10:30:37.703835Z","end":"2026-09-19T10:30:38.235778Z","steps":["trace[1369877302] 'agreement among raft nodes before linearized reading' (duration: 531.77102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:38.23583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.703817Z","time spent":"532.006256ms","remote":"127.0.0.1:39514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-09-19T10:30:38.23592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.733536Z","time spent":"502.166059ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:30:38.265856Z","caller":"traceutil/trace.go:171","msg":"trace[335047541] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"522.190586ms","start":"2026-09-19T10:30:37.743636Z","end":"2026-09-19T10:30:38.265826Z","steps":["trace[335047541] 'process raft request' (duration: 521.959231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:38.265999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.743609Z","time spent":"522.31332ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:30:38.266047Z","caller":"traceutil/trace.go:171","msg":"trace[270873523] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"518.889123ms","start":"2026-09-19T10:30:37.74711Z","end":"2026-09-19T10:30:38.266Z","steps":["trace[270873523] 'process raft request' (duration: 518.623057ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:38.266204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.747094Z","time spent":"519.034757ms","remote":"127.0.0.1:39894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:30:38.266363Z","caller":"traceutil/trace.go:171","msg":"trace[1364013725] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"508.571532ms","start":"2026-09-19T10:30:37.757762Z","end":"2026-09-19T10:30:38.266334Z","steps":["trace[1364013725] 'process raft request' (duration: 508.137173ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:38.266448Z","caller":"traceutil/trace.go:171","msg":"trace[94127612] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"481.215663ms","start":"2026-09-19T10:30:37.785215Z","end":"2026-09-19T10:30:38.266431Z","steps":["trace[94127612] 'process raft request' (duration: 480.889145ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:38.26648Z","caller":"traceutil/trace.go:171","msg":"trace[979261072] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"439.251776ms","start":"2026-09-19T10:30:37.827206Z","end":"2026-09-19T10:30:38.266458Z","steps":["trace[979261072] 'process raft request' (duration: 439.144854ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:38.266533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.827175Z","time spent":"439.331358ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T10:30:38.266552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.757736Z","time spent":"508.710536ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:30:38.266611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.276137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:30:38.26665Z","caller":"traceutil/trace.go:171","msg":"trace[613484795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"157.345679ms","start":"2026-09-19T10:30:38.109294Z","end":"2026-09-19T10:30:38.26664Z","steps":["trace[613484795] 'agreement among raft nodes before linearized reading' (duration: 157.272267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:38.266544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:37.785182Z","time spent":"481.321444ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:30:38.542948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.372374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:30:38.543292Z","caller":"traceutil/trace.go:171","msg":"trace[860745986] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:609; }","duration":"219.098487ms","start":"2026-09-19T10:30:38.32418Z","end":"2026-09-19T10:30:38.543278Z","steps":["trace[860745986] 'read index received' (duration: 103.392426ms)","trace[860745986] 'applied index is now lower than readState.Index' (duration: 115.705311ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:30:38.543357Z","caller":"traceutil/trace.go:171","msg":"trace[1074169035] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"177.033688ms","start":"2026-09-19T10:30:38.366315Z","end":"2026-09-19T10:30:38.543349Z","steps":["trace[1074169035] 'process raft request' (duration: 176.919665ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:38.543285Z","caller":"traceutil/trace.go:171","msg":"trace[793132280] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"255.052889ms","start":"2026-09-19T10:30:38.288206Z","end":"2026-09-19T10:30:38.543259Z","steps":["trace[793132280] 'process raft request' (duration: 254.890375ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:38.543496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.312122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-19T10:30:38.543254Z","caller":"traceutil/trace.go:171","msg":"trace[1489832228] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"264.631032ms","start":"2026-09-19T10:30:38.27856Z","end":"2026-09-19T10:30:38.543191Z","steps":["trace[1489832228] 'process raft request' (duration: 148.925631ms)","trace[1489832228] 'compare' (duration: 115.244661ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:30:38.543547Z","caller":"traceutil/trace.go:171","msg":"trace[571476380] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:585; }","duration":"219.402094ms","start":"2026-09-19T10:30:38.324135Z","end":"2026-09-19T10:30:38.543537Z","steps":["trace[571476380] 'agreement among raft nodes before linearized reading' (duration: 219.24533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:38.917864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.413661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:30:38.918014Z","caller":"traceutil/trace.go:171","msg":"trace[1553420374] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"278.406909ms","start":"2026-09-19T10:30:38.63958Z","end":"2026-09-19T10:30:38.917986Z","steps":["trace[1553420374] 'process raft request' (duration: 59.737742ms)","trace[1553420374] 'compare' (duration: 218.224147ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:30:38.953421Z","caller":"traceutil/trace.go:171","msg":"trace[1842226368] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"284.127177ms","start":"2026-09-19T10:30:38.669281Z","end":"2026-09-19T10:30:38.953408Z","steps":["trace[1842226368] 'process raft request' (duration: 284.063096ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:38.953852Z","caller":"traceutil/trace.go:171","msg":"trace[69054443] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"309.983583ms","start":"2026-09-19T10:30:38.643857Z","end":"2026-09-19T10:30:38.95384Z","steps":["trace[69054443] 'process raft request' (duration: 309.273068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:38.953936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:38.643821Z","time spent":"310.066524ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:30:38.954128Z","caller":"traceutil/trace.go:171","msg":"trace[1666613716] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"308.137342ms","start":"2026-09-19T10:30:38.64598Z","end":"2026-09-19T10:30:38.954117Z","steps":["trace[1666613716] 'process raft request' (duration: 307.265214ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:38.954193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:38.645962Z","time spent":"308.196393ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:30:39.737145Z","caller":"traceutil/trace.go:171","msg":"trace[1041982159] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"162.023544ms","start":"2026-09-19T10:30:39.575062Z","end":"2026-09-19T10:30:39.737086Z","steps":["trace[1041982159] 'process raft request' (duration: 161.311657ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:39.742752Z","caller":"traceutil/trace.go:171","msg":"trace[2015002488] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"147.357758ms","start":"2026-09-19T10:30:39.59537Z","end":"2026-09-19T10:30:39.742728Z","steps":["trace[2015002488] 'process raft request' (duration: 146.624761ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:42.745053Z","caller":"traceutil/trace.go:171","msg":"trace[926362044] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"124.857714ms","start":"2026-09-19T10:30:42.620152Z","end":"2026-09-19T10:30:42.74501Z","steps":["trace[926362044] 'process raft request' (duration: 123.240058ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:43.576697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.078311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:30:43.576787Z","caller":"traceutil/trace.go:171","msg":"trace[1164026586] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:645; }","duration":"169.180873ms","start":"2026-09-19T10:30:43.407585Z","end":"2026-09-19T10:30:43.576766Z","steps":["trace[1164026586] 'range keys from in-memory index tree' (duration: 168.970099ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:44.132915Z","caller":"traceutil/trace.go:171","msg":"trace[863649335] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"158.449724ms","start":"2026-09-19T10:30:43.974426Z","end":"2026-09-19T10:30:44.132876Z","steps":["trace[863649335] 'process raft request' (duration: 157.859351ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:44.553684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.834808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:30:44.553756Z","caller":"traceutil/trace.go:171","msg":"trace[1954191191] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"231.278378ms","start":"2026-09-19T10:30:44.322467Z","end":"2026-09-19T10:30:44.553745Z","steps":["trace[1954191191] 'process raft request' (duration: 39.324777ms)","trace[1954191191] 'compare' (duration: 191.743616ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:30:44.91498Z","caller":"traceutil/trace.go:171","msg":"trace[1034222203] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"218.153036ms","start":"2026-09-19T10:30:44.696772Z","end":"2026-09-19T10:30:44.914925Z","steps":["trace[1034222203] 'process raft request' (duration: 215.762372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:45.921452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.063405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:30:45.921643Z","caller":"traceutil/trace.go:171","msg":"trace[1784266204] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:679; }","duration":"433.148431ms","start":"2026-09-19T10:30:45.488478Z","end":"2026-09-19T10:30:45.921627Z","steps":["trace[1784266204] 'read index received' (duration: 287.876381ms)","trace[1784266204] 'applied index is now lower than readState.Index' (duration: 145.27097ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:30:45.921829Z","caller":"traceutil/trace.go:171","msg":"trace[782739332] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"964.880929ms","start":"2026-09-19T10:30:44.956937Z","end":"2026-09-19T10:30:45.921817Z","steps":["trace[782739332] 'process raft request' (duration: 819.370234ms)","trace[782739332] 'compare' (duration: 144.949312ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:30:45.921885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:44.956851Z","time spent":"965.001902ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:30:45.921999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.508879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:30:45.922101Z","caller":"traceutil/trace.go:171","msg":"trace[518368041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"433.653543ms","start":"2026-09-19T10:30:45.488425Z","end":"2026-09-19T10:30:45.922078Z","steps":["trace[518368041] 'agreement among raft nodes before linearized reading' (duration: 433.481839ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:45.922139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:45.488405Z","time spent":"433.725794ms","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T10:30:45.922139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.857564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-19T10:30:45.922174Z","caller":"traceutil/trace.go:171","msg":"trace[1024764346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"128.934265ms","start":"2026-09-19T10:30:45.793228Z","end":"2026-09-19T10:30:45.922162Z","steps":["trace[1024764346] 'agreement among raft nodes before linearized reading' (duration: 128.828773ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:45.922321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.343212ms","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-09-19T10:30:45.922345Z","caller":"traceutil/trace.go:171","msg":"trace[1885103230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"128.384483ms","start":"2026-09-19T10:30:45.793953Z","end":"2026-09-19T10:30:45.922338Z","steps":["trace[1885103230] 'agreement among raft nodes before linearized reading' (duration: 128.317792ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:45.922392Z","caller":"traceutil/trace.go:171","msg":"trace[1379509916] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"918.436394ms","start":"2026-09-19T10:30:45.003941Z","end":"2026-09-19T10:30:45.922377Z","steps":["trace[1379509916] 'process raft request' (duration: 917.594916ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:45.922476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:45.003914Z","time spent":"918.511575ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:30:45.922504Z","caller":"traceutil/trace.go:171","msg":"trace[252019839] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"909.347631ms","start":"2026-09-19T10:30:45.013147Z","end":"2026-09-19T10:30:45.922494Z","steps":["trace[252019839] 'process raft request' (duration: 908.444701ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:45.922569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:45.01312Z","time spent":"909.415503ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:30:46.661734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.309696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T10:30:46.661885Z","caller":"traceutil/trace.go:171","msg":"trace[1872344695] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"104.506971ms","start":"2026-09-19T10:30:46.557355Z","end":"2026-09-19T10:30:46.661862Z","steps":["trace[1872344695] 'range keys from in-memory index tree' (duration: 104.085311ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:46.776337Z","caller":"traceutil/trace.go:171","msg":"trace[165518489] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"111.108027ms","start":"2026-09-19T10:30:46.665205Z","end":"2026-09-19T10:30:46.776313Z","steps":["trace[165518489] 'process raft request' (duration: 110.960174ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:47.229842Z","caller":"traceutil/trace.go:171","msg":"trace[1372194179] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"382.43927ms","start":"2026-09-19T10:30:46.847368Z","end":"2026-09-19T10:30:47.229807Z","steps":["trace[1372194179] 'process raft request' (duration: 309.074304ms)","trace[1372194179] 'compare' (duration: 73.09617ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:30:47.230024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:46.847351Z","time spent":"382.565572ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:30:47.390648Z","caller":"traceutil/trace.go:171","msg":"trace[1590094936] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:691; }","duration":"431.284588ms","start":"2026-09-19T10:30:46.959334Z","end":"2026-09-19T10:30:47.390618Z","steps":["trace[1590094936] 'read index received' (duration: 197.194088ms)","trace[1590094936] 'applied index is now lower than readState.Index' (duration: 234.08954ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:30:47.39093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.592596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hvdds\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-19T10:30:47.39099Z","caller":"traceutil/trace.go:171","msg":"trace[1831539822] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hvdds; range_end:; response_count:1; response_revision:664; }","duration":"431.659787ms","start":"2026-09-19T10:30:46.959319Z","end":"2026-09-19T10:30:47.390978Z","steps":["trace[1831539822] 'agreement among raft nodes before linearized reading' (duration: 431.418912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:47.391024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:46.959274Z","time spent":"431.742819ms","remote":"127.0.0.1:39532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-hvdds\" "} {"level":"warn","ts":"2026-09-19T10:30:47.391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.675559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-19T10:30:47.391109Z","caller":"traceutil/trace.go:171","msg":"trace[1325414239] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:664; }","duration":"158.840522ms","start":"2026-09-19T10:30:47.232244Z","end":"2026-09-19T10:30:47.391085Z","steps":["trace[1325414239] 'agreement among raft nodes before linearized reading' (duration: 158.639058ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:47.390714Z","caller":"traceutil/trace.go:171","msg":"trace[2072762671] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"512.348997ms","start":"2026-09-19T10:30:46.878338Z","end":"2026-09-19T10:30:47.390687Z","steps":["trace[2072762671] 'process raft request' (duration: 473.214534ms)","trace[2072762671] 'compare' (duration: 38.660853ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:30:47.391345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:46.878307Z","time spent":"512.992141ms","remote":"127.0.0.1:39474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:30:47.989716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9380611791213225339,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-19T10:30:48.848399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.036995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:30:48.848592Z","caller":"traceutil/trace.go:171","msg":"trace[1206426373] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"1.35945162s","start":"2026-09-19T10:30:47.489124Z","end":"2026-09-19T10:30:48.848576Z","steps":["trace[1206426373] 'read index received' (duration: 771.155679ms)","trace[1206426373] 'applied index is now lower than readState.Index' (duration: 588.294921ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:30:48.848667Z","caller":"traceutil/trace.go:171","msg":"trace[333674463] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"1.384424567s","start":"2026-09-19T10:30:47.464234Z","end":"2026-09-19T10:30:48.848659Z","steps":["trace[333674463] 'process raft request' (duration: 1.384264803s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:48.848713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:47.464208Z","time spent":"1.384480138s","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:30:48.848798Z","caller":"traceutil/trace.go:171","msg":"trace[1905393060] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.433740187s","start":"2026-09-19T10:30:47.415024Z","end":"2026-09-19T10:30:48.848764Z","steps":["trace[1905393060] 'process raft request' (duration: 845.245962ms)","trace[1905393060] 'compare' (duration: 587.581825ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:30:48.84897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:47.414992Z","time spent":"1.433876049s","remote":"127.0.0.1:39532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:30:48.8494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.730978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-19T10:30:48.849469Z","caller":"traceutil/trace.go:171","msg":"trace[355188789] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:666; }","duration":"921.83146ms","start":"2026-09-19T10:30:47.92762Z","end":"2026-09-19T10:30:48.849452Z","steps":["trace[355188789] 'agreement among raft nodes before linearized reading' (duration: 921.613105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:48.849515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:47.927607Z","time spent":"921.895282ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T10:30:48.849723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360589865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:30:48.849783Z","caller":"traceutil/trace.go:171","msg":"trace[1134392401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"1.360648707s","start":"2026-09-19T10:30:47.489118Z","end":"2026-09-19T10:30:48.849767Z","steps":["trace[1134392401] 'agreement among raft nodes before linearized reading' (duration: 1.360566095s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:48.849823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:47.48907Z","time spent":"1.360742959s","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T10:30:48.849894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.407985ms","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-09-19T10:30:48.849954Z","caller":"traceutil/trace.go:171","msg":"trace[359131662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:666; }","duration":"910.492066ms","start":"2026-09-19T10:30:47.939451Z","end":"2026-09-19T10:30:48.849943Z","steps":["trace[359131662] 'agreement among raft nodes before linearized reading' (duration: 910.389334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:48.849915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.392745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-19T10:30:48.849987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:47.93944Z","time spent":"910.539517ms","remote":"127.0.0.1:39624","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":"info","ts":"2026-09-19T10:30:48.85003Z","caller":"traceutil/trace.go:171","msg":"trace[1939425316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:666; }","duration":"910.568339ms","start":"2026-09-19T10:30:47.939431Z","end":"2026-09-19T10:30:48.849999Z","steps":["trace[1939425316] 'agreement among raft nodes before linearized reading' (duration: 910.249302ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:48.85012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:30:47.939409Z","time spent":"910.694701ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-19T10:30:50.400984Z","caller":"traceutil/trace.go:171","msg":"trace[874540339] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"227.978985ms","start":"2026-09-19T10:30:50.172979Z","end":"2026-09-19T10:30:50.400958Z","steps":["trace[874540339] 'process raft request' (duration: 224.340443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:03.231137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.038688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:31:03.231351Z","caller":"traceutil/trace.go:171","msg":"trace[982058355] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:849; }","duration":"221.961281ms","start":"2026-09-19T10:31:03.009373Z","end":"2026-09-19T10:31:03.231335Z","steps":["trace[982058355] 'read index received' (duration: 5.618345ms)","trace[982058355] 'applied index is now lower than readState.Index' (duration: 216.342056ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:31:03.231535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.021103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:31:03.231576Z","caller":"traceutil/trace.go:171","msg":"trace[2009217724] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:818; }","duration":"222.083474ms","start":"2026-09-19T10:31:03.009481Z","end":"2026-09-19T10:31:03.231565Z","steps":["trace[2009217724] 'agreement among raft nodes before linearized reading' (duration: 221.988012ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:03.231658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.607075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T10:31:03.231832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.928052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:31:03.231868Z","caller":"traceutil/trace.go:171","msg":"trace[1689345190] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:818; }","duration":"170.994924ms","start":"2026-09-19T10:31:03.060865Z","end":"2026-09-19T10:31:03.23186Z","steps":["trace[1689345190] 'agreement among raft nodes before linearized reading' (duration: 170.937443ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:03.231838Z","caller":"traceutil/trace.go:171","msg":"trace[987209413] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:818; }","duration":"170.914112ms","start":"2026-09-19T10:31:03.060889Z","end":"2026-09-19T10:31:03.231803Z","steps":["trace[987209413] 'agreement among raft nodes before linearized reading' (duration: 170.601395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:03.232014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.994615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:31:03.232068Z","caller":"traceutil/trace.go:171","msg":"trace[1701013166] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:818; }","duration":"189.045916ms","start":"2026-09-19T10:31:03.042993Z","end":"2026-09-19T10:31:03.232039Z","steps":["trace[1701013166] 'agreement among raft nodes before linearized reading' (duration: 188.994415ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:03.23224Z","caller":"traceutil/trace.go:171","msg":"trace[1932342763] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"243.254386ms","start":"2026-09-19T10:31:02.98817Z","end":"2026-09-19T10:31:03.231424Z","steps":["trace[1932342763] 'process raft request' (duration: 243.074071ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:03.232272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.908249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:31:03.232463Z","caller":"traceutil/trace.go:171","msg":"trace[81184636] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:818; }","duration":"222.045271ms","start":"2026-09-19T10:31:03.010294Z","end":"2026-09-19T10:31:03.23234Z","steps":["trace[81184636] 'agreement among raft nodes before linearized reading' (duration: 221.915069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:03.232527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.012437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:31:03.232566Z","caller":"traceutil/trace.go:171","msg":"trace[878271683] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:818; }","duration":"190.083819ms","start":"2026-09-19T10:31:03.042473Z","end":"2026-09-19T10:31:03.232557Z","steps":["trace[878271683] 'agreement among raft nodes before linearized reading' (duration: 190.028028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:03.232709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.233993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:31:03.23275Z","caller":"traceutil/trace.go:171","msg":"trace[410010157] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:818; }","duration":"190.302654ms","start":"2026-09-19T10:31:03.042435Z","end":"2026-09-19T10:31:03.232738Z","steps":["trace[410010157] 'agreement among raft nodes before linearized reading' (duration: 190.239833ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:03.232835Z","caller":"traceutil/trace.go:171","msg":"trace[822871213] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"244.625517ms","start":"2026-09-19T10:31:02.988199Z","end":"2026-09-19T10:31:03.232825Z","steps":["trace[822871213] 'process raft request' (duration: 243.101693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:03.232877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.332592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:31:03.233002Z","caller":"traceutil/trace.go:171","msg":"trace[147182914] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:818; }","duration":"171.593508ms","start":"2026-09-19T10:31:03.061379Z","end":"2026-09-19T10:31:03.232972Z","steps":["trace[147182914] 'agreement among raft nodes before linearized reading' (duration: 171.343523ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:03.231363Z","caller":"traceutil/trace.go:171","msg":"trace[1897385034] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"244.636967ms","start":"2026-09-19T10:31:02.986691Z","end":"2026-09-19T10:31:03.231328Z","steps":["trace[1897385034] 'process raft request' (duration: 28.301971ms)","trace[1897385034] 'compare' (duration: 215.928436ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:31:03.233253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.861273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:31:03.233298Z","caller":"traceutil/trace.go:171","msg":"trace[1420310824] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:818; }","duration":"223.953065ms","start":"2026-09-19T10:31:03.009329Z","end":"2026-09-19T10:31:03.233282Z","steps":["trace[1420310824] 'agreement among raft nodes before linearized reading' (duration: 222.110964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:09.580079Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9380611791213225744,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T10:31:09.747718Z","caller":"traceutil/trace.go:171","msg":"trace[1589010359] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:874; }","duration":"668.662743ms","start":"2026-09-19T10:31:09.079031Z","end":"2026-09-19T10:31:09.747694Z","steps":["trace[1589010359] 'read index received' (duration: 668.495669ms)","trace[1589010359] 'applied index is now lower than readState.Index' (duration: 166.064µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:09.747795Z","caller":"traceutil/trace.go:171","msg":"trace[1724519260] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"693.451337ms","start":"2026-09-19T10:31:09.054316Z","end":"2026-09-19T10:31:09.747768Z","steps":["trace[1724519260] 'process raft request' (duration: 693.213992ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:09.747937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.61931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T10:31:09.747965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:09.054294Z","time spent":"693.58024ms","remote":"127.0.0.1:39624","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-09-19T10:31:09.748014Z","caller":"traceutil/trace.go:171","msg":"trace[324701884] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:840; }","duration":"493.727512ms","start":"2026-09-19T10:31:09.254259Z","end":"2026-09-19T10:31:09.747986Z","steps":["trace[324701884] 'agreement among raft nodes before linearized reading' (duration: 493.61982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:09.748029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.457209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T10:31:09.748071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.000031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-778mk\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-19T10:31:09.748127Z","caller":"traceutil/trace.go:171","msg":"trace[1239714822] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:840; }","duration":"286.559721ms","start":"2026-09-19T10:31:09.461551Z","end":"2026-09-19T10:31:09.748111Z","steps":["trace[1239714822] 'agreement among raft nodes before linearized reading' (duration: 286.424528ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:09.748145Z","caller":"traceutil/trace.go:171","msg":"trace[276167768] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-778mk; range_end:; response_count:1; response_revision:840; }","duration":"669.153424ms","start":"2026-09-19T10:31:09.078973Z","end":"2026-09-19T10:31:09.748126Z","steps":["trace[276167768] 'agreement among raft nodes before linearized reading' (duration: 668.886109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:09.748188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:09.078951Z","time spent":"669.227636ms","remote":"127.0.0.1:39532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-778mk\" "} {"level":"warn","ts":"2026-09-19T10:31:09.748266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.08131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-19T10:31:09.748292Z","caller":"traceutil/trace.go:171","msg":"trace[733784904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:840; }","duration":"408.140152ms","start":"2026-09-19T10:31:09.340144Z","end":"2026-09-19T10:31:09.748284Z","steps":["trace[733784904] 'agreement among raft nodes before linearized reading' (duration: 408.06267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:09.748315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:09.340129Z","time spent":"408.179803ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T10:31:09.748339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.227008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T10:31:09.74819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.925038ms","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-09-19T10:31:09.748396Z","caller":"traceutil/trace.go:171","msg":"trace[1423079646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:840; }","duration":"258.335871ms","start":"2026-09-19T10:31:09.490044Z","end":"2026-09-19T10:31:09.74838Z","steps":["trace[1423079646] 'agreement among raft nodes before linearized reading' (duration: 258.237769ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:09.748377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.506929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-19T10:31:09.748407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.204162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T10:31:09.748419Z","caller":"traceutil/trace.go:171","msg":"trace[333349759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:840; }","duration":"408.185082ms","start":"2026-09-19T10:31:09.340223Z","end":"2026-09-19T10:31:09.748408Z","steps":["trace[333349759] 'agreement among raft nodes before linearized reading' (duration: 407.874276ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:09.748454Z","caller":"traceutil/trace.go:171","msg":"trace[849764531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:840; }","duration":"442.292364ms","start":"2026-09-19T10:31:09.30615Z","end":"2026-09-19T10:31:09.748442Z","steps":["trace[849764531] 'agreement among raft nodes before linearized reading' (duration: 442.151861ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:09.748455Z","caller":"traceutil/trace.go:171","msg":"trace[12479848] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:840; }","duration":"142.641041ms","start":"2026-09-19T10:31:09.605796Z","end":"2026-09-19T10:31:09.748437Z","steps":["trace[12479848] 'agreement among raft nodes before linearized reading' (duration: 142.451987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:09.748488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:09.306129Z","time spent":"442.352516ms","remote":"127.0.0.1:39624","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-09-19T10:31:09.748459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:09.340204Z","time spent":"408.244134ms","remote":"127.0.0.1:39624","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-09-19T10:31:09.748078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:09.254242Z","time spent":"493.798344ms","remote":"127.0.0.1:39502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-09-19T10:31:23.731251Z","caller":"traceutil/trace.go:171","msg":"trace[381008363] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"175.196277ms","start":"2026-09-19T10:31:23.556028Z","end":"2026-09-19T10:31:23.731225Z","steps":["trace[381008363] 'process raft request' (duration: 174.916071ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:50.667269Z","caller":"traceutil/trace.go:171","msg":"trace[111912001] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1168; }","duration":"178.556362ms","start":"2026-09-19T10:31:50.488694Z","end":"2026-09-19T10:31:50.66725Z","steps":["trace[111912001] 'read index received' (duration: 178.43963ms)","trace[111912001] 'applied index is now lower than readState.Index' (duration: 116.042µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:31:50.667361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.657215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:31:50.667382Z","caller":"traceutil/trace.go:171","msg":"trace[911034300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1125; }","duration":"178.690876ms","start":"2026-09-19T10:31:50.488685Z","end":"2026-09-19T10:31:50.667376Z","steps":["trace[911034300] 'agreement among raft nodes before linearized reading' (duration: 178.633055ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:50.667463Z","caller":"traceutil/trace.go:171","msg":"trace[716944729] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"223.876283ms","start":"2026-09-19T10:31:50.443573Z","end":"2026-09-19T10:31:50.667449Z","steps":["trace[716944729] 'process raft request' (duration: 223.572977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:51.795298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9380611791213226349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-19T10:31:52.438227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.850195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:31:52.438326Z","caller":"traceutil/trace.go:171","msg":"trace[381952773] linearizableReadLoop","detail":"{readStateIndex:1171; appliedIndex:1170; }","duration":"1.14332566s","start":"2026-09-19T10:31:51.294985Z","end":"2026-09-19T10:31:52.43831Z","steps":["trace[381952773] 'read index received' (duration: 566.26375ms)","trace[381952773] 'applied index is now lower than readState.Index' (duration: 577.06056ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:52.438527Z","caller":"traceutil/trace.go:171","msg":"trace[1164926189] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"1.16532625s","start":"2026-09-19T10:31:51.273188Z","end":"2026-09-19T10:31:52.438514Z","steps":["trace[1164926189] 'process raft request' (duration: 588.104516ms)","trace[1164926189] 'compare' (duration: 576.320463ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:31:52.438583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:51.273173Z","time spent":"1.165378621s","remote":"127.0.0.1:39624","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-09-19T10:31:52.438779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14379576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T10:31:52.438809Z","caller":"traceutil/trace.go:171","msg":"trace[124202931] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1127; }","duration":"1.143866212s","start":"2026-09-19T10:31:51.294932Z","end":"2026-09-19T10:31:52.438799Z","steps":["trace[124202931] 'agreement among raft nodes before linearized reading' (duration: 1.14380674s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:52.438836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:51.294902Z","time spent":"1.143926433s","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-09-19T10:31:52.465831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.762025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:31:52.465918Z","caller":"traceutil/trace.go:171","msg":"trace[1683267313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1127; }","duration":"976.905969ms","start":"2026-09-19T10:31:51.48899Z","end":"2026-09-19T10:31:52.465896Z","steps":["trace[1683267313] 'agreement among raft nodes before linearized reading' (duration: 976.758195ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:52.465923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.458836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-19T10:31:52.465958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:51.48897Z","time spent":"976.97954ms","remote":"127.0.0.1:39378","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-09-19T10:31:52.465991Z","caller":"traceutil/trace.go:171","msg":"trace[828722737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1127; }","duration":"493.574748ms","start":"2026-09-19T10:31:51.972399Z","end":"2026-09-19T10:31:52.465974Z","steps":["trace[828722737] 'agreement among raft nodes before linearized reading' (duration: 493.377304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:52.466013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.137452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-19T10:31:52.466012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.800267ms","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-09-19T10:31:52.466048Z","caller":"traceutil/trace.go:171","msg":"trace[1308999911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1127; }","duration":"525.201744ms","start":"2026-09-19T10:31:51.940834Z","end":"2026-09-19T10:31:52.466035Z","steps":["trace[1308999911] 'agreement among raft nodes before linearized reading' (duration: 524.994309ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:52.466102Z","caller":"traceutil/trace.go:171","msg":"trace[241456009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1127; }","duration":"490.891419ms","start":"2026-09-19T10:31:51.975166Z","end":"2026-09-19T10:31:52.466057Z","steps":["trace[241456009] 'agreement among raft nodes before linearized reading' (duration: 490.586962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:52.466124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:51.940814Z","time spent":"525.256474ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T10:31:52.466137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:51.975152Z","time spent":"490.976951ms","remote":"127.0.0.1:39624","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-09-19T10:31:52.46603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:51.972378Z","time spent":"493.64382ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T10:31:52.465927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.189473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T10:31:52.466334Z","caller":"traceutil/trace.go:171","msg":"trace[37980441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1127; }","duration":"490.621972ms","start":"2026-09-19T10:31:51.975701Z","end":"2026-09-19T10:31:52.466323Z","steps":["trace[37980441] 'agreement among raft nodes before linearized reading' (duration: 490.117411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:52.466372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:51.975688Z","time spent":"490.673313ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-19T10:32:06.755445Z","caller":"traceutil/trace.go:171","msg":"trace[1939457219] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1219; }","duration":"132.640528ms","start":"2026-09-19T10:32:06.622785Z","end":"2026-09-19T10:32:06.755425Z","steps":["trace[1939457219] 'read index received' (duration: 132.508305ms)","trace[1939457219] 'applied index is now lower than readState.Index' (duration: 131.193µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:32:06.755551Z","caller":"traceutil/trace.go:171","msg":"trace[1555642663] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"164.412907ms","start":"2026-09-19T10:32:06.591128Z","end":"2026-09-19T10:32:06.755541Z","steps":["trace[1555642663] 'process raft request' (duration: 164.153161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:06.755767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.949975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-19T10:32:06.755797Z","caller":"traceutil/trace.go:171","msg":"trace[202397473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1173; }","duration":"133.057988ms","start":"2026-09-19T10:32:06.62273Z","end":"2026-09-19T10:32:06.755788Z","steps":["trace[202397473] 'agreement among raft nodes before linearized reading' (duration: 132.918855ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:06.755925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.8442ms","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-09-19T10:32:06.755968Z","caller":"traceutil/trace.go:171","msg":"trace[1003022623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1173; }","duration":"118.887302ms","start":"2026-09-19T10:32:06.637052Z","end":"2026-09-19T10:32:06.75594Z","steps":["trace[1003022623] 'agreement among raft nodes before linearized reading' (duration: 118.81812ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:32:06.931606Z","caller":"traceutil/trace.go:171","msg":"trace[1445563495] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"141.665629ms","start":"2026-09-19T10:32:06.789909Z","end":"2026-09-19T10:32:06.931575Z","steps":["trace[1445563495] 'process raft request' (duration: 87.662126ms)","trace[1445563495] 'compare' (duration: 53.751798ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:32:06.931906Z","caller":"traceutil/trace.go:171","msg":"trace[628564885] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1223; }","duration":"122.261867ms","start":"2026-09-19T10:32:06.809199Z","end":"2026-09-19T10:32:06.93146Z","steps":["trace[628564885] 'read index received' (duration: 68.292714ms)","trace[628564885] 'applied index is now lower than readState.Index' (duration: 53.967823ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:32:06.931904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.688776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-19T10:32:06.932082Z","caller":"traceutil/trace.go:171","msg":"trace[705626116] range","detail":"{range_begin:/registry/masterleases/162.253.55.205; range_end:; response_count:1; response_revision:1177; }","duration":"122.897721ms","start":"2026-09-19T10:32:06.809165Z","end":"2026-09-19T10:32:06.932062Z","steps":["trace[705626116] 'agreement among raft nodes before linearized reading' (duration: 122.605873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:09.2757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9380611791213226493,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T10:32:09.343798Z","caller":"traceutil/trace.go:171","msg":"trace[159895452] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"580.839694ms","start":"2026-09-19T10:32:08.76289Z","end":"2026-09-19T10:32:09.34373Z","steps":["trace[159895452] 'process raft request' (duration: 580.703152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:09.343939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:08.762874Z","time spent":"580.993767ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:32:09.831484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.849887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:32:09.831569Z","caller":"traceutil/trace.go:171","msg":"trace[1605594171] linearizableReadLoop","detail":"{readStateIndex:1230; appliedIndex:1229; }","duration":"1.05637759s","start":"2026-09-19T10:32:08.775176Z","end":"2026-09-19T10:32:09.831553Z","steps":["trace[1605594171] 'read index received' (duration: 568.54123ms)","trace[1605594171] 'applied index is now lower than readState.Index' (duration: 487.83515ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:32:09.8317Z","caller":"traceutil/trace.go:171","msg":"trace[2118522224] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"895.857321ms","start":"2026-09-19T10:32:08.935813Z","end":"2026-09-19T10:32:09.83167Z","steps":["trace[2118522224] 'process raft request' (duration: 456.732577ms)","trace[2118522224] 'compare' (duration: 438.505399ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:32:09.831782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042318207s","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-09-19T10:32:09.831809Z","caller":"traceutil/trace.go:171","msg":"trace[2137059000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1182; }","duration":"1.042374618s","start":"2026-09-19T10:32:08.789426Z","end":"2026-09-19T10:32:09.831801Z","steps":["trace[2137059000] 'agreement among raft nodes before linearized reading' (duration: 1.042209595s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:09.831835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:08.789411Z","time spent":"1.042416459s","remote":"127.0.0.1:39624","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-09-19T10:32:09.831828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056633306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-19T10:32:09.831896Z","caller":"traceutil/trace.go:171","msg":"trace[1518702107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1182; }","duration":"1.056741858s","start":"2026-09-19T10:32:08.775135Z","end":"2026-09-19T10:32:09.831877Z","steps":["trace[1518702107] 'agreement among raft nodes before linearized reading' (duration: 1.056489063s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:09.831895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.054715ms","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":"warn","ts":"2026-09-19T10:32:09.831939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:08.77512Z","time spent":"1.05680646s","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T10:32:09.831964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041641282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-19T10:32:09.831974Z","caller":"traceutil/trace.go:171","msg":"trace[1955758467] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1182; }","duration":"835.180707ms","start":"2026-09-19T10:32:08.996772Z","end":"2026-09-19T10:32:09.831953Z","steps":["trace[1955758467] 'agreement among raft nodes before linearized reading' (duration: 835.044484ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:32:09.832007Z","caller":"traceutil/trace.go:171","msg":"trace[1577190276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1182; }","duration":"1.041701553s","start":"2026-09-19T10:32:08.790291Z","end":"2026-09-19T10:32:09.831993Z","steps":["trace[1577190276] 'agreement among raft nodes before linearized reading' (duration: 1.04158245s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:09.832014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:08.996752Z","time spent":"835.252659ms","remote":"127.0.0.1:39494","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-09-19T10:32:09.832044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:08.790281Z","time spent":"1.041753155s","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T10:32:09.83184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:08.935784Z","time spent":"896.003393ms","remote":"127.0.0.1:39624","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-09-19T10:32:09.832113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.371096ms","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-09-19T10:32:09.831712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.614601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:32:09.832143Z","caller":"traceutil/trace.go:171","msg":"trace[1678217738] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1182; }","duration":"689.456658ms","start":"2026-09-19T10:32:09.142678Z","end":"2026-09-19T10:32:09.832134Z","steps":["trace[1678217738] 'agreement among raft nodes before linearized reading' (duration: 689.353625ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:09.832173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:09.142658Z","time spent":"689.509528ms","remote":"127.0.0.1:39624","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-09-19T10:32:09.832178Z","caller":"traceutil/trace.go:171","msg":"trace[1006868603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"343.114632ms","start":"2026-09-19T10:32:09.489046Z","end":"2026-09-19T10:32:09.83216Z","steps":["trace[1006868603] 'agreement among raft nodes before linearized reading' (duration: 342.621122ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:09.832227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:09.489027Z","time spent":"343.185174ms","remote":"127.0.0.1:39378","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-09-19T10:32:10.093639Z","caller":"traceutil/trace.go:171","msg":"trace[1634361823] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"257.206267ms","start":"2026-09-19T10:32:09.836412Z","end":"2026-09-19T10:32:10.093618Z","steps":["trace[1634361823] 'process raft request' (duration: 257.055674ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:32:10.11394Z","caller":"traceutil/trace.go:171","msg":"trace[1677319882] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"276.077958ms","start":"2026-09-19T10:32:09.837826Z","end":"2026-09-19T10:32:10.113904Z","steps":["trace[1677319882] 'process raft request' (duration: 275.904854ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:32:10.113961Z","caller":"traceutil/trace.go:171","msg":"trace[1333886568] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"274.674756ms","start":"2026-09-19T10:32:09.83926Z","end":"2026-09-19T10:32:10.113934Z","steps":["trace[1333886568] 'process raft request' (duration: 274.579024ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:32:10.11406Z","caller":"traceutil/trace.go:171","msg":"trace[206100133] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"273.04833ms","start":"2026-09-19T10:32:09.840997Z","end":"2026-09-19T10:32:10.114046Z","steps":["trace[206100133] 'process raft request' (duration: 272.893636ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:32:11.837634Z","caller":"traceutil/trace.go:171","msg":"trace[437421464] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"348.81752ms","start":"2026-09-19T10:32:11.488805Z","end":"2026-09-19T10:32:11.837622Z","steps":["trace[437421464] 'read index received' (duration: 348.732568ms)","trace[437421464] 'applied index is now lower than readState.Index' (duration: 84.382µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:32:11.837717Z","caller":"traceutil/trace.go:171","msg":"trace[1905458782] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"485.140519ms","start":"2026-09-19T10:32:11.352554Z","end":"2026-09-19T10:32:11.837695Z","steps":["trace[1905458782] 'process raft request' (duration: 484.968976ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:11.837736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.944232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:32:11.83776Z","caller":"traceutil/trace.go:171","msg":"trace[626691650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1188; }","duration":"349.014334ms","start":"2026-09-19T10:32:11.488738Z","end":"2026-09-19T10:32:11.837753Z","steps":["trace[626691650] 'agreement among raft nodes before linearized reading' (duration: 348.937272ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:11.837784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:11.488718Z","time spent":"349.060115ms","remote":"127.0.0.1:39388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T10:32:11.837823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:11.352542Z","time spent":"485.203491ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:32:12.39593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.589763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:32:12.396132Z","caller":"traceutil/trace.go:171","msg":"trace[1693506203] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"558.544247ms","start":"2026-09-19T10:32:11.837538Z","end":"2026-09-19T10:32:12.396082Z","steps":["trace[1693506203] 'process raft request' (duration: 236.72807ms)","trace[1693506203] 'compare' (duration: 321.416238ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:32:12.396278Z","caller":"traceutil/trace.go:171","msg":"trace[359676350] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"298.933847ms","start":"2026-09-19T10:32:12.09732Z","end":"2026-09-19T10:32:12.396254Z","steps":["trace[359676350] 'read index received' (duration: 47.701µs)","trace[359676350] 'applied index is now lower than readState.Index' (duration: 298.883866ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:32:12.396303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:11.837513Z","time spent":"558.703101ms","remote":"127.0.0.1:39624","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-09-19T10:32:12.787346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.313508ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-19T10:32:12.787445Z","caller":"traceutil/trace.go:171","msg":"trace[1628556924] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1238; }","duration":"391.098684ms","start":"2026-09-19T10:32:12.396329Z","end":"2026-09-19T10:32:12.787428Z","steps":["trace[1628556924] 'read index received' (duration: 96.692µs)","trace[1628556924] 'applied index is now lower than readState.Index' (duration: 391.000602ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:32:12.787427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.081841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-19T10:32:12.787519Z","caller":"traceutil/trace.go:171","msg":"trace[2089466596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1189; }","duration":"690.225354ms","start":"2026-09-19T10:32:12.097269Z","end":"2026-09-19T10:32:12.787495Z","steps":["trace[2089466596] 'agreement among raft nodes before linearized reading' (duration: 299.115781ms)","trace[2089466596] 'range keys from in-memory index tree' (duration: 390.865867ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:32:12.787571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:12.097249Z","time spent":"690.310986ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T10:32:12.787628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.66769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-19T10:32:12.787663Z","caller":"traceutil/trace.go:171","msg":"trace[1680802819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1189; }","duration":"667.712041ms","start":"2026-09-19T10:32:12.119941Z","end":"2026-09-19T10:32:12.787653Z","steps":["trace[1680802819] 'agreement among raft nodes before linearized reading' (duration: 667.570968ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:12.787695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:12.119901Z","time spent":"667.786113ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-19T10:32:12.787865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.730138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:32:12.787896Z","caller":"traceutil/trace.go:171","msg":"trace[1414017647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1189; }","duration":"296.825151ms","start":"2026-09-19T10:32:12.491063Z","end":"2026-09-19T10:32:12.787888Z","steps":["trace[1414017647] 'agreement among raft nodes before linearized reading' (duration: 296.78159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:12.787864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.057324ms","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-09-19T10:32:12.788005Z","caller":"traceutil/trace.go:171","msg":"trace[1695628244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1189; }","duration":"670.259539ms","start":"2026-09-19T10:32:12.117727Z","end":"2026-09-19T10:32:12.787987Z","steps":["trace[1695628244] 'agreement among raft nodes before linearized reading' (duration: 669.810399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:12.788082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:12.117702Z","time spent":"670.366631ms","remote":"127.0.0.1:39624","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-09-19T10:32:12.788077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.171457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-19T10:32:12.788209Z","caller":"traceutil/trace.go:171","msg":"trace[378145934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1189; }","duration":"670.32514ms","start":"2026-09-19T10:32:12.117866Z","end":"2026-09-19T10:32:12.788191Z","steps":["trace[378145934] 'agreement among raft nodes before linearized reading' (duration: 670.085875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:12.788256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:12.117853Z","time spent":"670.392172ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T10:32:12.788299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.811094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:32:12.788372Z","caller":"traceutil/trace.go:171","msg":"trace[2095226220] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1189; }","duration":"515.932977ms","start":"2026-09-19T10:32:12.272421Z","end":"2026-09-19T10:32:12.788354Z","steps":["trace[2095226220] 'agreement among raft nodes before linearized reading' (duration: 515.028187ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:12.788427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:12.272398Z","time spent":"516.015038ms","remote":"127.0.0.1:39668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-09-19T10:32:13.743014Z","caller":"traceutil/trace.go:171","msg":"trace[1910567609] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"799.92466ms","start":"2026-09-19T10:32:12.94307Z","end":"2026-09-19T10:32:13.742995Z","steps":["trace[1910567609] 'process raft request' (duration: 799.817628ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:13.743147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:12.943046Z","time spent":"800.023373ms","remote":"127.0.0.1:39456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:32:13.761627Z","caller":"traceutil/trace.go:171","msg":"trace[1594593943] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1243; }","duration":"351.900448ms","start":"2026-09-19T10:32:13.409706Z","end":"2026-09-19T10:32:13.761606Z","steps":["trace[1594593943] 'read index received' (duration: 333.13786ms)","trace[1594593943] 'applied index is now lower than readState.Index' (duration: 18.761668ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:32:13.762011Z","caller":"traceutil/trace.go:171","msg":"trace[142211121] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"697.863685ms","start":"2026-09-19T10:32:13.064114Z","end":"2026-09-19T10:32:13.761978Z","steps":["trace[142211121] 'process raft request' (duration: 697.392374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:13.762163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:13.064078Z","time spent":"697.996498ms","remote":"127.0.0.1:39624","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-09-19T10:32:13.762366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.673136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5374"} {"level":"info","ts":"2026-09-19T10:32:13.762395Z","caller":"traceutil/trace.go:171","msg":"trace[1641065541] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1195; }","duration":"352.738158ms","start":"2026-09-19T10:32:13.409648Z","end":"2026-09-19T10:32:13.762386Z","steps":["trace[1641065541] 'agreement among raft nodes before linearized reading' (duration: 352.631875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:13.762413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:13.409629Z","time spent":"352.779308ms","remote":"127.0.0.1:39524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5397,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-19T10:32:14.03946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.628341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:32:14.039563Z","caller":"traceutil/trace.go:171","msg":"trace[650353229] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1195; }","duration":"207.817085ms","start":"2026-09-19T10:32:13.831722Z","end":"2026-09-19T10:32:14.039539Z","steps":["trace[650353229] 'range keys from in-memory index tree' (duration: 207.487687ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:32:14.039765Z","caller":"traceutil/trace.go:171","msg":"trace[1854635963] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"198.305773ms","start":"2026-09-19T10:32:13.841421Z","end":"2026-09-19T10:32:14.039727Z","steps":["trace[1854635963] 'read index received' (duration: 165.144953ms)","trace[1854635963] 'applied index is now lower than readState.Index' (duration: 33.15929ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:32:14.03979Z","caller":"traceutil/trace.go:171","msg":"trace[1232746474] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"205.062583ms","start":"2026-09-19T10:32:13.834667Z","end":"2026-09-19T10:32:14.039729Z","steps":["trace[1232746474] 'process raft request' (duration: 171.902363ms)","trace[1232746474] 'compare' (duration: 32.751301ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:32:14.039933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.503957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T10:32:14.039961Z","caller":"traceutil/trace.go:171","msg":"trace[46397284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1196; }","duration":"198.581979ms","start":"2026-09-19T10:32:13.841371Z","end":"2026-09-19T10:32:14.039953Z","steps":["trace[46397284] 'agreement among raft nodes before linearized reading' (duration: 198.440416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:14.312279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.003535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:32:14.312431Z","caller":"traceutil/trace.go:171","msg":"trace[417448181] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"269.03264ms","start":"2026-09-19T10:32:14.043372Z","end":"2026-09-19T10:32:14.312405Z","steps":["trace[417448181] 'process raft request' (duration: 114.824231ms)","trace[417448181] 'compare' (duration: 153.694847ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:32:17.162568Z","caller":"traceutil/trace.go:171","msg":"trace[209773964] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"140.689838ms","start":"2026-09-19T10:32:17.021856Z","end":"2026-09-19T10:32:17.162546Z","steps":["trace[209773964] 'process raft request' (duration: 140.553165ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:32:22.302364Z","caller":"traceutil/trace.go:171","msg":"trace[1468110883] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"160.567041ms","start":"2026-09-19T10:32:22.14177Z","end":"2026-09-19T10:32:22.302337Z","steps":["trace[1468110883] 'process raft request' (duration: 139.559092ms)","trace[1468110883] 'compare' (duration: 20.911497ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:32:22.575193Z","caller":"traceutil/trace.go:171","msg":"trace[1293065338] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"124.267722ms","start":"2026-09-19T10:32:22.450903Z","end":"2026-09-19T10:32:22.57517Z","steps":["trace[1293065338] 'process raft request' (duration: 115.692231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:23.458819Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9380611791213226648,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-19T10:32:23.691619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.649324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:32:23.691707Z","caller":"traceutil/trace.go:171","msg":"trace[944780427] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1315; }","duration":"733.485959ms","start":"2026-09-19T10:32:22.958206Z","end":"2026-09-19T10:32:23.691692Z","steps":["trace[944780427] 'read index received' (duration: 554.680901ms)","trace[944780427] 'applied index is now lower than readState.Index' (duration: 178.804188ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:32:23.691946Z","caller":"traceutil/trace.go:171","msg":"trace[1658010069] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"738.44836ms","start":"2026-09-19T10:32:22.953484Z","end":"2026-09-19T10:32:23.691933Z","steps":["trace[1658010069] 'process raft request' (duration: 559.411447ms)","trace[1658010069] 'compare' (duration: 178.558612ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:32:23.692014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:22.953462Z","time spent":"738.520611ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:32:23.692261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.815624ms","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-09-19T10:32:23.692362Z","caller":"traceutil/trace.go:171","msg":"trace[1437250247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1264; }","duration":"728.964158ms","start":"2026-09-19T10:32:22.963379Z","end":"2026-09-19T10:32:23.692343Z","steps":["trace[1437250247] 'agreement among raft nodes before linearized reading' (duration: 728.695242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:23.692377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.052949ms","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":"warn","ts":"2026-09-19T10:32:23.692401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:22.963367Z","time spent":"729.025109ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-19T10:32:23.692415Z","caller":"traceutil/trace.go:171","msg":"trace[2125512306] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1264; }","duration":"155.112369ms","start":"2026-09-19T10:32:23.537293Z","end":"2026-09-19T10:32:23.692405Z","steps":["trace[2125512306] 'agreement among raft nodes before linearized reading' (duration: 155.003757ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:23.692535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.244503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:32:23.692598Z","caller":"traceutil/trace.go:171","msg":"trace[1323747070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1264; }","duration":"203.357005ms","start":"2026-09-19T10:32:23.489224Z","end":"2026-09-19T10:32:23.692581Z","steps":["trace[1323747070] 'agreement among raft nodes before linearized reading' (duration: 203.245353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:23.692802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.583442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-19T10:32:23.692852Z","caller":"traceutil/trace.go:171","msg":"trace[840577190] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1264; }","duration":"734.669565ms","start":"2026-09-19T10:32:22.958169Z","end":"2026-09-19T10:32:23.692839Z","steps":["trace[840577190] 'agreement among raft nodes before linearized reading' (duration: 734.177354ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:23.693279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:22.958156Z","time spent":"735.107675ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T10:32:24.263406Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9380611791213226652,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T10:32:24.448682Z","caller":"traceutil/trace.go:171","msg":"trace[1461830970] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"752.36922ms","start":"2026-09-19T10:32:23.696286Z","end":"2026-09-19T10:32:24.448655Z","steps":["trace[1461830970] 'process raft request' (duration: 751.545702ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:24.448831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:23.696264Z","time spent":"752.492572ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:32:24.450244Z","caller":"traceutil/trace.go:171","msg":"trace[1750849570] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"753.901794ms","start":"2026-09-19T10:32:23.696319Z","end":"2026-09-19T10:32:24.450221Z","steps":["trace[1750849570] 'process raft request' (duration: 753.70261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:24.45041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:23.696299Z","time spent":"754.026067ms","remote":"127.0.0.1:39624","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-09-19T10:32:24.450592Z","caller":"traceutil/trace.go:171","msg":"trace[927869702] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"545.564488ms","start":"2026-09-19T10:32:23.905016Z","end":"2026-09-19T10:32:24.450581Z","steps":["trace[927869702] 'process raft request' (duration: 545.399453ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:32:24.450626Z","caller":"traceutil/trace.go:171","msg":"trace[1557763280] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"753.110586ms","start":"2026-09-19T10:32:23.6975Z","end":"2026-09-19T10:32:24.45061Z","steps":["trace[1557763280] 'process raft request' (duration: 752.682436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:24.450646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:23.904997Z","time spent":"545.621209ms","remote":"127.0.0.1:39624","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-09-19T10:32:24.450571Z","caller":"traceutil/trace.go:171","msg":"trace[1868942014] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1316; }","duration":"687.495353ms","start":"2026-09-19T10:32:23.763029Z","end":"2026-09-19T10:32:24.450525Z","steps":["trace[1868942014] 'read index received' (duration: 684.65993ms)","trace[1868942014] 'applied index is now lower than readState.Index' (duration: 2.834493ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:32:24.450698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:23.69749Z","time spent":"753.172278ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:32:24.450744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.709158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:32:24.450779Z","caller":"traceutil/trace.go:171","msg":"trace[1349716957] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1268; }","duration":"687.77199ms","start":"2026-09-19T10:32:23.762997Z","end":"2026-09-19T10:32:24.450769Z","steps":["trace[1349716957] 'agreement among raft nodes before linearized reading' (duration: 687.718539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:32:24.450806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:32:23.762985Z","time spent":"687.813821ms","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-09-19T10:32:24.45094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.36807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-19T10:32:24.450985Z","caller":"traceutil/trace.go:171","msg":"trace[1708327230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1268; }","duration":"144.467932ms","start":"2026-09-19T10:32:24.306506Z","end":"2026-09-19T10:32:24.450974Z","steps":["trace[1708327230] 'agreement among raft nodes before linearized reading' (duration: 144.257168ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:34:33.423509Z","caller":"traceutil/trace.go:171","msg":"trace[973690963] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"114.721548ms","start":"2026-09-19T10:34:33.308758Z","end":"2026-09-19T10:34:33.42348Z","steps":["trace[973690963] 'process raft request' (duration: 114.510434ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:37:00.431934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.840412ms","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-09-19T10:37:00.432041Z","caller":"traceutil/trace.go:171","msg":"trace[675157204] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2569; }","duration":"210.024476ms","start":"2026-09-19T10:37:00.221996Z","end":"2026-09-19T10:37:00.432021Z","steps":["trace[675157204] 'range keys from in-memory index tree' (duration: 209.629478ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:37:06.146572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.517748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:37:06.146688Z","caller":"traceutil/trace.go:171","msg":"trace[489388373] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"120.405596ms","start":"2026-09-19T10:37:06.026266Z","end":"2026-09-19T10:37:06.146672Z","steps":["trace[489388373] 'compare' (duration: 111.389255ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:37:06.687415Z","caller":"traceutil/trace.go:171","msg":"trace[655769600] transaction","detail":"{read_only:false; response_revision:2603; number_of_response:1; }","duration":"175.11727ms","start":"2026-09-19T10:37:06.512255Z","end":"2026-09-19T10:37:06.687372Z","steps":["trace[655769600] 'process raft request' (duration: 174.850855ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:37:06.908164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.975838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:37:06.908411Z","caller":"traceutil/trace.go:171","msg":"trace[337587732] linearizableReadLoop","detail":"{readStateIndex:2714; appliedIndex:2712; }","duration":"111.035557ms","start":"2026-09-19T10:37:06.797356Z","end":"2026-09-19T10:37:06.908391Z","steps":["trace[337587732] 'read index received' (duration: 61.392µs)","trace[337587732] 'applied index is now lower than readState.Index' (duration: 110.972565ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:37:06.909019Z","caller":"traceutil/trace.go:171","msg":"trace[1881604954] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"395.060207ms","start":"2026-09-19T10:37:06.513887Z","end":"2026-09-19T10:37:06.908947Z","steps":["trace[1881604954] 'process raft request' (duration: 394.393722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:37:06.909546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.195052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:37:06.909523Z","caller":"traceutil/trace.go:171","msg":"trace[889198929] transaction","detail":"{read_only:false; response_revision:2604; number_of_response:1; }","duration":"396.136731ms","start":"2026-09-19T10:37:06.513337Z","end":"2026-09-19T10:37:06.909474Z","steps":["trace[889198929] 'process raft request' (duration: 196.725621ms)","trace[889198929] 'compare' (duration: 197.778544ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:37:06.909611Z","caller":"traceutil/trace.go:171","msg":"trace[1604977687] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2605; }","duration":"112.296154ms","start":"2026-09-19T10:37:06.797287Z","end":"2026-09-19T10:37:06.909583Z","steps":["trace[1604977687] 'agreement among raft nodes before linearized reading' (duration: 112.201532ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:37:06.909745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:37:06.513314Z","time spent":"396.309634ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:37:06.909663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:37:06.513877Z","time spent":"395.251091ms","remote":"127.0.0.1:39624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}